{"level":"warn","ts":"2026-04-28T06:47:54.779925Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-28T06:47:54.780047Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.246:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.246:2380","--initial-cluster=instance=https://199.204.45.246:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.246:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.246:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-28T06:47:54.780139Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-28T06:47:54.780153Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.246:2380"]} {"level":"info","ts":"2026-04-28T06:47:54.78018Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-28T06:47:54.781038Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.246:2379"]} {"level":"info","ts":"2026-04-28T06:47:54.781132Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.246:2380"],"listen-peer-urls":["https://199.204.45.246:2380"],"advertise-client-urls":["https://199.204.45.246:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.246:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.246:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-28T06:47:54.78465Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.249767ms"} {"level":"info","ts":"2026-04-28T06:47:54.78956Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"ab97322ebdd79664","cluster-id":"87bc89380c3dc2c"} {"level":"info","ts":"2026-04-28T06:47:54.789624Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 switched to configuration voters=()"} {"level":"info","ts":"2026-04-28T06:47:54.789645Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became follower at term 0"} {"level":"info","ts":"2026-04-28T06:47:54.789662Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft ab97322ebdd79664 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-28T06:47:54.789675Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became follower at term 1"} {"level":"info","ts":"2026-04-28T06:47:54.789708Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 switched to configuration voters=(12364406478303893092)"} {"level":"warn","ts":"2026-04-28T06:47:54.79516Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-28T06:47:54.796353Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-28T06:47:54.798306Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-28T06:47:54.800252Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"ab97322ebdd79664","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-28T06:47:54.800621Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-28T06:47:54.800718Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-28T06:47:54.800732Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-28T06:47:54.80068Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"ab97322ebdd79664","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-28T06:47:54.802228Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 switched to configuration voters=(12364406478303893092)"} {"level":"info","ts":"2026-04-28T06:47:54.802844Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"87bc89380c3dc2c","local-member-id":"ab97322ebdd79664","added-peer-id":"ab97322ebdd79664","added-peer-peer-urls":["https://199.204.45.246:2380"]} {"level":"info","ts":"2026-04-28T06:47:54.80434Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-28T06:47:54.80449Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"ab97322ebdd79664","initial-advertise-peer-urls":["https://199.204.45.246:2380"],"listen-peer-urls":["https://199.204.45.246:2380"],"advertise-client-urls":["https://199.204.45.246:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.246:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-28T06:47:54.80452Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-28T06:47:54.804598Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.246:2380"} {"level":"info","ts":"2026-04-28T06:47:54.804612Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.246:2380"} {"level":"info","ts":"2026-04-28T06:47:55.29035Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 is starting a new election at term 1"} {"level":"info","ts":"2026-04-28T06:47:55.290413Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-28T06:47:55.290432Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 received MsgPreVoteResp from ab97322ebdd79664 at term 1"} {"level":"info","ts":"2026-04-28T06:47:55.290446Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became candidate at term 2"} {"level":"info","ts":"2026-04-28T06:47:55.290453Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 received MsgVoteResp from ab97322ebdd79664 at term 2"} {"level":"info","ts":"2026-04-28T06:47:55.290463Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became leader at term 2"} {"level":"info","ts":"2026-04-28T06:47:55.290474Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: ab97322ebdd79664 elected leader ab97322ebdd79664 at term 2"} {"level":"info","ts":"2026-04-28T06:47:55.293001Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T06:47:55.293691Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-28T06:47:55.293687Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"ab97322ebdd79664","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.246:2379]}","request-path":"/0/members/ab97322ebdd79664/attributes","cluster-id":"87bc89380c3dc2c","publish-timeout":"7s"} {"level":"info","ts":"2026-04-28T06:47:55.293745Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-28T06:47:55.294057Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-28T06:47:55.294083Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-28T06:47:55.295726Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.246:2379"} {"level":"info","ts":"2026-04-28T06:47:55.296485Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"87bc89380c3dc2c","local-member-id":"ab97322ebdd79664","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T06:47:55.296548Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T06:47:55.296575Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-28T06:47:55.297227Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-04-28T06:47:57.370772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.067343ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-04-28T06:47:57.689551Z","caller":"traceutil/trace.go:171","msg":"trace[1121156411] transaction","detail":"{read_only:false; response_revision:71; number_of_response:1; }","duration":"317.552456ms","start":"2026-04-28T06:47:57.371963Z","end":"2026-04-28T06:47:57.689516Z","steps":["trace[1121156411] 'process raft request' (duration: 260.255116ms)","trace[1121156411] 'compare' (duration: 57.122606ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:47:57.689582Z","caller":"traceutil/trace.go:171","msg":"trace[238758515] linearizableReadLoop","detail":"{readStateIndex:76; appliedIndex:75; }","duration":"106.973129ms","start":"2026-04-28T06:47:57.582537Z","end":"2026-04-28T06:47:57.68951Z","steps":["trace[238758515] 'read index received' (duration: 49.628448ms)","trace[238758515] 'applied index is now lower than readState.Index' (duration: 57.342931ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:47:57.689849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.210344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/system-node-critical\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-28T06:47:57.68997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.910636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-28T06:47:57.689998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:47:57.371939Z","time spent":"317.728901ms","remote":"127.0.0.1:49808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":592,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T06:47:57.690052Z","caller":"traceutil/trace.go:171","msg":"trace[1541922653] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:71; }","duration":"104.029889ms","start":"2026-04-28T06:47:57.586004Z","end":"2026-04-28T06:47:57.690034Z","steps":["trace[1541922653] 'agreement among raft nodes before linearized reading' (duration: 103.865905ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:47:57.689947Z","caller":"traceutil/trace.go:171","msg":"trace[1987434473] range","detail":"{range_begin:/registry/priorityclasses/system-node-critical; range_end:; response_count:0; response_revision:71; }","duration":"107.45343ms","start":"2026-04-28T06:47:57.582477Z","end":"2026-04-28T06:47:57.68993Z","steps":["trace[1987434473] 'agreement among raft nodes before linearized reading' (duration: 107.145212ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:48:05.226084Z","caller":"traceutil/trace.go:171","msg":"trace[481504288] transaction","detail":"{read_only:false; response_revision:373; number_of_response:1; }","duration":"108.181898ms","start":"2026-04-28T06:48:05.11787Z","end":"2026-04-28T06:48:05.226052Z","steps":["trace[481504288] 'process raft request' (duration: 108.014874ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:48:05.49598Z","caller":"traceutil/trace.go:171","msg":"trace[1857656257] linearizableReadLoop","detail":"{readStateIndex:388; appliedIndex:385; }","duration":"157.660068ms","start":"2026-04-28T06:48:05.338293Z","end":"2026-04-28T06:48:05.495953Z","steps":["trace[1857656257] 'read index received' (duration: 43.224122ms)","trace[1857656257] 'applied index is now lower than readState.Index' (duration: 114.434816ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:48:05.496202Z","caller":"traceutil/trace.go:171","msg":"trace[397269093] transaction","detail":"{read_only:false; response_revision:380; number_of_response:1; }","duration":"154.445503ms","start":"2026-04-28T06:48:05.341731Z","end":"2026-04-28T06:48:05.496177Z","steps":["trace[397269093] 'process raft request' (duration: 154.172936ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:48:05.496206Z","caller":"traceutil/trace.go:171","msg":"trace[1095073633] transaction","detail":"{read_only:false; response_revision:379; number_of_response:1; }","duration":"158.769715ms","start":"2026-04-28T06:48:05.337401Z","end":"2026-04-28T06:48:05.496171Z","steps":["trace[1095073633] 'process raft request' (duration: 158.423146ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:48:05.496225Z","caller":"traceutil/trace.go:171","msg":"trace[841304531] transaction","detail":"{read_only:false; response_revision:378; number_of_response:1; }","duration":"159.547493ms","start":"2026-04-28T06:48:05.336609Z","end":"2026-04-28T06:48:05.496156Z","steps":["trace[841304531] 'process raft request' (duration: 108.962687ms)","trace[841304531] 'compare' (duration: 49.91526ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:48:05.496354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.924645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4017"} {"level":"info","ts":"2026-04-28T06:48:05.496453Z","caller":"traceutil/trace.go:171","msg":"trace[1414966672] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:380; }","duration":"158.169101ms","start":"2026-04-28T06:48:05.33827Z","end":"2026-04-28T06:48:05.496439Z","steps":["trace[1414966672] 'agreement among raft nodes before linearized reading' (duration: 157.778812ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:48:05.701781Z","caller":"traceutil/trace.go:171","msg":"trace[2134209834] transaction","detail":"{read_only:false; response_revision:385; number_of_response:1; }","duration":"132.004181ms","start":"2026-04-28T06:48:05.569756Z","end":"2026-04-28T06:48:05.70176Z","steps":["trace[2134209834] 'process raft request' (duration: 100.606449ms)","trace[2134209834] 'compare' (duration: 30.88246ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:48:23.459945Z","caller":"traceutil/trace.go:171","msg":"trace[2307251] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"104.803106ms","start":"2026-04-28T06:48:23.355116Z","end":"2026-04-28T06:48:23.459919Z","steps":["trace[2307251] 'process raft request' (duration: 104.621032ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:48:24.016683Z","caller":"traceutil/trace.go:171","msg":"trace[2116475823] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"621.862179ms","start":"2026-04-28T06:48:23.394789Z","end":"2026-04-28T06:48:24.016651Z","steps":["trace[2116475823] 'process raft request' (duration: 621.555212ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:48:24.016708Z","caller":"traceutil/trace.go:171","msg":"trace[559721835] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"617.309252ms","start":"2026-04-28T06:48:23.39937Z","end":"2026-04-28T06:48:24.016679Z","steps":["trace[559721835] 'process raft request' (duration: 617.146278ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:48:24.016845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:48:23.399357Z","time spent":"617.423824ms","remote":"127.0.0.1:49776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T06:48:24.016835Z","caller":"traceutil/trace.go:171","msg":"trace[737983848] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"617.294842ms","start":"2026-04-28T06:48:23.399493Z","end":"2026-04-28T06:48:24.016788Z","steps":["trace[737983848] 'process raft request' (duration: 617.077566ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:48:24.016888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:48:23.39477Z","time spent":"621.988062ms","remote":"127.0.0.1:49776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T06:48:24.016977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:48:23.399464Z","time spent":"617.449174ms","remote":"127.0.0.1:49776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T06:48:24.017329Z","caller":"traceutil/trace.go:171","msg":"trace[1462685032] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"610.541933ms","start":"2026-04-28T06:48:23.406773Z","end":"2026-04-28T06:48:24.017315Z","steps":["trace[1462685032] 'process raft request' (duration: 609.853987ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:48:24.017445Z","caller":"traceutil/trace.go:171","msg":"trace[1940649920] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"610.534403ms","start":"2026-04-28T06:48:23.406893Z","end":"2026-04-28T06:48:24.017428Z","steps":["trace[1940649920] 'process raft request' (duration: 610.254066ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:48:24.017547Z","caller":"traceutil/trace.go:171","msg":"trace[745126489] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"608.865203ms","start":"2026-04-28T06:48:23.408672Z","end":"2026-04-28T06:48:24.017537Z","steps":["trace[745126489] 'process raft request' (duration: 608.641738ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:48:24.017558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:48:23.406888Z","time spent":"610.627275ms","remote":"127.0.0.1:49776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T06:48:24.017598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:48:23.408662Z","time spent":"608.913194ms","remote":"127.0.0.1:49776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T06:48:24.017459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:48:23.406762Z","time spent":"610.667746ms","remote":"127.0.0.1:49776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T06:48:24.018115Z","caller":"traceutil/trace.go:171","msg":"trace[1934608973] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"609.385295ms","start":"2026-04-28T06:48:23.408713Z","end":"2026-04-28T06:48:24.018098Z","steps":["trace[1934608973] 'process raft request' (duration: 609.033527ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:48:24.018203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:48:23.408709Z","time spent":"609.457697ms","remote":"127.0.0.1:49776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T06:48:24.018667Z","caller":"traceutil/trace.go:171","msg":"trace[373633890] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"607.424139ms","start":"2026-04-28T06:48:23.411208Z","end":"2026-04-28T06:48:24.018632Z","steps":["trace[373633890] 'process raft request' (duration: 606.843605ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:48:24.01884Z","caller":"traceutil/trace.go:171","msg":"trace[702578187] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"571.397801ms","start":"2026-04-28T06:48:23.447419Z","end":"2026-04-28T06:48:24.018817Z","steps":["trace[702578187] 'process raft request' (duration: 571.259118ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:48:24.018854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:48:23.411183Z","time spent":"607.598183ms","remote":"127.0.0.1:49776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T06:48:24.01897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:48:23.447402Z","time spent":"571.494514ms","remote":"127.0.0.1:49776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T06:48:24.019132Z","caller":"traceutil/trace.go:171","msg":"trace[1832963265] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"603.401614ms","start":"2026-04-28T06:48:23.415718Z","end":"2026-04-28T06:48:24.019119Z","steps":["trace[1832963265] 'process raft request' (duration: 602.742279ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:48:24.019204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:48:23.4157Z","time spent":"603.473557ms","remote":"127.0.0.1:49776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T06:48:24.019273Z","caller":"traceutil/trace.go:171","msg":"trace[1400885764] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"595.867327ms","start":"2026-04-28T06:48:23.42333Z","end":"2026-04-28T06:48:24.019197Z","steps":["trace[1400885764] 'process raft request' (duration: 595.283243ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:48:24.019369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:48:23.423319Z","time spent":"595.98852ms","remote":"127.0.0.1:49776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T06:48:24.022601Z","caller":"traceutil/trace.go:171","msg":"trace[386821216] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"476.08785ms","start":"2026-04-28T06:48:23.546502Z","end":"2026-04-28T06:48:24.02259Z","steps":["trace[386821216] 'process raft request' (duration: 475.449195ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:48:24.022653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:48:23.546466Z","time spent":"476.165082ms","remote":"127.0.0.1:49776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T06:48:25.0327Z","caller":"traceutil/trace.go:171","msg":"trace[998386008] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"121.065897ms","start":"2026-04-28T06:48:24.911608Z","end":"2026-04-28T06:48:25.032674Z","steps":["trace[998386008] 'process raft request' (duration: 120.612586ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:48:25.070993Z","caller":"traceutil/trace.go:171","msg":"trace[919557062] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"115.351303ms","start":"2026-04-28T06:48:24.955619Z","end":"2026-04-28T06:48:25.07097Z","steps":["trace[919557062] 'process raft request' (duration: 115.155858ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:48:28.551994Z","caller":"traceutil/trace.go:171","msg":"trace[596980703] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"171.393518ms","start":"2026-04-28T06:48:28.380572Z","end":"2026-04-28T06:48:28.551965Z","steps":["trace[596980703] 'process raft request' (duration: 170.61263ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:48:28.552156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.1982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.246\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-28T06:48:28.552264Z","caller":"traceutil/trace.go:171","msg":"trace[685185753] range","detail":"{range_begin:/registry/masterleases/199.204.45.246; range_end:; response_count:1; response_revision:600; }","duration":"150.288932ms","start":"2026-04-28T06:48:28.401929Z","end":"2026-04-28T06:48:28.552218Z","steps":["trace[685185753] 'agreement among raft nodes before linearized reading' (duration: 150.152629ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:48:28.561575Z","caller":"traceutil/trace.go:171","msg":"trace[1035953280] linearizableReadLoop","detail":"{readStateIndex:619; appliedIndex:618; }","duration":"159.611191ms","start":"2026-04-28T06:48:28.401935Z","end":"2026-04-28T06:48:28.561546Z","steps":["trace[1035953280] 'read index received' (duration: 148.834818ms)","trace[1035953280] 'applied index is now lower than readState.Index' (duration: 10.774443ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:48:30.297633Z","caller":"traceutil/trace.go:171","msg":"trace[1965309197] linearizableReadLoop","detail":"{readStateIndex:631; appliedIndex:630; }","duration":"323.60074ms","start":"2026-04-28T06:48:29.974008Z","end":"2026-04-28T06:48:30.297609Z","steps":["trace[1965309197] 'read index received' (duration: 322.844293ms)","trace[1965309197] 'applied index is now lower than readState.Index' (duration: 755.387µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:48:30.297796Z","caller":"traceutil/trace.go:171","msg":"trace[393545225] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"357.887137ms","start":"2026-04-28T06:48:29.939895Z","end":"2026-04-28T06:48:30.297782Z","steps":["trace[393545225] 'process raft request' (duration: 357.142369ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:48:30.298103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:48:29.93986Z","time spent":"357.965859ms","remote":"127.0.0.1:49832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036281,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:48:30.298155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.873693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-04-28T06:48:30.29818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.797948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-28T06:48:30.298243Z","caller":"traceutil/trace.go:171","msg":"trace[1168580538] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:611; }","duration":"192.904101ms","start":"2026-04-28T06:48:30.105319Z","end":"2026-04-28T06:48:30.298224Z","steps":["trace[1168580538] 'agreement among raft nodes before linearized reading' (duration: 192.707276ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:48:30.29824Z","caller":"traceutil/trace.go:171","msg":"trace[183211641] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:611; }","duration":"147.969935ms","start":"2026-04-28T06:48:30.150246Z","end":"2026-04-28T06:48:30.298216Z","steps":["trace[183211641] 'agreement among raft nodes before linearized reading' (duration: 147.78289ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:48:30.29826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.223875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-njgww\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-04-28T06:48:30.298347Z","caller":"traceutil/trace.go:171","msg":"trace[1256720690] range","detail":"{range_begin:/registry/pods/kube-system/cilium-njgww; range_end:; response_count:1; response_revision:611; }","duration":"324.349608ms","start":"2026-04-28T06:48:29.973972Z","end":"2026-04-28T06:48:30.298322Z","steps":["trace[1256720690] 'agreement among raft nodes before linearized reading' (duration: 324.085332ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:48:30.29839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:48:29.973941Z","time spent":"324.441211ms","remote":"127.0.0.1:49928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-njgww\" "} {"level":"warn","ts":"2026-04-28T06:48:30.298711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.792332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-28T06:48:30.298758Z","caller":"traceutil/trace.go:171","msg":"trace[1444469133] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:611; }","duration":"219.867234ms","start":"2026-04-28T06:48:30.078877Z","end":"2026-04-28T06:48:30.298744Z","steps":["trace[1444469133] 'agreement among raft nodes before linearized reading' (duration: 219.032905ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:48:30.681488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.878521ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T06:48:30.68159Z","caller":"traceutil/trace.go:171","msg":"trace[1012718144] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"378.524402ms","start":"2026-04-28T06:48:30.303047Z","end":"2026-04-28T06:48:30.681571Z","steps":["trace[1012718144] 'process raft request' (duration: 185.483957ms)","trace[1012718144] 'compare' (duration: 192.774239ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:48:30.681853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:48:30.302988Z","time spent":"378.830859ms","remote":"127.0.0.1:49980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:48:31.169365Z","caller":"traceutil/trace.go:171","msg":"trace[106503752] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"865.894601ms","start":"2026-04-28T06:48:30.303439Z","end":"2026-04-28T06:48:31.169333Z","steps":["trace[106503752] 'process raft request' (duration: 865.662885ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:48:31.169521Z","caller":"traceutil/trace.go:171","msg":"trace[1806650302] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"865.679145ms","start":"2026-04-28T06:48:30.303831Z","end":"2026-04-28T06:48:31.16951Z","steps":["trace[1806650302] 'process raft request' (duration: 865.447919ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:48:31.169538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:48:30.303419Z","time spent":"866.032133ms","remote":"127.0.0.1:49980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:48:31.169595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:48:30.30382Z","time spent":"865.739997ms","remote":"127.0.0.1:49980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:48:31.169789Z","caller":"traceutil/trace.go:171","msg":"trace[622875825] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"703.979787ms","start":"2026-04-28T06:48:30.465788Z","end":"2026-04-28T06:48:31.169768Z","steps":["trace[622875825] 'process raft request' (duration: 703.941586ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:48:31.169838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:48:30.465748Z","time spent":"704.067869ms","remote":"127.0.0.1:49980","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-04-28T06:48:31.16997Z","caller":"traceutil/trace.go:171","msg":"trace[552794172] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"858.266011ms","start":"2026-04-28T06:48:30.311695Z","end":"2026-04-28T06:48:31.169961Z","steps":["trace[552794172] 'process raft request' (duration: 857.756639ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:48:31.170031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:48:30.311668Z","time spent":"858.332722ms","remote":"127.0.0.1:49928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:48:31.17014Z","caller":"traceutil/trace.go:171","msg":"trace[1564412855] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"804.728693ms","start":"2026-04-28T06:48:30.365392Z","end":"2026-04-28T06:48:31.17012Z","steps":["trace[1564412855] 'process raft request' (duration: 804.296293ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:48:31.170231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:48:30.365356Z","time spent":"804.837956ms","remote":"127.0.0.1:50236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T06:48:32.550376Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.381290949s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-28T06:48:32.758414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.739118ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T06:48:32.758516Z","caller":"traceutil/trace.go:171","msg":"trace[1918548372] linearizableReadLoop","detail":"{readStateIndex:638; appliedIndex:632; }","duration":"1.776698816s","start":"2026-04-28T06:48:30.981801Z","end":"2026-04-28T06:48:32.7585Z","steps":["trace[1918548372] 'read index received' (duration: 187.359601ms)","trace[1918548372] 'applied index is now lower than readState.Index' (duration: 1.589338235s)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:48:32.758602Z","caller":"traceutil/trace.go:171","msg":"trace[1145202533] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"2.235067353s","start":"2026-04-28T06:48:30.523524Z","end":"2026-04-28T06:48:32.758591Z","steps":["trace[1145202533] 'process raft request' (duration: 2.027075639s)","trace[1145202533] 'compare' (duration: 207.650026ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:48:32.758637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.576388481s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-28T06:48:32.758659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:48:30.523493Z","time spent":"2.235139225s","remote":"127.0.0.1:49914","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4539,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:48:32.758697Z","caller":"traceutil/trace.go:171","msg":"trace[417167504] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:618; }","duration":"1.576477213s","start":"2026-04-28T06:48:31.182204Z","end":"2026-04-28T06:48:32.758681Z","steps":["trace[417167504] 'agreement among raft nodes before linearized reading' (duration: 1.576396511s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:48:32.758735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:48:31.182187Z","time spent":"1.576540825s","remote":"127.0.0.1:49964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"warn","ts":"2026-04-28T06:48:32.758912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.777106685s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-28T06:48:32.758926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.248150482s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-28T06:48:32.758979Z","caller":"traceutil/trace.go:171","msg":"trace[1415538524] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:618; }","duration":"1.248239924s","start":"2026-04-28T06:48:31.510725Z","end":"2026-04-28T06:48:32.758965Z","steps":["trace[1415538524] 'agreement among raft nodes before linearized reading' (duration: 1.24809216s)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:48:32.758973Z","caller":"traceutil/trace.go:171","msg":"trace[1475317706] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:618; }","duration":"1.777210428s","start":"2026-04-28T06:48:30.981747Z","end":"2026-04-28T06:48:32.758958Z","steps":["trace[1475317706] 'agreement among raft nodes before linearized reading' (duration: 1.777111985s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:48:32.759068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:48:30.981728Z","time spent":"1.777328101s","remote":"127.0.0.1:49712","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-28T06:48:32.759068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:48:31.510706Z","time spent":"1.248349107s","remote":"127.0.0.1:49980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-28T06:48:33.270525Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10836960132255492624,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-28T06:48:33.652186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.709704ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T06:48:33.652334Z","caller":"traceutil/trace.go:171","msg":"trace[806236720] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"888.675016ms","start":"2026-04-28T06:48:32.763639Z","end":"2026-04-28T06:48:33.652314Z","steps":["trace[806236720] 'process raft request' (duration: 724.764427ms)","trace[806236720] 'compare' (duration: 163.430097ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:48:33.652399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:48:32.763617Z","time spent":"888.751418ms","remote":"127.0.0.1:49980","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-04-28T06:48:33.661985Z","caller":"traceutil/trace.go:171","msg":"trace[1148667358] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"896.08487ms","start":"2026-04-28T06:48:32.765868Z","end":"2026-04-28T06:48:33.661952Z","steps":["trace[1148667358] 'process raft request' (duration: 895.882435ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:48:33.662513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:48:32.765856Z","time spent":"896.581221ms","remote":"127.0.0.1:49980","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-04-28T06:48:33.668759Z","caller":"traceutil/trace.go:171","msg":"trace[1794154730] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"518.152715ms","start":"2026-04-28T06:48:33.150544Z","end":"2026-04-28T06:48:33.668697Z","steps":["trace[1794154730] 'process raft request' (duration: 516.22769ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:48:33.669006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:48:33.150516Z","time spent":"518.403172ms","remote":"127.0.0.1:49832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036309,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:48:33.742308Z","caller":"traceutil/trace.go:171","msg":"trace[106627603] linearizableReadLoop","detail":"{readStateIndex:641; appliedIndex:638; }","duration":"972.33792ms","start":"2026-04-28T06:48:32.769906Z","end":"2026-04-28T06:48:33.742244Z","steps":["trace[106627603] 'read index received' (duration: 718.589883ms)","trace[106627603] 'applied index is now lower than readState.Index' (duration: 253.746497ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:48:33.742479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"972.547025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:48:33.742522Z","caller":"traceutil/trace.go:171","msg":"trace[2094724875] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:621; }","duration":"972.633017ms","start":"2026-04-28T06:48:32.769876Z","end":"2026-04-28T06:48:33.742509Z","steps":["trace[2094724875] 'agreement among raft nodes before linearized reading' (duration: 972.541875ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:48:33.742563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:48:32.769861Z","time spent":"972.694198ms","remote":"127.0.0.1:49712","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-28T06:48:33.75937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"581.584885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-28T06:48:33.759472Z","caller":"traceutil/trace.go:171","msg":"trace[1907167823] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:622; }","duration":"581.700778ms","start":"2026-04-28T06:48:33.177722Z","end":"2026-04-28T06:48:33.759423Z","steps":["trace[1907167823] 'agreement among raft nodes before linearized reading' (duration: 581.502233ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:48:33.759512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:48:33.1777Z","time spent":"581.80482ms","remote":"127.0.0.1:49980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-28T06:48:33.759914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"582.013405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-28T06:48:33.759944Z","caller":"traceutil/trace.go:171","msg":"trace[761322369] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:622; }","duration":"582.095097ms","start":"2026-04-28T06:48:33.177841Z","end":"2026-04-28T06:48:33.759936Z","steps":["trace[761322369] 'agreement among raft nodes before linearized reading' (duration: 582.023945ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:48:33.759963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:48:33.177834Z","time spent":"582.124607ms","remote":"127.0.0.1:49980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-28T06:48:33.974431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.246947ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-28T06:48:33.974701Z","caller":"traceutil/trace.go:171","msg":"trace[1571289449] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"134.577899ms","start":"2026-04-28T06:48:33.840109Z","end":"2026-04-28T06:48:33.974687Z","steps":["trace[1571289449] 'process raft request' (duration: 134.502427ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:48:33.974931Z","caller":"traceutil/trace.go:171","msg":"trace[1548866930] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"137.258552ms","start":"2026-04-28T06:48:33.837619Z","end":"2026-04-28T06:48:33.974878Z","steps":["trace[1548866930] 'process raft request' (duration: 32.511143ms)","trace[1548866930] 'compare' (duration: 104.149175ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:48:33.975113Z","caller":"traceutil/trace.go:171","msg":"trace[933809386] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"136.834602ms","start":"2026-04-28T06:48:33.838254Z","end":"2026-04-28T06:48:33.975089Z","steps":["trace[933809386] 'process raft request' (duration: 136.288149ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:48:33.975136Z","caller":"traceutil/trace.go:171","msg":"trace[475412406] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"137.264822ms","start":"2026-04-28T06:48:33.837856Z","end":"2026-04-28T06:48:33.97512Z","steps":["trace[475412406] 'process raft request' (duration: 136.655538ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:48:35.238624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.185919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-28T06:48:35.238627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.151069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:48:35.238668Z","caller":"traceutil/trace.go:171","msg":"trace[626112307] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:637; }","duration":"108.263802ms","start":"2026-04-28T06:48:35.130396Z","end":"2026-04-28T06:48:35.238659Z","steps":["trace[626112307] 'count revisions from in-memory index tree' (duration: 108.078697ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:48:35.238684Z","caller":"traceutil/trace.go:171","msg":"trace[85804291] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:637; }","duration":"108.251421ms","start":"2026-04-28T06:48:35.130417Z","end":"2026-04-28T06:48:35.238669Z","steps":["trace[85804291] 'range keys from in-memory index tree' (duration: 108.057077ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:48:36.290606Z","caller":"traceutil/trace.go:171","msg":"trace[1337615519] transaction","detail":"{read_only:false; response_revision:668; number_of_response:1; }","duration":"165.463642ms","start":"2026-04-28T06:48:36.125105Z","end":"2026-04-28T06:48:36.290568Z","steps":["trace[1337615519] 'process raft request' (duration: 157.199079ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:48:37.262205Z","caller":"traceutil/trace.go:171","msg":"trace[609467030] transaction","detail":"{read_only:false; response_revision:679; number_of_response:1; }","duration":"124.096912ms","start":"2026-04-28T06:48:37.13805Z","end":"2026-04-28T06:48:37.262147Z","steps":["trace[609467030] 'process raft request' (duration: 110.943123ms)","trace[609467030] 'compare' (duration: 13.080917ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:48:49.074212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:48:48.403722Z","time spent":"670.484328ms","remote":"127.0.0.1:49756","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-04-28T06:50:04.303851Z","caller":"traceutil/trace.go:171","msg":"trace[1476115722] linearizableReadLoop","detail":"{readStateIndex:1910; appliedIndex:1909; }","duration":"158.828935ms","start":"2026-04-28T06:50:04.144996Z","end":"2026-04-28T06:50:04.303825Z","steps":["trace[1476115722] 'read index received' (duration: 158.666452ms)","trace[1476115722] 'applied index is now lower than readState.Index' (duration: 161.443µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:50:04.303983Z","caller":"traceutil/trace.go:171","msg":"trace[1650691335] transaction","detail":"{read_only:false; response_revision:1843; number_of_response:1; }","duration":"221.928598ms","start":"2026-04-28T06:50:04.082043Z","end":"2026-04-28T06:50:04.303972Z","steps":["trace[1650691335] 'process raft request' (duration: 221.622912ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:50:04.304491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.95649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-28T06:50:04.304592Z","caller":"traceutil/trace.go:171","msg":"trace[594057338] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1843; }","duration":"126.106543ms","start":"2026-04-28T06:50:04.178462Z","end":"2026-04-28T06:50:04.304569Z","steps":["trace[594057338] 'agreement among raft nodes before linearized reading' (duration: 125.842028ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:50:04.304661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.64738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-04-28T06:50:04.304706Z","caller":"traceutil/trace.go:171","msg":"trace[183573803] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1843; }","duration":"159.727802ms","start":"2026-04-28T06:50:04.144967Z","end":"2026-04-28T06:50:04.304694Z","steps":["trace[183573803] 'agreement among raft nodes before linearized reading' (duration: 159.558339ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:50:04.813994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.837273ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T06:50:04.814273Z","caller":"traceutil/trace.go:171","msg":"trace[1389986036] linearizableReadLoop","detail":"{readStateIndex:1913; appliedIndex:1910; }","duration":"468.701561ms","start":"2026-04-28T06:50:04.345555Z","end":"2026-04-28T06:50:04.814256Z","steps":["trace[1389986036] 'read index received' (duration: 94.524301ms)","trace[1389986036] 'applied index is now lower than readState.Index' (duration: 374.17612ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:50:04.814304Z","caller":"traceutil/trace.go:171","msg":"trace[1245403652] transaction","detail":"{read_only:false; response_revision:1846; number_of_response:1; }","duration":"504.36063ms","start":"2026-04-28T06:50:04.309923Z","end":"2026-04-28T06:50:04.814284Z","steps":["trace[1245403652] 'process raft request' (duration: 504.239577ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:50:04.814302Z","caller":"traceutil/trace.go:171","msg":"trace[1960967892] transaction","detail":"{read_only:false; response_revision:1845; number_of_response:1; }","duration":"504.572603ms","start":"2026-04-28T06:50:04.309695Z","end":"2026-04-28T06:50:04.814268Z","steps":["trace[1960967892] 'process raft request' (duration: 504.42163ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:50:04.814397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:50:04.309908Z","time spent":"504.444291ms","remote":"127.0.0.1:49980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:50:04.814481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.922465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-28T06:50:04.814481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:50:04.309676Z","time spent":"504.708995ms","remote":"127.0.0.1:49980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:50:04.814527Z","caller":"traceutil/trace.go:171","msg":"trace[579126326] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1847; }","duration":"468.994507ms","start":"2026-04-28T06:50:04.345512Z","end":"2026-04-28T06:50:04.814507Z","steps":["trace[579126326] 'agreement among raft nodes before linearized reading' (duration: 468.853424ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:50:04.814573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:50:04.345486Z","time spent":"469.078328ms","remote":"127.0.0.1:49980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-28T06:50:04.814617Z","caller":"traceutil/trace.go:171","msg":"trace[1163448960] transaction","detail":"{read_only:false; response_revision:1844; number_of_response:1; }","duration":"505.731785ms","start":"2026-04-28T06:50:04.308855Z","end":"2026-04-28T06:50:04.814587Z","steps":["trace[1163448960] 'process raft request' (duration: 131.210799ms)","trace[1163448960] 'compare' (duration: 373.731191ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:50:04.814349Z","caller":"traceutil/trace.go:171","msg":"trace[1555945823] transaction","detail":"{read_only:false; response_revision:1847; number_of_response:1; }","duration":"439.23488ms","start":"2026-04-28T06:50:04.375104Z","end":"2026-04-28T06:50:04.814339Z","steps":["trace[1555945823] 'process raft request' (duration: 439.101657ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:50:04.814722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:50:04.37509Z","time spent":"439.592936ms","remote":"127.0.0.1:49980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:50:04.814883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:50:04.308832Z","time spent":"505.849827ms","remote":"127.0.0.1:49980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:50:04.855619Z","caller":"traceutil/trace.go:171","msg":"trace[82580972] transaction","detail":"{read_only:false; response_revision:1848; number_of_response:1; }","duration":"149.962499ms","start":"2026-04-28T06:50:04.705627Z","end":"2026-04-28T06:50:04.855589Z","steps":["trace[82580972] 'process raft request' (duration: 149.660763ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:50:04.855705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.381974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-2b818335-4322-4c7f-9778-c3bb73e1992c\" ","response":"range_response_count:1 size:3627"} {"level":"warn","ts":"2026-04-28T06:50:04.855671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.573815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-28T06:50:04.855767Z","caller":"traceutil/trace.go:171","msg":"trace[981600005] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-2b818335-4322-4c7f-9778-c3bb73e1992c; range_end:; response_count:1; response_revision:1848; }","duration":"421.490006ms","start":"2026-04-28T06:50:04.434254Z","end":"2026-04-28T06:50:04.855744Z","steps":["trace[981600005] 'agreement among raft nodes before linearized reading' (duration: 421.288712ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:50:04.855816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:50:04.434234Z","time spent":"421.570728ms","remote":"127.0.0.1:49928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3650,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-2b818335-4322-4c7f-9778-c3bb73e1992c\" "} {"level":"warn","ts":"2026-04-28T06:50:04.855998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.245619ms","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-04-28T06:50:04.85578Z","caller":"traceutil/trace.go:171","msg":"trace[1899623221] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1848; }","duration":"486.80268ms","start":"2026-04-28T06:50:04.368959Z","end":"2026-04-28T06:50:04.855761Z","steps":["trace[1899623221] 'agreement among raft nodes before linearized reading' (duration: 486.492315ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:50:04.856045Z","caller":"traceutil/trace.go:171","msg":"trace[571143216] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1848; }","duration":"304.329641ms","start":"2026-04-28T06:50:04.5517Z","end":"2026-04-28T06:50:04.856029Z","steps":["trace[571143216] 'agreement among raft nodes before linearized reading' (duration: 303.903423ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:50:04.856083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:50:04.551676Z","time spent":"304.398383ms","remote":"127.0.0.1:52734","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-04-28T06:50:04.856364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:50:04.368948Z","time spent":"487.096246ms","remote":"127.0.0.1:49980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-28T06:50:04.856001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.29272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-2b818335-4322-4c7f-9778-c3bb73e1992c\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-04-28T06:50:04.856781Z","caller":"traceutil/trace.go:171","msg":"trace[1671975835] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-2b818335-4322-4c7f-9778-c3bb73e1992c; range_end:; response_count:1; response_revision:1848; }","duration":"487.096975ms","start":"2026-04-28T06:50:04.369657Z","end":"2026-04-28T06:50:04.856754Z","steps":["trace[1671975835] 'agreement among raft nodes before linearized reading' (duration: 485.957514ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:50:04.856946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:50:04.369586Z","time spent":"487.32693ms","remote":"127.0.0.1:49928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3650,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-2b818335-4322-4c7f-9778-c3bb73e1992c\" "} {"level":"warn","ts":"2026-04-28T06:50:05.313604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.164853ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T06:50:05.313716Z","caller":"traceutil/trace.go:171","msg":"trace[800587454] transaction","detail":"{read_only:false; response_revision:1849; number_of_response:1; }","duration":"494.411818ms","start":"2026-04-28T06:50:04.819285Z","end":"2026-04-28T06:50:05.313696Z","steps":["trace[800587454] 'process raft request' (duration: 288.070942ms)","trace[800587454] 'compare' (duration: 205.834987ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:50:05.313778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:50:04.819261Z","time spent":"494.486349ms","remote":"127.0.0.1:49980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:50:05.317984Z","caller":"traceutil/trace.go:171","msg":"trace[797935400] transaction","detail":"{read_only:false; response_revision:1851; number_of_response:1; }","duration":"448.63774ms","start":"2026-04-28T06:50:04.869321Z","end":"2026-04-28T06:50:05.317958Z","steps":["trace[797935400] 'process raft request' (duration: 448.554209ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:50:05.318156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:50:04.869304Z","time spent":"448.760083ms","remote":"127.0.0.1:49928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3732,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:50:05.318159Z","caller":"traceutil/trace.go:171","msg":"trace[662705898] transaction","detail":"{read_only:false; response_revision:1850; number_of_response:1; }","duration":"456.588259ms","start":"2026-04-28T06:50:04.86154Z","end":"2026-04-28T06:50:05.318128Z","steps":["trace[662705898] 'process raft request' (duration: 456.225742ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:50:05.31832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:50:04.861519Z","time spent":"456.718872ms","remote":"127.0.0.1:49980","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-04-28T06:50:18.082354Z","caller":"traceutil/trace.go:171","msg":"trace[360533263] transaction","detail":"{read_only:false; response_revision:1960; number_of_response:1; }","duration":"499.802997ms","start":"2026-04-28T06:50:17.582528Z","end":"2026-04-28T06:50:18.082331Z","steps":["trace[360533263] 'process raft request' (duration: 499.643384ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:50:18.082493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:50:17.582479Z","time spent":"499.942149ms","remote":"127.0.0.1:49980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:50:18.08408Z","caller":"traceutil/trace.go:171","msg":"trace[1418632021] transaction","detail":"{read_only:false; response_revision:1961; number_of_response:1; }","duration":"323.780464ms","start":"2026-04-28T06:50:17.760285Z","end":"2026-04-28T06:50:18.084066Z","steps":["trace[1418632021] 'process raft request' (duration: 323.55534ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:50:18.084071Z","caller":"traceutil/trace.go:171","msg":"trace[990968437] linearizableReadLoop","detail":"{readStateIndex:2030; appliedIndex:2028; }","duration":"280.122501ms","start":"2026-04-28T06:50:17.803905Z","end":"2026-04-28T06:50:18.084027Z","steps":["trace[990968437] 'read index received' (duration: 278.366907ms)","trace[990968437] 'applied index is now lower than readState.Index' (duration: 1.754304ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:50:18.084162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:50:17.760245Z","time spent":"323.881117ms","remote":"127.0.0.1:49980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:50:18.084301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.398346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11989"} {"level":"info","ts":"2026-04-28T06:50:18.084365Z","caller":"traceutil/trace.go:171","msg":"trace[1009906975] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:1961; }","duration":"280.477937ms","start":"2026-04-28T06:50:17.80386Z","end":"2026-04-28T06:50:18.084337Z","steps":["trace[1009906975] 'agreement among raft nodes before linearized reading' (duration: 280.299633ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:50:18.084389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.653169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:50:18.084419Z","caller":"traceutil/trace.go:171","msg":"trace[2085231325] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1961; }","duration":"102.72295ms","start":"2026-04-28T06:50:17.981689Z","end":"2026-04-28T06:50:18.084412Z","steps":["trace[2085231325] 'agreement among raft nodes before linearized reading' (duration: 102.671729ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:50:18.084473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.385902ms","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-04-28T06:50:18.084557Z","caller":"traceutil/trace.go:171","msg":"trace[742856673] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1961; }","duration":"135.533125ms","start":"2026-04-28T06:50:17.949009Z","end":"2026-04-28T06:50:18.084542Z","steps":["trace[742856673] 'agreement among raft nodes before linearized reading' (duration: 135.27778ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:50:38.047487Z","caller":"traceutil/trace.go:171","msg":"trace[131122772] linearizableReadLoop","detail":"{readStateIndex:2157; appliedIndex:2156; }","duration":"223.680427ms","start":"2026-04-28T06:50:37.823778Z","end":"2026-04-28T06:50:38.047458Z","steps":["trace[131122772] 'read index received' (duration: 223.495373ms)","trace[131122772] 'applied index is now lower than readState.Index' (duration: 184.144µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:50:38.047628Z","caller":"traceutil/trace.go:171","msg":"trace[1371984908] transaction","detail":"{read_only:false; response_revision:2084; number_of_response:1; }","duration":"249.526766ms","start":"2026-04-28T06:50:37.79808Z","end":"2026-04-28T06:50:38.047606Z","steps":["trace[1371984908] 'process raft request' (duration: 249.21932ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:50:38.047822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.038605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-28T06:50:38.047872Z","caller":"traceutil/trace.go:171","msg":"trace[639572305] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:2084; }","duration":"224.137696ms","start":"2026-04-28T06:50:37.823725Z","end":"2026-04-28T06:50:38.047862Z","steps":["trace[639572305] 'agreement among raft nodes before linearized reading' (duration: 224.011943ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:50:38.286741Z","caller":"traceutil/trace.go:171","msg":"trace[407917455] linearizableReadLoop","detail":"{readStateIndex:2158; appliedIndex:2157; }","duration":"116.589282ms","start":"2026-04-28T06:50:38.170115Z","end":"2026-04-28T06:50:38.286705Z","steps":["trace[407917455] 'read index received' (duration: 98.56243ms)","trace[407917455] 'applied index is now lower than readState.Index' (duration: 18.025512ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:50:38.286839Z","caller":"traceutil/trace.go:171","msg":"trace[2140072575] transaction","detail":"{read_only:false; response_revision:2085; number_of_response:1; }","duration":"141.213296ms","start":"2026-04-28T06:50:38.145602Z","end":"2026-04-28T06:50:38.286815Z","steps":["trace[2140072575] 'process raft request' (duration: 123.049671ms)","trace[2140072575] 'compare' (duration: 17.848659ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:50:38.287454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.327146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-04-28T06:50:38.287511Z","caller":"traceutil/trace.go:171","msg":"trace[522327996] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2085; }","duration":"117.427669ms","start":"2026-04-28T06:50:38.170068Z","end":"2026-04-28T06:50:38.287495Z","steps":["trace[522327996] 'agreement among raft nodes before linearized reading' (duration: 117.251415ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:50:38.287786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.447698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12386"} {"level":"info","ts":"2026-04-28T06:50:38.28807Z","caller":"traceutil/trace.go:171","msg":"trace[1842755077] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2085; }","duration":"110.764115ms","start":"2026-04-28T06:50:38.177286Z","end":"2026-04-28T06:50:38.28805Z","steps":["trace[1842755077] 'agreement among raft nodes before linearized reading' (duration: 110.045851ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:50:38.510212Z","caller":"traceutil/trace.go:171","msg":"trace[390255283] linearizableReadLoop","detail":"{readStateIndex:2159; appliedIndex:2158; }","duration":"212.167112ms","start":"2026-04-28T06:50:38.298023Z","end":"2026-04-28T06:50:38.51019Z","steps":["trace[390255283] 'read index received' (duration: 143.594094ms)","trace[390255283] 'applied index is now lower than readState.Index' (duration: 68.571888ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:50:38.510291Z","caller":"traceutil/trace.go:171","msg":"trace[394462350] transaction","detail":"{read_only:false; response_revision:2086; number_of_response:1; }","duration":"219.217213ms","start":"2026-04-28T06:50:38.291036Z","end":"2026-04-28T06:50:38.510253Z","steps":["trace[394462350] 'process raft request' (duration: 150.633735ms)","trace[394462350] 'compare' (duration: 68.424325ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:50:38.510384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.345265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:50:38.51042Z","caller":"traceutil/trace.go:171","msg":"trace[580481153] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:2086; }","duration":"212.421857ms","start":"2026-04-28T06:50:38.297989Z","end":"2026-04-28T06:50:38.51041Z","steps":["trace[580481153] 'agreement among raft nodes before linearized reading' (duration: 212.325025ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:50:38.510786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.5382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.246\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-28T06:50:38.510911Z","caller":"traceutil/trace.go:171","msg":"trace[564053615] range","detail":"{range_begin:/registry/masterleases/199.204.45.246; range_end:; response_count:1; response_revision:2086; }","duration":"100.693093ms","start":"2026-04-28T06:50:38.410194Z","end":"2026-04-28T06:50:38.510888Z","steps":["trace[564053615] 'agreement among raft nodes before linearized reading' (duration: 100.311385ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:50:38.682716Z","caller":"traceutil/trace.go:171","msg":"trace[2112814141] linearizableReadLoop","detail":"{readStateIndex:2161; appliedIndex:2160; }","duration":"130.4463ms","start":"2026-04-28T06:50:38.552229Z","end":"2026-04-28T06:50:38.682675Z","steps":["trace[2112814141] 'read index received' (duration: 80.254232ms)","trace[2112814141] 'applied index is now lower than readState.Index' (duration: 50.190948ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:50:38.682904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.664514ms","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-04-28T06:50:38.682954Z","caller":"traceutil/trace.go:171","msg":"trace[1968028835] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2087; }","duration":"130.762056ms","start":"2026-04-28T06:50:38.552182Z","end":"2026-04-28T06:50:38.682944Z","steps":["trace[1968028835] 'agreement among raft nodes before linearized reading' (duration: 130.569332ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:50:38.683251Z","caller":"traceutil/trace.go:171","msg":"trace[1005130325] transaction","detail":"{read_only:false; response_revision:2087; number_of_response:1; }","duration":"137.484831ms","start":"2026-04-28T06:50:38.545219Z","end":"2026-04-28T06:50:38.682704Z","steps":["trace[1005130325] 'process raft request' (duration: 87.362165ms)","trace[1005130325] 'compare' (duration: 49.658457ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:50:39.259992Z","caller":"traceutil/trace.go:171","msg":"trace[1423261696] transaction","detail":"{read_only:false; response_revision:2091; number_of_response:1; }","duration":"229.988785ms","start":"2026-04-28T06:50:39.029978Z","end":"2026-04-28T06:50:39.259967Z","steps":["trace[1423261696] 'process raft request' (duration: 196.782787ms)","trace[1423261696] 'compare' (duration: 33.025244ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:50:39.260108Z","caller":"traceutil/trace.go:171","msg":"trace[1899030149] transaction","detail":"{read_only:false; response_revision:2092; number_of_response:1; }","duration":"229.667759ms","start":"2026-04-28T06:50:39.030392Z","end":"2026-04-28T06:50:39.26006Z","steps":["trace[1899030149] 'process raft request' (duration: 229.499436ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:50:40.867579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"917.96012ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T06:50:40.867709Z","caller":"traceutil/trace.go:171","msg":"trace[1634342152] transaction","detail":"{read_only:false; response_revision:2095; number_of_response:1; }","duration":"1.385196308s","start":"2026-04-28T06:50:39.482478Z","end":"2026-04-28T06:50:40.867674Z","steps":["trace[1634342152] 'process raft request' (duration: 467.057825ms)","trace[1634342152] 'compare' (duration: 917.168004ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:50:40.867876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:50:39.482462Z","time spent":"1.385329541s","remote":"127.0.0.1:49980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:50:40.867918Z","caller":"traceutil/trace.go:171","msg":"trace[1226171081] transaction","detail":"{read_only:false; response_revision:2097; number_of_response:1; }","duration":"576.744998ms","start":"2026-04-28T06:50:40.291149Z","end":"2026-04-28T06:50:40.867894Z","steps":["trace[1226171081] 'process raft request' (duration: 576.682106ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:50:40.867902Z","caller":"traceutil/trace.go:171","msg":"trace[123005615] linearizableReadLoop","detail":"{readStateIndex:2169; appliedIndex:2168; }","duration":"1.385196708s","start":"2026-04-28T06:50:39.482685Z","end":"2026-04-28T06:50:40.867881Z","steps":["trace[123005615] 'read index received' (duration: 466.866821ms)","trace[123005615] 'applied index is now lower than readState.Index' (duration: 918.327657ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:50:40.868075Z","caller":"traceutil/trace.go:171","msg":"trace[320444678] transaction","detail":"{read_only:false; response_revision:2096; number_of_response:1; }","duration":"1.074800645s","start":"2026-04-28T06:50:39.793265Z","end":"2026-04-28T06:50:40.868065Z","steps":["trace[320444678] 'process raft request' (duration: 1.074416967s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:50:40.86815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:50:39.793239Z","time spent":"1.074874796s","remote":"127.0.0.1:49980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:50:40.86827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.385590076s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-28T06:50:40.868337Z","caller":"traceutil/trace.go:171","msg":"trace[2137383770] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2097; }","duration":"1.385698858s","start":"2026-04-28T06:50:39.482622Z","end":"2026-04-28T06:50:40.868321Z","steps":["trace[2137383770] 'agreement among raft nodes before linearized reading' (duration: 1.385523905s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:50:40.868571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:50:39.482603Z","time spent":"1.385773709s","remote":"127.0.0.1:49980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-28T06:50:40.868024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:50:40.291124Z","time spent":"576.84747ms","remote":"127.0.0.1:49980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:50:40.86893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"887.028497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:50:40.869043Z","caller":"traceutil/trace.go:171","msg":"trace[1593259367] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2097; }","duration":"887.17639ms","start":"2026-04-28T06:50:39.981849Z","end":"2026-04-28T06:50:40.869026Z","steps":["trace[1593259367] 'agreement among raft nodes before linearized reading' (duration: 887.034298ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:50:40.869137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:50:39.98183Z","time spent":"887.290273ms","remote":"127.0.0.1:49700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-28T06:50:40.869496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"528.388594ms","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-04-28T06:50:40.869533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.993601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-04-28T06:50:40.869549Z","caller":"traceutil/trace.go:171","msg":"trace[534391798] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2097; }","duration":"528.470446ms","start":"2026-04-28T06:50:40.341063Z","end":"2026-04-28T06:50:40.869534Z","steps":["trace[534391798] 'agreement among raft nodes before linearized reading' (duration: 528.313763ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:50:40.869588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:50:40.341051Z","time spent":"528.528687ms","remote":"127.0.0.1:52734","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":"info","ts":"2026-04-28T06:50:40.869587Z","caller":"traceutil/trace.go:171","msg":"trace[1561018820] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2097; }","duration":"354.046182ms","start":"2026-04-28T06:50:40.515523Z","end":"2026-04-28T06:50:40.869569Z","steps":["trace[1561018820] 'agreement among raft nodes before linearized reading' (duration: 353.91098ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:50:40.869507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"776.37447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:528"} {"level":"info","ts":"2026-04-28T06:50:40.869741Z","caller":"traceutil/trace.go:171","msg":"trace[398547536] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:2097; }","duration":"776.633695ms","start":"2026-04-28T06:50:40.093089Z","end":"2026-04-28T06:50:40.869723Z","steps":["trace[398547536] 'agreement among raft nodes before linearized reading' (duration: 776.314788ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:50:40.869834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:50:40.093075Z","time spent":"776.741016ms","remote":"127.0.0.1:49980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":551,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"warn","ts":"2026-04-28T06:50:40.869637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:50:40.515436Z","time spent":"354.188715ms","remote":"127.0.0.1:49980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":577,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-04-28T06:50:41.232336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.519221ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:50:41.232987Z","caller":"traceutil/trace.go:171","msg":"trace[526833174] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:2097; }","duration":"232.173084ms","start":"2026-04-28T06:50:41.000793Z","end":"2026-04-28T06:50:41.232966Z","steps":["trace[526833174] 'range keys from in-memory index tree' (duration: 231.50471ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:50:41.439016Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10836960132255495635,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-28T06:50:41.499296Z","caller":"traceutil/trace.go:171","msg":"trace[1464712165] transaction","detail":"{read_only:false; response_revision:2098; number_of_response:1; }","duration":"623.230579ms","start":"2026-04-28T06:50:40.87604Z","end":"2026-04-28T06:50:41.499271Z","steps":["trace[1464712165] 'process raft request' (duration: 623.067676ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:50:41.49962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:50:40.876019Z","time spent":"623.415973ms","remote":"127.0.0.1:49980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:50:41.744252Z","caller":"traceutil/trace.go:171","msg":"trace[1672902791] transaction","detail":"{read_only:false; response_revision:2099; number_of_response:1; }","duration":"867.852374ms","start":"2026-04-28T06:50:40.876373Z","end":"2026-04-28T06:50:41.744225Z","steps":["trace[1672902791] 'process raft request' (duration: 865.379704ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:50:41.744419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:50:40.876365Z","time spent":"867.969786ms","remote":"127.0.0.1:49980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":513,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:50:41.744945Z","caller":"traceutil/trace.go:171","msg":"trace[200257357] linearizableReadLoop","detail":"{readStateIndex:2175; appliedIndex:2172; }","duration":"806.968038ms","start":"2026-04-28T06:50:40.937964Z","end":"2026-04-28T06:50:41.744932Z","steps":["trace[200257357] 'read index received' (duration: 561.753551ms)","trace[200257357] 'applied index is now lower than readState.Index' (duration: 245.213476ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:50:41.74516Z","caller":"traceutil/trace.go:171","msg":"trace[110419767] transaction","detail":"{read_only:false; response_revision:2100; number_of_response:1; }","duration":"868.596569ms","start":"2026-04-28T06:50:40.876553Z","end":"2026-04-28T06:50:41.745149Z","steps":["trace[110419767] 'process raft request' (duration: 867.624599ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:50:41.745226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:50:40.876546Z","time spent":"868.64352ms","remote":"127.0.0.1:49980","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-04-28T06:50:41.745386Z","caller":"traceutil/trace.go:171","msg":"trace[1262742875] transaction","detail":"{read_only:false; response_revision:2101; number_of_response:1; }","duration":"714.901591ms","start":"2026-04-28T06:50:41.030475Z","end":"2026-04-28T06:50:41.745376Z","steps":["trace[1262742875] 'process raft request' (duration: 714.360391ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:50:41.745441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:50:41.030449Z","time spent":"714.964543ms","remote":"127.0.0.1:49980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:50:41.745568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"764.079964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:50:41.745633Z","caller":"traceutil/trace.go:171","msg":"trace[1689290302] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2102; }","duration":"764.181445ms","start":"2026-04-28T06:50:40.98144Z","end":"2026-04-28T06:50:41.745621Z","steps":["trace[1689290302] 'agreement among raft nodes before linearized reading' (duration: 764.089364ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:50:41.745959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:50:40.981423Z","time spent":"764.519703ms","remote":"127.0.0.1:49712","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-28T06:50:41.746102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"482.046764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-28T06:50:41.746148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"482.377681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-28T06:50:41.746163Z","caller":"traceutil/trace.go:171","msg":"trace[1020667381] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2102; }","duration":"482.126196ms","start":"2026-04-28T06:50:41.264022Z","end":"2026-04-28T06:50:41.746148Z","steps":["trace[1020667381] 'agreement among raft nodes before linearized reading' (duration: 481.957763ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:50:41.746192Z","caller":"traceutil/trace.go:171","msg":"trace[1793148563] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2102; }","duration":"482.462463ms","start":"2026-04-28T06:50:41.263718Z","end":"2026-04-28T06:50:41.74618Z","steps":["trace[1793148563] 'agreement among raft nodes before linearized reading' (duration: 482.33205ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:50:41.746209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:50:41.264014Z","time spent":"482.182477ms","remote":"127.0.0.1:49980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-28T06:50:41.746232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:50:41.263698Z","time spent":"482.526134ms","remote":"127.0.0.1:49980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-28T06:50:41.746297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"808.333335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-28T06:50:41.746333Z","caller":"traceutil/trace.go:171","msg":"trace[1866388504] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:2102; }","duration":"808.400627ms","start":"2026-04-28T06:50:40.937923Z","end":"2026-04-28T06:50:41.746323Z","steps":["trace[1866388504] 'agreement among raft nodes before linearized reading' (duration: 808.334885ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:50:41.746363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:50:40.937892Z","time spent":"808.462927ms","remote":"127.0.0.1:50130","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":30,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"info","ts":"2026-04-28T06:50:41.746659Z","caller":"traceutil/trace.go:171","msg":"trace[1213242004] transaction","detail":"{read_only:false; response_revision:2102; number_of_response:1; }","duration":"277.920007ms","start":"2026-04-28T06:50:41.468325Z","end":"2026-04-28T06:50:41.746245Z","steps":["trace[1213242004] 'process raft request' (duration: 276.549499ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:50:41.747073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"676.206081ms","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-04-28T06:50:41.747327Z","caller":"traceutil/trace.go:171","msg":"trace[1708793483] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2102; }","duration":"676.492836ms","start":"2026-04-28T06:50:41.070812Z","end":"2026-04-28T06:50:41.747305Z","steps":["trace[1708793483] 'agreement among raft nodes before linearized reading' (duration: 675.319743ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:50:41.74748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:50:41.070794Z","time spent":"676.65058ms","remote":"127.0.0.1:49980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-28T06:51:45.927112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.644032ms","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-04-28T06:51:45.927234Z","caller":"traceutil/trace.go:171","msg":"trace[800829170] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2525; }","duration":"111.798744ms","start":"2026-04-28T06:51:45.815409Z","end":"2026-04-28T06:51:45.927207Z","steps":["trace[800829170] 'range keys from in-memory index tree' (duration: 111.291183ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:52:21.951706Z","caller":"traceutil/trace.go:171","msg":"trace[183789290] transaction","detail":"{read_only:false; response_revision:2745; number_of_response:1; }","duration":"179.727675ms","start":"2026-04-28T06:52:21.771957Z","end":"2026-04-28T06:52:21.951685Z","steps":["trace[183789290] 'process raft request' (duration: 179.575581ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:52:23.142039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.03048443s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-04-28T06:52:23.142118Z","caller":"traceutil/trace.go:171","msg":"trace[722855875] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2745; }","duration":"1.030617753s","start":"2026-04-28T06:52:22.111481Z","end":"2026-04-28T06:52:23.142099Z","steps":["trace[722855875] 'range keys from in-memory index tree' (duration: 1.030194363s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:52:23.142259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:52:22.111458Z","time spent":"1.030738205s","remote":"127.0.0.1:49980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":577,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-04-28T06:52:23.143094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"503.67846ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T06:52:23.143229Z","caller":"traceutil/trace.go:171","msg":"trace[1385283943] transaction","detail":"{read_only:false; response_revision:2746; number_of_response:1; }","duration":"988.001141ms","start":"2026-04-28T06:52:22.155206Z","end":"2026-04-28T06:52:23.143207Z","steps":["trace[1385283943] 'process raft request' (duration: 484.058746ms)","trace[1385283943] 'compare' (duration: 502.81087ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:52:23.143346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:52:22.155184Z","time spent":"988.094753ms","remote":"127.0.0.1:49980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:52:23.230336Z","caller":"traceutil/trace.go:171","msg":"trace[1759515764] linearizableReadLoop","detail":"{readStateIndex:2844; appliedIndex:2842; }","duration":"1.010852636s","start":"2026-04-28T06:52:22.219454Z","end":"2026-04-28T06:52:23.230307Z","steps":["trace[1759515764] 'read index received' (duration: 419.833847ms)","trace[1759515764] 'applied index is now lower than readState.Index' (duration: 591.017429ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:52:23.230671Z","caller":"traceutil/trace.go:171","msg":"trace[908296195] transaction","detail":"{read_only:false; response_revision:2747; number_of_response:1; }","duration":"1.074038392s","start":"2026-04-28T06:52:22.156611Z","end":"2026-04-28T06:52:23.23065Z","steps":["trace[908296195] 'process raft request' (duration: 1.073557652s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:52:23.230875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:52:22.156586Z","time spent":"1.074133454s","remote":"127.0.0.1:49980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:52:23.231109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.011650644s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-28T06:52:23.231215Z","caller":"traceutil/trace.go:171","msg":"trace[1760344928] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2747; }","duration":"1.011761445s","start":"2026-04-28T06:52:22.219398Z","end":"2026-04-28T06:52:23.231159Z","steps":["trace[1760344928] 'agreement among raft nodes before linearized reading' (duration: 1.011298735s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:52:23.231428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:52:22.219338Z","time spent":"1.011996571s","remote":"127.0.0.1:49980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-28T06:52:23.440703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.482056ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T06:52:23.441005Z","caller":"traceutil/trace.go:171","msg":"trace[112679447] transaction","detail":"{read_only:false; response_revision:2748; number_of_response:1; }","duration":"290.099834ms","start":"2026-04-28T06:52:23.150874Z","end":"2026-04-28T06:52:23.440973Z","steps":["trace[112679447] 'process raft request' (duration: 169.221169ms)","trace[112679447] 'compare' (duration: 120.365564ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:52:23.441305Z","caller":"traceutil/trace.go:171","msg":"trace[670473978] linearizableReadLoop","detail":"{readStateIndex:2845; appliedIndex:2844; }","duration":"210.8761ms","start":"2026-04-28T06:52:23.230412Z","end":"2026-04-28T06:52:23.441288Z","steps":["trace[670473978] 'read index received' (duration: 89.69438ms)","trace[670473978] 'applied index is now lower than readState.Index' (duration: 121.17967ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:52:23.441556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"756.503378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-28T06:52:23.44156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.122806478s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-28T06:52:23.441607Z","caller":"traceutil/trace.go:171","msg":"trace[1776851061] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:2750; }","duration":"756.61395ms","start":"2026-04-28T06:52:22.684981Z","end":"2026-04-28T06:52:23.441595Z","steps":["trace[1776851061] 'agreement among raft nodes before linearized reading' (duration: 756.354294ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:52:23.441636Z","caller":"traceutil/trace.go:171","msg":"trace[2049879485] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:2750; }","duration":"1.12294627s","start":"2026-04-28T06:52:22.318665Z","end":"2026-04-28T06:52:23.441612Z","steps":["trace[2049879485] 'agreement among raft nodes before linearized reading' (duration: 1.122796927s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:52:23.441713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:52:22.318622Z","time spent":"1.123072573s","remote":"127.0.0.1:49980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":14,"response size":30,"request content":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true "} {"level":"warn","ts":"2026-04-28T06:52:23.441683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.92436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-28T06:52:23.441667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"903.548497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/auth-system/keycloak-0.18aa72b356e00c58\" ","response":"range_response_count:1 size:779"} {"level":"warn","ts":"2026-04-28T06:52:23.441653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:52:22.684962Z","time spent":"756.678981ms","remote":"127.0.0.1:49776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":120,"response count":54,"response size":30,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true "} {"level":"warn","ts":"2026-04-28T06:52:23.441814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.080798729s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/topicpermissions/\" range_end:\"/registry/rabbitmq.com/topicpermissions0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:52:23.441843Z","caller":"traceutil/trace.go:171","msg":"trace[773933318] range","detail":"{range_begin:/registry/events/auth-system/keycloak-0.18aa72b356e00c58; range_end:; response_count:1; response_revision:2750; }","duration":"903.780182ms","start":"2026-04-28T06:52:22.538044Z","end":"2026-04-28T06:52:23.441824Z","steps":["trace[773933318] 'agreement among raft nodes before linearized reading' (duration: 903.349843ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:52:23.441897Z","caller":"traceutil/trace.go:171","msg":"trace[1800934786] range","detail":"{range_begin:/registry/rabbitmq.com/topicpermissions/; range_end:/registry/rabbitmq.com/topicpermissions0; response_count:0; response_revision:2750; }","duration":"1.080918452s","start":"2026-04-28T06:52:22.360948Z","end":"2026-04-28T06:52:23.441866Z","steps":["trace[1800934786] 'agreement among raft nodes before linearized reading' (duration: 1.08081574s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:52:23.441963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:52:22.360921Z","time spent":"1.081026444s","remote":"127.0.0.1:52508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/topicpermissions/\" range_end:\"/registry/rabbitmq.com/topicpermissions0\" count_only:true "} {"level":"warn","ts":"2026-04-28T06:52:23.441755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"983.969796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-28T06:52:23.442034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.218256791s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-28T06:52:23.441926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:52:22.538016Z","time spent":"903.897935ms","remote":"127.0.0.1:49808","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":1,"response size":802,"request content":"key:\"/registry/events/auth-system/keycloak-0.18aa72b356e00c58\" "} {"level":"info","ts":"2026-04-28T06:52:23.442127Z","caller":"traceutil/trace.go:171","msg":"trace[770101556] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:2750; }","duration":"984.461016ms","start":"2026-04-28T06:52:22.457588Z","end":"2026-04-28T06:52:23.442049Z","steps":["trace[770101556] 'agreement among raft nodes before linearized reading' (duration: 983.72661ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:52:23.44219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:52:22.457563Z","time spent":"984.61094ms","remote":"127.0.0.1:54966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true "} {"level":"warn","ts":"2026-04-28T06:52:23.442242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.190095253s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-28T06:52:23.442407Z","caller":"traceutil/trace.go:171","msg":"trace[1263613782] transaction","detail":"{read_only:false; response_revision:2749; number_of_response:1; }","duration":"206.527426ms","start":"2026-04-28T06:52:23.235865Z","end":"2026-04-28T06:52:23.442393Z","steps":["trace[1263613782] 'process raft request' (duration: 204.970073ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:52:23.442069Z","caller":"traceutil/trace.go:171","msg":"trace[2092478875] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2750; }","duration":"1.218305832s","start":"2026-04-28T06:52:22.223753Z","end":"2026-04-28T06:52:23.442059Z","steps":["trace[2092478875] 'agreement among raft nodes before linearized reading' (duration: 1.218111278s)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:52:23.441793Z","caller":"traceutil/trace.go:171","msg":"trace[1321447499] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2750; }","duration":"462.120294ms","start":"2026-04-28T06:52:22.979651Z","end":"2026-04-28T06:52:23.441771Z","steps":["trace[1321447499] 'agreement among raft nodes before linearized reading' (duration: 461.695775ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:52:23.442566Z","caller":"traceutil/trace.go:171","msg":"trace[1234573416] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2750; }","duration":"1.190169774s","start":"2026-04-28T06:52:22.252102Z","end":"2026-04-28T06:52:23.442272Z","steps":["trace[1234573416] 'agreement among raft nodes before linearized reading' (duration: 1.1900124s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:52:23.442586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:52:22.223747Z","time spent":"1.218799783s","remote":"127.0.0.1:49980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-28T06:52:23.442624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:52:22.979627Z","time spent":"462.977773ms","remote":"127.0.0.1:49712","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-28T06:52:23.44265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:52:22.252084Z","time spent":"1.190550622s","remote":"127.0.0.1:49980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-28T06:52:23.442786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.186487154s","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-04-28T06:52:23.443094Z","caller":"traceutil/trace.go:171","msg":"trace[74251776] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2750; }","duration":"1.186823751s","start":"2026-04-28T06:52:22.256251Z","end":"2026-04-28T06:52:23.443075Z","steps":["trace[74251776] 'agreement among raft nodes before linearized reading' (duration: 1.185259047s)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:52:23.44376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:52:22.256232Z","time spent":"1.187510796s","remote":"127.0.0.1:49980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-28T06:52:23.443304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"979.449398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:52:23.44393Z","caller":"traceutil/trace.go:171","msg":"trace[1754745845] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:2751; }","duration":"980.112872ms","start":"2026-04-28T06:52:22.463793Z","end":"2026-04-28T06:52:23.443906Z","steps":["trace[1754745845] 'agreement among raft nodes before linearized reading' (duration: 979.447008ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:52:23.444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:52:22.463768Z","time spent":"980.211245ms","remote":"127.0.0.1:41650","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"info","ts":"2026-04-28T06:52:23.443418Z","caller":"traceutil/trace.go:171","msg":"trace[754242408] transaction","detail":"{read_only:false; response_revision:2750; number_of_response:1; }","duration":"200.306312ms","start":"2026-04-28T06:52:23.243096Z","end":"2026-04-28T06:52:23.443402Z","steps":["trace[754242408] 'process raft request' (duration: 197.809568ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:52:23.443435Z","caller":"traceutil/trace.go:171","msg":"trace[601375388] transaction","detail":"{read_only:false; response_revision:2751; number_of_response:1; }","duration":"141.666233ms","start":"2026-04-28T06:52:23.301754Z","end":"2026-04-28T06:52:23.44342Z","steps":["trace[601375388] 'process raft request' (duration: 139.19718ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:52:23.44346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.643691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-04-28T06:52:23.444343Z","caller":"traceutil/trace.go:171","msg":"trace[451811676] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2751; }","duration":"202.564131ms","start":"2026-04-28T06:52:23.241758Z","end":"2026-04-28T06:52:23.444322Z","steps":["trace[451811676] 'agreement among raft nodes before linearized reading' (duration: 201.535519ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:52:23.681776Z","caller":"traceutil/trace.go:171","msg":"trace[415797691] transaction","detail":"{read_only:false; response_revision:2752; number_of_response:1; }","duration":"235.519703ms","start":"2026-04-28T06:52:23.446205Z","end":"2026-04-28T06:52:23.681725Z","steps":["trace[415797691] 'process raft request' (duration: 203.747056ms)","trace[415797691] 'compare' (duration: 31.476341ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:52:23.681768Z","caller":"traceutil/trace.go:171","msg":"trace[811037578] linearizableReadLoop","detail":"{readStateIndex:2849; appliedIndex:2848; }","duration":"231.457845ms","start":"2026-04-28T06:52:23.450266Z","end":"2026-04-28T06:52:23.681724Z","steps":["trace[811037578] 'read index received' (duration: 199.737429ms)","trace[811037578] 'applied index is now lower than readState.Index' (duration: 31.717236ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:52:23.682482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.180171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-metrics\" ","response":"range_response_count:1 size:1447"} {"level":"info","ts":"2026-04-28T06:52:23.682545Z","caller":"traceutil/trace.go:171","msg":"trace[183628784] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-metrics; range_end:; response_count:1; response_revision:2752; }","duration":"232.290894ms","start":"2026-04-28T06:52:23.45024Z","end":"2026-04-28T06:52:23.68253Z","steps":["trace[183628784] 'agreement among raft nodes before linearized reading' (duration: 231.66312ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:52:23.690778Z","caller":"traceutil/trace.go:171","msg":"trace[1631670525] transaction","detail":"{read_only:false; response_revision:2753; number_of_response:1; }","duration":"240.325137ms","start":"2026-04-28T06:52:23.450427Z","end":"2026-04-28T06:52:23.690752Z","steps":["trace[1631670525] 'process raft request' (duration: 240.159284ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:52:23.691107Z","caller":"traceutil/trace.go:171","msg":"trace[778672560] transaction","detail":"{read_only:false; response_revision:2754; number_of_response:1; }","duration":"240.579212ms","start":"2026-04-28T06:52:23.450493Z","end":"2026-04-28T06:52:23.691072Z","steps":["trace[778672560] 'process raft request' (duration: 240.218224ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:52:23.69116Z","caller":"traceutil/trace.go:171","msg":"trace[1844311614] transaction","detail":"{read_only:false; response_revision:2755; number_of_response:1; }","duration":"239.836787ms","start":"2026-04-28T06:52:23.451301Z","end":"2026-04-28T06:52:23.691138Z","steps":["trace[1844311614] 'process raft request' (duration: 239.632332ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:52:23.691187Z","caller":"traceutil/trace.go:171","msg":"trace[393726682] transaction","detail":"{read_only:false; response_revision:2756; number_of_response:1; }","duration":"239.721654ms","start":"2026-04-28T06:52:23.45144Z","end":"2026-04-28T06:52:23.691161Z","steps":["trace[393726682] 'process raft request' (duration: 239.630532ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:52:23.691378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.832358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:52:23.691481Z","caller":"traceutil/trace.go:171","msg":"trace[764034018] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:2756; }","duration":"191.951052ms","start":"2026-04-28T06:52:23.499508Z","end":"2026-04-28T06:52:23.691459Z","steps":["trace[764034018] 'agreement among raft nodes before linearized reading' (duration: 191.777528ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:52:39.929869Z","caller":"traceutil/trace.go:171","msg":"trace[205983356] linearizableReadLoop","detail":"{readStateIndex:2939; appliedIndex:2938; }","duration":"153.163525ms","start":"2026-04-28T06:52:39.776682Z","end":"2026-04-28T06:52:39.929846Z","steps":["trace[205983356] 'read index received' (duration: 152.922329ms)","trace[205983356] 'applied index is now lower than readState.Index' (duration: 239.866µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:52:39.929928Z","caller":"traceutil/trace.go:171","msg":"trace[1288262053] transaction","detail":"{read_only:false; response_revision:2838; number_of_response:1; }","duration":"155.137468ms","start":"2026-04-28T06:52:39.774762Z","end":"2026-04-28T06:52:39.9299Z","steps":["trace[1288262053] 'process raft request' (duration: 154.934373ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:52:39.930092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.390349ms","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-04-28T06:52:39.930297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.729509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-28T06:52:39.930398Z","caller":"traceutil/trace.go:171","msg":"trace[756944670] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2838; }","duration":"148.849621ms","start":"2026-04-28T06:52:39.781522Z","end":"2026-04-28T06:52:39.930372Z","steps":["trace[756944670] 'agreement among raft nodes before linearized reading' (duration: 148.599566ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:52:39.93041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.504804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-28T06:52:39.930457Z","caller":"traceutil/trace.go:171","msg":"trace[166004260] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2838; }","duration":"148.559775ms","start":"2026-04-28T06:52:39.781885Z","end":"2026-04-28T06:52:39.930445Z","steps":["trace[166004260] 'agreement among raft nodes before linearized reading' (duration: 148.322679ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:52:39.930581Z","caller":"traceutil/trace.go:171","msg":"trace[1848330096] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2838; }","duration":"153.492862ms","start":"2026-04-28T06:52:39.776636Z","end":"2026-04-28T06:52:39.930129Z","steps":["trace[1848330096] 'agreement among raft nodes before linearized reading' (duration: 153.334798ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:52:45.659674Z","caller":"traceutil/trace.go:171","msg":"trace[1359832216] transaction","detail":"{read_only:false; response_revision:2868; number_of_response:1; }","duration":"104.305577ms","start":"2026-04-28T06:52:45.55535Z","end":"2026-04-28T06:52:45.659656Z","steps":["trace[1359832216] 'process raft request' (duration: 80.470469ms)","trace[1359832216] 'compare' (duration: 23.570382ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:52:53.469155Z","caller":"traceutil/trace.go:171","msg":"trace[47645906] transaction","detail":"{read_only:false; response_revision:2906; number_of_response:1; }","duration":"126.549985ms","start":"2026-04-28T06:52:53.342577Z","end":"2026-04-28T06:52:53.469127Z","steps":["trace[47645906] 'process raft request' (duration: 96.794147ms)","trace[47645906] 'compare' (duration: 29.486262ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:53:34.462274Z","caller":"traceutil/trace.go:171","msg":"trace[1577666593] transaction","detail":"{read_only:false; response_revision:3126; number_of_response:1; }","duration":"103.493372ms","start":"2026-04-28T06:53:34.358756Z","end":"2026-04-28T06:53:34.462249Z","steps":["trace[1577666593] 'process raft request' (duration: 103.449121ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:53:34.462381Z","caller":"traceutil/trace.go:171","msg":"trace[250251097] transaction","detail":"{read_only:false; response_revision:3125; number_of_response:1; }","duration":"156.377675ms","start":"2026-04-28T06:53:34.305946Z","end":"2026-04-28T06:53:34.462324Z","steps":["trace[250251097] 'process raft request' (duration: 156.14731ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:53:34.647227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.447665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-28T06:53:34.647794Z","caller":"traceutil/trace.go:171","msg":"trace[912837167] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:3126; }","duration":"171.07906ms","start":"2026-04-28T06:53:34.476686Z","end":"2026-04-28T06:53:34.647765Z","steps":["trace[912837167] 'count revisions from in-memory index tree' (duration: 170.312112ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:53:49.922459Z","caller":"traceutil/trace.go:171","msg":"trace[45542192] linearizableReadLoop","detail":"{readStateIndex:3317; appliedIndex:3316; }","duration":"127.111789ms","start":"2026-04-28T06:53:49.795322Z","end":"2026-04-28T06:53:49.922433Z","steps":["trace[45542192] 'read index received' (duration: 71.848918ms)","trace[45542192] 'applied index is now lower than readState.Index' (duration: 55.261741ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:53:49.922929Z","caller":"traceutil/trace.go:171","msg":"trace[612173701] transaction","detail":"{read_only:false; response_revision:3201; number_of_response:1; }","duration":"260.670039ms","start":"2026-04-28T06:53:49.662241Z","end":"2026-04-28T06:53:49.922911Z","steps":["trace[612173701] 'process raft request' (duration: 204.911716ms)","trace[612173701] 'compare' (duration: 54.874962ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:53:49.923328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.939908ms","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-04-28T06:53:49.923474Z","caller":"traceutil/trace.go:171","msg":"trace[592094903] range","detail":"{range_begin:/registry/cert-manager.io/issuers/; range_end:/registry/cert-manager.io/issuers0; response_count:0; response_revision:3201; }","duration":"128.153723ms","start":"2026-04-28T06:53:49.79529Z","end":"2026-04-28T06:53:49.923444Z","steps":["trace[592094903] 'agreement among raft nodes before linearized reading' (duration: 127.236302ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:53:49.927533Z","caller":"traceutil/trace.go:171","msg":"trace[1896086047] transaction","detail":"{read_only:false; response_revision:3202; number_of_response:1; }","duration":"120.72152ms","start":"2026-04-28T06:53:49.806784Z","end":"2026-04-28T06:53:49.927506Z","steps":["trace[1896086047] 'process raft request' (duration: 120.573887ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:53:50.081619Z","caller":"traceutil/trace.go:171","msg":"trace[838375755] transaction","detail":"{read_only:false; response_revision:3203; number_of_response:1; }","duration":"105.126835ms","start":"2026-04-28T06:53:49.976474Z","end":"2026-04-28T06:53:50.081601Z","steps":["trace[838375755] 'process raft request' (duration: 62.710904ms)","trace[838375755] 'compare' (duration: 42.207226ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:53:50.081793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.292222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:53:50.081824Z","caller":"traceutil/trace.go:171","msg":"trace[617118656] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3203; }","duration":"100.367814ms","start":"2026-04-28T06:53:49.981449Z","end":"2026-04-28T06:53:50.081817Z","steps":["trace[617118656] 'agreement among raft nodes before linearized reading' (duration: 100.310442ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:54:06.227471Z","caller":"traceutil/trace.go:171","msg":"trace[724860281] linearizableReadLoop","detail":"{readStateIndex:3411; appliedIndex:3410; }","duration":"159.045212ms","start":"2026-04-28T06:54:06.068396Z","end":"2026-04-28T06:54:06.227441Z","steps":["trace[724860281] 'read index received' (duration: 123.350173ms)","trace[724860281] 'applied index is now lower than readState.Index' (duration: 35.694099ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:54:06.227604Z","caller":"traceutil/trace.go:171","msg":"trace[682331927] transaction","detail":"{read_only:false; response_revision:3292; number_of_response:1; }","duration":"186.887828ms","start":"2026-04-28T06:54:06.040704Z","end":"2026-04-28T06:54:06.227591Z","steps":["trace[682331927] 'process raft request' (duration: 151.110208ms)","trace[682331927] 'compare' (duration: 35.508624ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:54:06.227894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.468972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/auth-system/keycloak\" ","response":"range_response_count:1 size:7508"} {"level":"info","ts":"2026-04-28T06:54:06.228018Z","caller":"traceutil/trace.go:171","msg":"trace[383503875] range","detail":"{range_begin:/registry/statefulsets/auth-system/keycloak; range_end:; response_count:1; response_revision:3292; }","duration":"159.638996ms","start":"2026-04-28T06:54:06.068359Z","end":"2026-04-28T06:54:06.227998Z","steps":["trace[383503875] 'agreement among raft nodes before linearized reading' (duration: 159.340039ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:54:06.588599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.347601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-28T06:54:06.588651Z","caller":"traceutil/trace.go:171","msg":"trace[1385248458] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3295; }","duration":"141.437204ms","start":"2026-04-28T06:54:06.447202Z","end":"2026-04-28T06:54:06.58864Z","steps":["trace[1385248458] 'range keys from in-memory index tree' (duration: 141.249019ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:54:22.910227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.427578ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T06:54:22.910481Z","caller":"traceutil/trace.go:171","msg":"trace[261167167] linearizableReadLoop","detail":"{readStateIndex:3590; appliedIndex:3588; }","duration":"170.770813ms","start":"2026-04-28T06:54:22.739551Z","end":"2026-04-28T06:54:22.910322Z","steps":["trace[261167167] 'read index received' (duration: 12.54973ms)","trace[261167167] 'applied index is now lower than readState.Index' (duration: 158.219843ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:54:22.910489Z","caller":"traceutil/trace.go:171","msg":"trace[420414281] transaction","detail":"{read_only:false; response_revision:3465; number_of_response:1; }","duration":"225.946218ms","start":"2026-04-28T06:54:22.684463Z","end":"2026-04-28T06:54:22.910409Z","steps":["trace[420414281] 'process raft request' (duration: 106.256354ms)","trace[420414281] 'compare' (duration: 119.310776ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:54:22.91064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.080771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-28T06:54:22.910788Z","caller":"traceutil/trace.go:171","msg":"trace[802674118] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3465; }","duration":"171.249795ms","start":"2026-04-28T06:54:22.739525Z","end":"2026-04-28T06:54:22.910775Z","steps":["trace[802674118] 'agreement among raft nodes before linearized reading' (duration: 171.005219ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:54:23.033505Z","caller":"traceutil/trace.go:171","msg":"trace[733493789] transaction","detail":"{read_only:false; response_revision:3466; number_of_response:1; }","duration":"118.514806ms","start":"2026-04-28T06:54:22.914963Z","end":"2026-04-28T06:54:23.033478Z","steps":["trace[733493789] 'process raft request' (duration: 114.589586ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:54:38.392891Z","caller":"traceutil/trace.go:171","msg":"trace[199965903] transaction","detail":"{read_only:false; response_revision:3545; number_of_response:1; }","duration":"207.528141ms","start":"2026-04-28T06:54:38.185337Z","end":"2026-04-28T06:54:38.392865Z","steps":["trace[199965903] 'process raft request' (duration: 207.394078ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:54:45.087516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.31671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:54:45.08758Z","caller":"traceutil/trace.go:171","msg":"trace[1998694345] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3585; }","duration":"106.434963ms","start":"2026-04-28T06:54:44.981131Z","end":"2026-04-28T06:54:45.087566Z","steps":["trace[1998694345] 'range keys from in-memory index tree' (duration: 106.137976ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:55:26.530609Z","caller":"traceutil/trace.go:171","msg":"trace[2125824627] transaction","detail":"{read_only:false; response_revision:3874; number_of_response:1; }","duration":"169.447283ms","start":"2026-04-28T06:55:26.361117Z","end":"2026-04-28T06:55:26.530564Z","steps":["trace[2125824627] 'process raft request' (duration: 169.266989ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:55:34.891985Z","caller":"traceutil/trace.go:171","msg":"trace[823950772] transaction","detail":"{read_only:false; response_revision:3922; number_of_response:1; }","duration":"152.81985ms","start":"2026-04-28T06:55:34.73914Z","end":"2026-04-28T06:55:34.89196Z","steps":["trace[823950772] 'process raft request' (duration: 152.666447ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:55:34.904413Z","caller":"traceutil/trace.go:171","msg":"trace[1394309286] transaction","detail":"{read_only:false; response_revision:3923; number_of_response:1; }","duration":"142.064617ms","start":"2026-04-28T06:55:34.76232Z","end":"2026-04-28T06:55:34.904385Z","steps":["trace[1394309286] 'process raft request' (duration: 141.810121ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:55:35.5028Z","caller":"traceutil/trace.go:171","msg":"trace[713180876] linearizableReadLoop","detail":"{readStateIndex:4070; appliedIndex:4069; }","duration":"129.803467ms","start":"2026-04-28T06:55:35.372977Z","end":"2026-04-28T06:55:35.50278Z","steps":["trace[713180876] 'read index received' (duration: 129.663073ms)","trace[713180876] 'applied index is now lower than readState.Index' (duration: 139.214µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:55:35.502937Z","caller":"traceutil/trace.go:171","msg":"trace[851039828] transaction","detail":"{read_only:false; response_revision:3927; number_of_response:1; }","duration":"172.541397ms","start":"2026-04-28T06:55:35.330385Z","end":"2026-04-28T06:55:35.502926Z","steps":["trace[851039828] 'process raft request' (duration: 172.25895ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:55:35.503406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.418841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:8 size:11771"} {"level":"info","ts":"2026-04-28T06:55:35.503479Z","caller":"traceutil/trace.go:171","msg":"trace[997174568] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:8; response_revision:3927; }","duration":"130.528823ms","start":"2026-04-28T06:55:35.372933Z","end":"2026-04-28T06:55:35.503462Z","steps":["trace[997174568] 'agreement among raft nodes before linearized reading' (duration: 130.343719ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:55:35.622441Z","caller":"traceutil/trace.go:171","msg":"trace[571063397] transaction","detail":"{read_only:false; response_revision:3928; number_of_response:1; }","duration":"115.40353ms","start":"2026-04-28T06:55:35.506977Z","end":"2026-04-28T06:55:35.622381Z","steps":["trace[571063397] 'process raft request' (duration: 61.102347ms)","trace[571063397] 'compare' (duration: 54.105599ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:55:35.622594Z","caller":"traceutil/trace.go:171","msg":"trace[1374379719] transaction","detail":"{read_only:false; response_revision:3929; number_of_response:1; }","duration":"115.504683ms","start":"2026-04-28T06:55:35.507075Z","end":"2026-04-28T06:55:35.622579Z","steps":["trace[1374379719] 'process raft request' (duration: 115.262977ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:55:35.622831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.634042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-28T06:55:35.622506Z","caller":"traceutil/trace.go:171","msg":"trace[86365511] linearizableReadLoop","detail":"{readStateIndex:4072; appliedIndex:4070; }","duration":"106.316324ms","start":"2026-04-28T06:55:35.516168Z","end":"2026-04-28T06:55:35.622484Z","steps":["trace[86365511] 'read index received' (duration: 51.921049ms)","trace[86365511] 'applied index is now lower than readState.Index' (duration: 54.394285ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:55:35.622949Z","caller":"traceutil/trace.go:171","msg":"trace[436191125] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:3929; }","duration":"106.761674ms","start":"2026-04-28T06:55:35.51614Z","end":"2026-04-28T06:55:35.622901Z","steps":["trace[436191125] 'agreement among raft nodes before linearized reading' (duration: 106.58097ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:55:56.526846Z","caller":"traceutil/trace.go:171","msg":"trace[513277128] transaction","detail":"{read_only:false; response_revision:4171; number_of_response:1; }","duration":"121.380026ms","start":"2026-04-28T06:55:56.405435Z","end":"2026-04-28T06:55:56.526815Z","steps":["trace[513277128] 'process raft request' (duration: 90.196181ms)","trace[513277128] 'compare' (duration: 30.693044ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:55:56.527074Z","caller":"traceutil/trace.go:171","msg":"trace[1086574977] transaction","detail":"{read_only:false; response_revision:4173; number_of_response:1; }","duration":"120.546148ms","start":"2026-04-28T06:55:56.406516Z","end":"2026-04-28T06:55:56.527062Z","steps":["trace[1086574977] 'process raft request' (duration: 120.239811ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:55:56.52711Z","caller":"traceutil/trace.go:171","msg":"trace[1683445089] transaction","detail":"{read_only:false; response_revision:4176; number_of_response:1; }","duration":"113.401706ms","start":"2026-04-28T06:55:56.413671Z","end":"2026-04-28T06:55:56.527073Z","steps":["trace[1683445089] 'process raft request' (duration: 113.289124ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:55:56.52721Z","caller":"traceutil/trace.go:171","msg":"trace[1415907785] transaction","detail":"{read_only:false; response_revision:4172; number_of_response:1; }","duration":"120.66648ms","start":"2026-04-28T06:55:56.40652Z","end":"2026-04-28T06:55:56.527186Z","steps":["trace[1415907785] 'process raft request' (duration: 120.158588ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:55:56.527372Z","caller":"traceutil/trace.go:171","msg":"trace[62778569] transaction","detail":"{read_only:false; response_revision:4175; number_of_response:1; }","duration":"115.577245ms","start":"2026-04-28T06:55:56.411779Z","end":"2026-04-28T06:55:56.527356Z","steps":["trace[62778569] 'process raft request' (duration: 115.122554ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:55:56.527352Z","caller":"traceutil/trace.go:171","msg":"trace[36647317] transaction","detail":"{read_only:false; response_revision:4174; number_of_response:1; }","duration":"115.598536ms","start":"2026-04-28T06:55:56.411725Z","end":"2026-04-28T06:55:56.527323Z","steps":["trace[36647317] 'process raft request' (duration: 115.095274ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:55:57.974688Z","caller":"traceutil/trace.go:171","msg":"trace[974330421] transaction","detail":"{read_only:false; response_revision:4368; number_of_response:1; }","duration":"109.064948ms","start":"2026-04-28T06:55:57.86558Z","end":"2026-04-28T06:55:57.974645Z","steps":["trace[974330421] 'process raft request' (duration: 103.512222ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:56:01.165135Z","caller":"traceutil/trace.go:171","msg":"trace[932076945] transaction","detail":"{read_only:false; response_revision:4474; number_of_response:1; }","duration":"153.04752ms","start":"2026-04-28T06:56:01.012061Z","end":"2026-04-28T06:56:01.165108Z","steps":["trace[932076945] 'process raft request' (duration: 93.617967ms)","trace[932076945] 'compare' (duration: 59.27221ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:56:01.165557Z","caller":"traceutil/trace.go:171","msg":"trace[857138991] transaction","detail":"{read_only:false; response_revision:4476; number_of_response:1; }","duration":"121.034197ms","start":"2026-04-28T06:56:01.044509Z","end":"2026-04-28T06:56:01.165543Z","steps":["trace[857138991] 'process raft request' (duration: 120.820652ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:56:01.165642Z","caller":"traceutil/trace.go:171","msg":"trace[40561597] transaction","detail":"{read_only:false; response_revision:4480; number_of_response:1; }","duration":"108.210917ms","start":"2026-04-28T06:56:01.057359Z","end":"2026-04-28T06:56:01.16557Z","steps":["trace[40561597] 'process raft request' (duration: 108.176166ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:56:01.165731Z","caller":"traceutil/trace.go:171","msg":"trace[2057397458] linearizableReadLoop","detail":"{readStateIndex:4640; appliedIndex:4638; }","duration":"126.016329ms","start":"2026-04-28T06:56:01.039708Z","end":"2026-04-28T06:56:01.165724Z","steps":["trace[2057397458] 'read index received' (duration: 65.983292ms)","trace[2057397458] 'applied index is now lower than readState.Index' (duration: 60.029437ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:56:01.165902Z","caller":"traceutil/trace.go:171","msg":"trace[463488230] transaction","detail":"{read_only:false; response_revision:4477; number_of_response:1; }","duration":"121.225041ms","start":"2026-04-28T06:56:01.044667Z","end":"2026-04-28T06:56:01.165893Z","steps":["trace[463488230] 'process raft request' (duration: 120.74916ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:56:01.165966Z","caller":"traceutil/trace.go:171","msg":"trace[932806276] transaction","detail":"{read_only:false; response_revision:4478; number_of_response:1; }","duration":"121.073148ms","start":"2026-04-28T06:56:01.044851Z","end":"2026-04-28T06:56:01.165924Z","steps":["trace[932806276] 'process raft request' (duration: 120.598957ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:56:01.165928Z","caller":"traceutil/trace.go:171","msg":"trace[1968788315] transaction","detail":"{read_only:false; response_revision:4479; number_of_response:1; }","duration":"117.328083ms","start":"2026-04-28T06:56:01.04858Z","end":"2026-04-28T06:56:01.165908Z","steps":["trace[1968788315] 'process raft request' (duration: 116.911134ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:56:01.166148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.881694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:7 size:61057"} {"level":"info","ts":"2026-04-28T06:56:01.165699Z","caller":"traceutil/trace.go:171","msg":"trace[139181353] transaction","detail":"{read_only:false; response_revision:4475; number_of_response:1; }","duration":"129.12743ms","start":"2026-04-28T06:56:01.036562Z","end":"2026-04-28T06:56:01.16569Z","steps":["trace[139181353] 'process raft request' (duration: 128.511856ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:56:01.166201Z","caller":"traceutil/trace.go:171","msg":"trace[518502943] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:7; response_revision:4480; }","duration":"151.964616ms","start":"2026-04-28T06:56:01.014223Z","end":"2026-04-28T06:56:01.166188Z","steps":["trace[518502943] 'agreement among raft nodes before linearized reading' (duration: 151.772591ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:56:01.166498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.564733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-operator-cd88cf4bf-27grw\" ","response":"range_response_count:1 size:5042"} {"level":"info","ts":"2026-04-28T06:56:01.166542Z","caller":"traceutil/trace.go:171","msg":"trace[526432668] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-operator-cd88cf4bf-27grw; range_end:; response_count:1; response_revision:4480; }","duration":"142.645666ms","start":"2026-04-28T06:56:01.023886Z","end":"2026-04-28T06:56:01.166531Z","steps":["trace[526432668] 'agreement among raft nodes before linearized reading' (duration: 141.891759ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:56:01.166722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.458644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:2643"} {"level":"info","ts":"2026-04-28T06:56:01.166749Z","caller":"traceutil/trace.go:171","msg":"trace[180971523] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:4481; }","duration":"116.512834ms","start":"2026-04-28T06:56:01.05023Z","end":"2026-04-28T06:56:01.166742Z","steps":["trace[180971523] 'agreement among raft nodes before linearized reading' (duration: 116.400402ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:56:03.35378Z","caller":"traceutil/trace.go:171","msg":"trace[231892032] transaction","detail":"{read_only:false; response_revision:4528; number_of_response:1; }","duration":"190.059866ms","start":"2026-04-28T06:56:03.163692Z","end":"2026-04-28T06:56:03.353751Z","steps":["trace[231892032] 'process raft request' (duration: 189.882362ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:56:03.358611Z","caller":"traceutil/trace.go:171","msg":"trace[1155622589] linearizableReadLoop","detail":"{readStateIndex:4697; appliedIndex:4693; }","duration":"167.741332ms","start":"2026-04-28T06:56:03.190852Z","end":"2026-04-28T06:56:03.358594Z","steps":["trace[1155622589] 'read index received' (duration: 162.685498ms)","trace[1155622589] 'applied index is now lower than readState.Index' (duration: 5.053664ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:56:03.358823Z","caller":"traceutil/trace.go:171","msg":"trace[321177366] transaction","detail":"{read_only:false; response_revision:4529; number_of_response:1; }","duration":"183.080878ms","start":"2026-04-28T06:56:03.175733Z","end":"2026-04-28T06:56:03.358814Z","steps":["trace[321177366] 'process raft request' (duration: 182.614358ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:56:03.359259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.172255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1c71b91e-6bca-43b4-8b0b-ec17564af2bc\" ","response":"range_response_count:1 size:4066"} {"level":"info","ts":"2026-04-28T06:56:03.35929Z","caller":"traceutil/trace.go:171","msg":"trace[594958658] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-1c71b91e-6bca-43b4-8b0b-ec17564af2bc; range_end:; response_count:1; response_revision:4531; }","duration":"131.253357ms","start":"2026-04-28T06:56:03.228028Z","end":"2026-04-28T06:56:03.359282Z","steps":["trace[594958658] 'agreement among raft nodes before linearized reading' (duration: 131.130224ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:56:03.359298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.426617ms","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-04-28T06:56:03.359382Z","caller":"traceutil/trace.go:171","msg":"trace[1354489138] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4531; }","duration":"168.56284ms","start":"2026-04-28T06:56:03.190796Z","end":"2026-04-28T06:56:03.359359Z","steps":["trace[1354489138] 'agreement among raft nodes before linearized reading' (duration: 168.303105ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:56:03.359582Z","caller":"traceutil/trace.go:171","msg":"trace[2969753] transaction","detail":"{read_only:false; response_revision:4531; number_of_response:1; }","duration":"170.418212ms","start":"2026-04-28T06:56:03.189157Z","end":"2026-04-28T06:56:03.359576Z","steps":["trace[2969753] 'process raft request' (duration: 169.342298ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:56:03.359678Z","caller":"traceutil/trace.go:171","msg":"trace[351048814] transaction","detail":"{read_only:false; response_revision:4530; number_of_response:1; }","duration":"180.421598ms","start":"2026-04-28T06:56:03.179251Z","end":"2026-04-28T06:56:03.359672Z","steps":["trace[351048814] 'process raft request' (duration: 179.217091ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:56:03.646242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.654631ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-28T06:56:03.646451Z","caller":"traceutil/trace.go:171","msg":"trace[1071806564] transaction","detail":"{read_only:false; response_revision:4543; number_of_response:1; }","duration":"246.337338ms","start":"2026-04-28T06:56:03.400083Z","end":"2026-04-28T06:56:03.64642Z","steps":["trace[1071806564] 'process raft request' (duration: 139.458593ms)","trace[1071806564] 'compare' (duration: 106.574089ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:56:03.646673Z","caller":"traceutil/trace.go:171","msg":"trace[996583186] linearizableReadLoop","detail":"{readStateIndex:4709; appliedIndex:4708; }","duration":"218.937069ms","start":"2026-04-28T06:56:03.427698Z","end":"2026-04-28T06:56:03.646635Z","steps":["trace[996583186] 'read index received' (duration: 111.811958ms)","trace[996583186] 'applied index is now lower than readState.Index' (duration: 107.121231ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:56:03.687257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.545957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-1\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:56:03.687315Z","caller":"traceutil/trace.go:171","msg":"trace[7148979] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-1; range_end:; response_count:0; response_revision:4543; }","duration":"259.647409ms","start":"2026-04-28T06:56:03.427652Z","end":"2026-04-28T06:56:03.687299Z","steps":["trace[7148979] 'agreement among raft nodes before linearized reading' (duration: 219.140944ms)","trace[7148979] 'range keys from in-memory index tree' (duration: 40.425814ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:56:03.687614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.470388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/local-path-storage/helper-pod-create-pvc-9e2b6d06-a9e1-49ac-8434-6085bfbc032a\" ","response":"range_response_count:1 size:2245"} {"level":"info","ts":"2026-04-28T06:56:03.687638Z","caller":"traceutil/trace.go:171","msg":"trace[889945897] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/local-path-storage/helper-pod-create-pvc-9e2b6d06-a9e1-49ac-8434-6085bfbc032a; range_end:; response_count:1; response_revision:4544; }","duration":"245.50819ms","start":"2026-04-28T06:56:03.442123Z","end":"2026-04-28T06:56:03.687631Z","steps":["trace[889945897] 'agreement among raft nodes before linearized reading' (duration: 245.394227ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:56:03.687681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.124635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1433"} {"level":"info","ts":"2026-04-28T06:56:03.687752Z","caller":"traceutil/trace.go:171","msg":"trace[559627242] range","detail":"{range_begin:/registry/clusterrolebindings/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:4544; }","duration":"258.229787ms","start":"2026-04-28T06:56:03.429504Z","end":"2026-04-28T06:56:03.687733Z","steps":["trace[559627242] 'agreement among raft nodes before linearized reading' (duration: 258.022843ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:56:03.687897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.700649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:56:03.688001Z","caller":"traceutil/trace.go:171","msg":"trace[624161455] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:4544; }","duration":"244.072297ms","start":"2026-04-28T06:56:03.443909Z","end":"2026-04-28T06:56:03.687981Z","steps":["trace[624161455] 'agreement among raft nodes before linearized reading' (duration: 243.655247ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:56:04.280545Z","caller":"traceutil/trace.go:171","msg":"trace[356997430] linearizableReadLoop","detail":"{readStateIndex:4741; appliedIndex:4739; }","duration":"185.416881ms","start":"2026-04-28T06:56:04.095101Z","end":"2026-04-28T06:56:04.280518Z","steps":["trace[356997430] 'read index received' (duration: 33.126759ms)","trace[356997430] 'applied index is now lower than readState.Index' (duration: 152.288862ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:56:04.280645Z","caller":"traceutil/trace.go:171","msg":"trace[913013971] transaction","detail":"{read_only:false; response_revision:4573; number_of_response:1; }","duration":"209.887663ms","start":"2026-04-28T06:56:04.07073Z","end":"2026-04-28T06:56:04.280618Z","steps":["trace[913013971] 'process raft request' (duration: 208.049482ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:56:04.28085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.728018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0.18aa72ec11c33c78\" ","response":"range_response_count:1 size:1179"} {"level":"info","ts":"2026-04-28T06:56:04.280954Z","caller":"traceutil/trace.go:171","msg":"trace[1105802827] range","detail":"{range_begin:/registry/events/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0.18aa72ec11c33c78; range_end:; response_count:1; response_revision:4573; }","duration":"185.862331ms","start":"2026-04-28T06:56:04.095066Z","end":"2026-04-28T06:56:04.280928Z","steps":["trace[1105802827] 'agreement among raft nodes before linearized reading' (duration: 185.594115ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:56:04.280889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.012258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-ql8g2\" ","response":"range_response_count:1 size:9716"} {"level":"info","ts":"2026-04-28T06:56:04.281129Z","caller":"traceutil/trace.go:171","msg":"trace[124910314] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-ql8g2; range_end:; response_count:1; response_revision:4573; }","duration":"149.296864ms","start":"2026-04-28T06:56:04.131801Z","end":"2026-04-28T06:56:04.281098Z","steps":["trace[124910314] 'agreement among raft nodes before linearized reading' (duration: 148.886245ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:56:04.281595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.501274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:22 size:494852"} {"level":"info","ts":"2026-04-28T06:56:04.281662Z","caller":"traceutil/trace.go:171","msg":"trace[170112641] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:22; response_revision:4573; }","duration":"105.592836ms","start":"2026-04-28T06:56:04.176049Z","end":"2026-04-28T06:56:04.281642Z","steps":["trace[170112641] 'agreement among raft nodes before linearized reading' (duration: 105.053754ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:56:07.006422Z","caller":"traceutil/trace.go:171","msg":"trace[1072563345] transaction","detail":"{read_only:false; response_revision:4624; number_of_response:1; }","duration":"120.770939ms","start":"2026-04-28T06:56:06.885632Z","end":"2026-04-28T06:56:07.006403Z","steps":["trace[1072563345] 'process raft request' (duration: 120.582894ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:56:12.707354Z","caller":"traceutil/trace.go:171","msg":"trace[1117453240] transaction","detail":"{read_only:false; response_revision:4723; number_of_response:1; }","duration":"179.655426ms","start":"2026-04-28T06:56:12.527669Z","end":"2026-04-28T06:56:12.707324Z","steps":["trace[1117453240] 'process raft request' (duration: 179.282138ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:56:12.707591Z","caller":"traceutil/trace.go:171","msg":"trace[1075801718] linearizableReadLoop","detail":"{readStateIndex:4895; appliedIndex:4893; }","duration":"145.655078ms","start":"2026-04-28T06:56:12.561862Z","end":"2026-04-28T06:56:12.707517Z","steps":["trace[1075801718] 'read index received' (duration: 144.992853ms)","trace[1075801718] 'applied index is now lower than readState.Index' (duration: 659.095µs)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:56:12.707626Z","caller":"traceutil/trace.go:171","msg":"trace[372610440] transaction","detail":"{read_only:false; response_revision:4724; number_of_response:1; }","duration":"156.389621ms","start":"2026-04-28T06:56:12.55121Z","end":"2026-04-28T06:56:12.7076Z","steps":["trace[372610440] 'process raft request' (duration: 155.961922ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:56:12.707841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.522804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-keystone-server\" ","response":"range_response_count:1 size:9637"} {"level":"info","ts":"2026-04-28T06:56:12.707921Z","caller":"traceutil/trace.go:171","msg":"trace[1089462772] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-keystone-server; range_end:; response_count:1; response_revision:4724; }","duration":"156.673597ms","start":"2026-04-28T06:56:12.551236Z","end":"2026-04-28T06:56:12.70791Z","steps":["trace[1089462772] 'agreement among raft nodes before linearized reading' (duration: 156.472053ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:56:12.707969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.467156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/alertmanager-operated\" ","response":"range_response_count:1 size:1285"} {"level":"info","ts":"2026-04-28T06:56:12.708024Z","caller":"traceutil/trace.go:171","msg":"trace[1332251629] range","detail":"{range_begin:/registry/services/specs/monitoring/alertmanager-operated; range_end:; response_count:1; response_revision:4724; }","duration":"138.554138ms","start":"2026-04-28T06:56:12.569456Z","end":"2026-04-28T06:56:12.70801Z","steps":["trace[1332251629] 'agreement among raft nodes before linearized reading' (duration: 138.386565ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:56:12.97926Z","caller":"traceutil/trace.go:171","msg":"trace[992616115] linearizableReadLoop","detail":"{readStateIndex:4898; appliedIndex:4897; }","duration":"136.325798ms","start":"2026-04-28T06:56:12.842909Z","end":"2026-04-28T06:56:12.979235Z","steps":["trace[992616115] 'read index received' (duration: 121.868391ms)","trace[992616115] 'applied index is now lower than readState.Index' (duration: 14.456207ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:56:12.979461Z","caller":"traceutil/trace.go:171","msg":"trace[621297307] transaction","detail":"{read_only:false; response_revision:4727; number_of_response:1; }","duration":"137.613627ms","start":"2026-04-28T06:56:12.841812Z","end":"2026-04-28T06:56:12.979426Z","steps":["trace[621297307] 'process raft request' (duration: 123.027948ms)","trace[621297307] 'compare' (duration: 14.276522ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:56:12.979663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.700806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0\" ","response":"range_response_count:1 size:1342"} {"level":"info","ts":"2026-04-28T06:56:12.979712Z","caller":"traceutil/trace.go:171","msg":"trace[700028132] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0; range_end:; response_count:1; response_revision:4727; }","duration":"136.820999ms","start":"2026-04-28T06:56:12.842876Z","end":"2026-04-28T06:56:12.979697Z","steps":["trace[700028132] 'agreement among raft nodes before linearized reading' (duration: 136.554933ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:56:12.97975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.99055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-keystone-server\" ","response":"range_response_count:1 size:9637"} {"level":"info","ts":"2026-04-28T06:56:12.979823Z","caller":"traceutil/trace.go:171","msg":"trace[50996972] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-keystone-server; range_end:; response_count:1; response_revision:4727; }","duration":"128.091331ms","start":"2026-04-28T06:56:12.851711Z","end":"2026-04-28T06:56:12.979802Z","steps":["trace[50996972] 'agreement among raft nodes before linearized reading' (duration: 127.899477ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:56:13.149043Z","caller":"traceutil/trace.go:171","msg":"trace[451006165] transaction","detail":"{read_only:false; response_revision:4729; number_of_response:1; }","duration":"105.461161ms","start":"2026-04-28T06:56:13.043527Z","end":"2026-04-28T06:56:13.148988Z","steps":["trace[451006165] 'process raft request' (duration: 45.897826ms)","trace[451006165] 'compare' (duration: 59.33126ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:56:13.753757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.968143ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-28T06:56:13.753858Z","caller":"traceutil/trace.go:171","msg":"trace[803146414] linearizableReadLoop","detail":"{readStateIndex:4901; appliedIndex:4900; }","duration":"611.583066ms","start":"2026-04-28T06:56:13.142259Z","end":"2026-04-28T06:56:13.753842Z","steps":["trace[803146414] 'read index received' (duration: 194.554532ms)","trace[803146414] 'applied index is now lower than readState.Index' (duration: 417.027234ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:56:13.753927Z","caller":"traceutil/trace.go:171","msg":"trace[634568908] transaction","detail":"{read_only:false; response_revision:4730; number_of_response:1; }","duration":"634.103764ms","start":"2026-04-28T06:56:13.119808Z","end":"2026-04-28T06:56:13.753912Z","steps":["trace[634568908] 'process raft request' (duration: 216.913247ms)","trace[634568908] 'compare' (duration: 416.645985ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:56:13.753981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:56:13.119782Z","time spent":"634.176436ms","remote":"127.0.0.1:41748","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2227,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-28T06:56:13.754275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"611.981244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:16233"} {"level":"info","ts":"2026-04-28T06:56:13.754409Z","caller":"traceutil/trace.go:171","msg":"trace[1433620722] range","detail":"{range_begin:/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4730; }","duration":"612.154149ms","start":"2026-04-28T06:56:13.142227Z","end":"2026-04-28T06:56:13.754381Z","steps":["trace[1433620722] 'agreement among raft nodes before linearized reading' (duration: 611.715399ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:56:13.754463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:56:13.14221Z","time spent":"612.24413ms","remote":"127.0.0.1:49928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":73,"response count":1,"response size":16256,"request content":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" "} {"level":"info","ts":"2026-04-28T06:56:13.889245Z","caller":"traceutil/trace.go:171","msg":"trace[1537247736] transaction","detail":"{read_only:false; response_revision:4731; number_of_response:1; }","duration":"736.504225ms","start":"2026-04-28T06:56:13.152711Z","end":"2026-04-28T06:56:13.889216Z","steps":["trace[1537247736] 'process raft request' (duration: 701.790342ms)","trace[1537247736] 'compare' (duration: 34.410917ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:56:13.889368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:56:13.15268Z","time spent":"736.631779ms","remote":"127.0.0.1:49808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":880,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-28T06:56:13.907752Z","caller":"traceutil/trace.go:171","msg":"trace[1905285808] transaction","detail":"{read_only:false; response_revision:4733; number_of_response:1; }","duration":"511.22331ms","start":"2026-04-28T06:56:13.396515Z","end":"2026-04-28T06:56:13.907738Z","steps":["trace[1905285808] 'process raft request' (duration: 511.172209ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:56:13.907839Z","caller":"traceutil/trace.go:171","msg":"trace[188192415] transaction","detail":"{read_only:false; response_revision:4732; number_of_response:1; }","duration":"512.55381ms","start":"2026-04-28T06:56:13.395254Z","end":"2026-04-28T06:56:13.907808Z","steps":["trace[188192415] 'process raft request' (duration: 512.348436ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:56:13.908069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:56:13.395226Z","time spent":"512.747314ms","remote":"127.0.0.1:49980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:56:13.907844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:56:13.39649Z","time spent":"511.304862ms","remote":"127.0.0.1:49980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:56:13.923518Z","caller":"traceutil/trace.go:171","msg":"trace[972259158] linearizableReadLoop","detail":"{readStateIndex:4905; appliedIndex:4901; }","duration":"169.611309ms","start":"2026-04-28T06:56:13.753883Z","end":"2026-04-28T06:56:13.923494Z","steps":["trace[972259158] 'read index received' (duration: 100.55941ms)","trace[972259158] 'applied index is now lower than readState.Index' (duration: 69.050859ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:56:13.923772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.171673ms","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-04-28T06:56:13.923854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"500.858937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-28T06:56:13.923902Z","caller":"traceutil/trace.go:171","msg":"trace[846559192] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4734; }","duration":"497.294155ms","start":"2026-04-28T06:56:13.426559Z","end":"2026-04-28T06:56:13.923854Z","steps":["trace[846559192] 'agreement among raft nodes before linearized reading' (duration: 497.000779ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:56:13.923907Z","caller":"traceutil/trace.go:171","msg":"trace[1988977224] transaction","detail":"{read_only:false; response_revision:4734; number_of_response:1; }","duration":"154.300893ms","start":"2026-04-28T06:56:13.769583Z","end":"2026-04-28T06:56:13.923883Z","steps":["trace[1988977224] 'process raft request' (duration: 153.73443ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:56:13.923871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"696.406631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"warn","ts":"2026-04-28T06:56:13.923968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"495.772561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-28T06:56:13.924003Z","caller":"traceutil/trace.go:171","msg":"trace[2017078293] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4734; }","duration":"495.822082ms","start":"2026-04-28T06:56:13.42817Z","end":"2026-04-28T06:56:13.923992Z","steps":["trace[2017078293] 'agreement among raft nodes before linearized reading' (duration: 495.741521ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:56:13.924005Z","caller":"traceutil/trace.go:171","msg":"trace[328688610] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4734; }","duration":"696.594005ms","start":"2026-04-28T06:56:13.227389Z","end":"2026-04-28T06:56:13.923983Z","steps":["trace[328688610] 'agreement among raft nodes before linearized reading' (duration: 696.34535ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:56:13.923996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.763077ms","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-04-28T06:56:13.924014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:56:13.426549Z","time spent":"497.397657ms","remote":"127.0.0.1:49980","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":"info","ts":"2026-04-28T06:56:13.924132Z","caller":"traceutil/trace.go:171","msg":"trace[272584796] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4734; }","duration":"167.990552ms","start":"2026-04-28T06:56:13.756116Z","end":"2026-04-28T06:56:13.924107Z","steps":["trace[272584796] 'agreement among raft nodes before linearized reading' (duration: 167.612573ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:56:13.924068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:56:13.227364Z","time spent":"696.683967ms","remote":"127.0.0.1:49900","response type":"/etcdserverpb.KV/Range","request count":0,"request size":147,"response count":1,"response size":1958,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" "} {"level":"info","ts":"2026-04-28T06:56:13.923942Z","caller":"traceutil/trace.go:171","msg":"trace[997919466] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4734; }","duration":"500.988269ms","start":"2026-04-28T06:56:13.422933Z","end":"2026-04-28T06:56:13.923921Z","steps":["trace[997919466] 'agreement among raft nodes before linearized reading' (duration: 500.745653ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:56:13.92421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:56:13.422908Z","time spent":"501.292966ms","remote":"127.0.0.1:49980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-28T06:56:13.924051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:56:13.428161Z","time spent":"495.881834ms","remote":"127.0.0.1:49980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-28T06:56:13.924163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.158358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-89cef545-85d6-4eb2-a1b0-2216a88edc40\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-04-28T06:56:13.92436Z","caller":"traceutil/trace.go:171","msg":"trace[173759116] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-89cef545-85d6-4eb2-a1b0-2216a88edc40; range_end:; response_count:1; response_revision:4734; }","duration":"406.451125ms","start":"2026-04-28T06:56:13.51788Z","end":"2026-04-28T06:56:13.924331Z","steps":["trace[173759116] 'agreement among raft nodes before linearized reading' (duration: 405.862092ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:56:13.924464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:56:13.517865Z","time spent":"406.584638ms","remote":"127.0.0.1:49928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3669,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-89cef545-85d6-4eb2-a1b0-2216a88edc40\" "} {"level":"warn","ts":"2026-04-28T06:56:13.924958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"542.813773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/rsakeys/\" range_end:\"/registry/secretgen.k14s.io/rsakeys0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:56:13.925017Z","caller":"traceutil/trace.go:171","msg":"trace[1962118250] range","detail":"{range_begin:/registry/secretgen.k14s.io/rsakeys/; range_end:/registry/secretgen.k14s.io/rsakeys0; response_count:0; response_revision:4734; }","duration":"542.885824ms","start":"2026-04-28T06:56:13.382118Z","end":"2026-04-28T06:56:13.925003Z","steps":["trace[1962118250] 'agreement among raft nodes before linearized reading' (duration: 541.502093ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:56:13.925056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:56:13.38206Z","time spent":"542.984687ms","remote":"127.0.0.1:55218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":28,"request content":"key:\"/registry/secretgen.k14s.io/rsakeys/\" range_end:\"/registry/secretgen.k14s.io/rsakeys0\" count_only:true "} {"level":"warn","ts":"2026-04-28T06:56:13.925309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"647.141398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2194901"} {"level":"info","ts":"2026-04-28T06:56:13.925358Z","caller":"traceutil/trace.go:171","msg":"trace[1037656999] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4734; }","duration":"647.21794ms","start":"2026-04-28T06:56:13.278127Z","end":"2026-04-28T06:56:13.925345Z","steps":["trace[1037656999] 'agreement among raft nodes before linearized reading' (duration: 645.521152ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:56:13.925391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:56:13.27811Z","time spent":"647.271421ms","remote":"127.0.0.1:49846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":47,"response size":2194924,"request content":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" "} {"level":"info","ts":"2026-04-28T06:56:14.081787Z","caller":"traceutil/trace.go:171","msg":"trace[1238250558] transaction","detail":"{read_only:false; response_revision:4740; number_of_response:1; }","duration":"144.207535ms","start":"2026-04-28T06:56:13.937545Z","end":"2026-04-28T06:56:14.081752Z","steps":["trace[1238250558] 'process raft request' (duration: 90.742399ms)","trace[1238250558] 'compare' (duration: 52.896204ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:56:14.082131Z","caller":"traceutil/trace.go:171","msg":"trace[1738719284] transaction","detail":"{read_only:false; response_revision:4741; number_of_response:1; }","duration":"142.318152ms","start":"2026-04-28T06:56:13.939776Z","end":"2026-04-28T06:56:14.082094Z","steps":["trace[1738719284] 'process raft request' (duration: 142.070407ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:56:14.082211Z","caller":"traceutil/trace.go:171","msg":"trace[1598271701] transaction","detail":"{read_only:false; response_revision:4742; number_of_response:1; }","duration":"141.898353ms","start":"2026-04-28T06:56:13.940288Z","end":"2026-04-28T06:56:14.082186Z","steps":["trace[1598271701] 'process raft request' (duration: 141.641607ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:56:14.082981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.596941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:56:14.083059Z","caller":"traceutil/trace.go:171","msg":"trace[1049273742] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4743; }","duration":"100.696653ms","start":"2026-04-28T06:56:13.982347Z","end":"2026-04-28T06:56:14.083043Z","steps":["trace[1049273742] 'agreement among raft nodes before linearized reading' (duration: 100.56436ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:56:14.083158Z","caller":"traceutil/trace.go:171","msg":"trace[249721189] transaction","detail":"{read_only:false; response_revision:4743; number_of_response:1; }","duration":"136.512191ms","start":"2026-04-28T06:56:13.946602Z","end":"2026-04-28T06:56:14.083115Z","steps":["trace[249721189] 'process raft request' (duration: 135.375996ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:56:14.083331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.503124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-28T06:56:14.083365Z","caller":"traceutil/trace.go:171","msg":"trace[320649066] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4743; }","duration":"141.536255ms","start":"2026-04-28T06:56:13.941819Z","end":"2026-04-28T06:56:14.083355Z","steps":["trace[320649066] 'agreement among raft nodes before linearized reading' (duration: 141.422862ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:56:14.083467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.937256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-04-28T06:56:14.08355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.408378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1817"} {"level":"info","ts":"2026-04-28T06:56:14.083546Z","caller":"traceutil/trace.go:171","msg":"trace[1657020042] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4743; }","duration":"143.024768ms","start":"2026-04-28T06:56:13.940501Z","end":"2026-04-28T06:56:14.083526Z","steps":["trace[1657020042] 'agreement among raft nodes before linearized reading' (duration: 142.815604ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:56:14.083582Z","caller":"traceutil/trace.go:171","msg":"trace[1112521122] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:4743; }","duration":"124.47714ms","start":"2026-04-28T06:56:13.959096Z","end":"2026-04-28T06:56:14.083573Z","steps":["trace[1112521122] 'agreement among raft nodes before linearized reading' (duration: 124.388788ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:56:14.609631Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10836960132255502178,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-28T06:56:14.676288Z","caller":"traceutil/trace.go:171","msg":"trace[1660381328] transaction","detail":"{read_only:false; response_revision:4744; number_of_response:1; }","duration":"587.557681ms","start":"2026-04-28T06:56:14.088709Z","end":"2026-04-28T06:56:14.676267Z","steps":["trace[1660381328] 'process raft request' (duration: 541.157824ms)","trace[1660381328] 'compare' (duration: 45.721222ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:56:14.676414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:56:14.088688Z","time spent":"587.659934ms","remote":"127.0.0.1:49980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:56:14.70557Z","caller":"traceutil/trace.go:171","msg":"trace[584949299] transaction","detail":"{read_only:false; response_revision:4745; number_of_response:1; }","duration":"613.014727ms","start":"2026-04-28T06:56:14.092516Z","end":"2026-04-28T06:56:14.705531Z","steps":["trace[584949299] 'process raft request' (duration: 612.479405ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:56:14.705799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:56:14.092503Z","time spent":"613.142269ms","remote":"127.0.0.1:49902","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1283,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:56:14.70606Z","caller":"traceutil/trace.go:171","msg":"trace[1008926372] transaction","detail":"{read_only:false; response_revision:4747; number_of_response:1; }","duration":"613.025237ms","start":"2026-04-28T06:56:14.093001Z","end":"2026-04-28T06:56:14.706026Z","steps":["trace[1008926372] 'process raft request' (duration: 612.25567ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:56:14.706381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:56:14.092994Z","time spent":"613.17161ms","remote":"127.0.0.1:49992","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1455,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:56:14.706417Z","caller":"traceutil/trace.go:171","msg":"trace[1687324381] linearizableReadLoop","detail":"{readStateIndex:4922; appliedIndex:4915; }","duration":"596.80619ms","start":"2026-04-28T06:56:14.109587Z","end":"2026-04-28T06:56:14.706393Z","steps":["trace[1687324381] 'read index received' (duration: 520.291963ms)","trace[1687324381] 'applied index is now lower than readState.Index' (duration: 76.512757ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:56:14.706786Z","caller":"traceutil/trace.go:171","msg":"trace[2047623452] transaction","detail":"{read_only:false; response_revision:4748; number_of_response:1; }","duration":"613.631131ms","start":"2026-04-28T06:56:14.093141Z","end":"2026-04-28T06:56:14.706772Z","steps":["trace[2047623452] 'process raft request' (duration: 612.769181ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:56:14.706914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:56:14.093104Z","time spent":"613.722472ms","remote":"127.0.0.1:49902","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":906,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:56:14.7072Z","caller":"traceutil/trace.go:171","msg":"trace[724001980] transaction","detail":"{read_only:false; response_revision:4746; number_of_response:1; }","duration":"613.223891ms","start":"2026-04-28T06:56:14.092708Z","end":"2026-04-28T06:56:14.705932Z","steps":["trace[724001980] 'process raft request' (duration: 612.467904ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:56:14.70725Z","caller":"traceutil/trace.go:171","msg":"trace[966439773] transaction","detail":"{read_only:false; response_revision:4749; number_of_response:1; }","duration":"613.980928ms","start":"2026-04-28T06:56:14.093257Z","end":"2026-04-28T06:56:14.707238Z","steps":["trace[966439773] 'process raft request' (duration: 612.929935ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:56:14.707309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:56:14.092683Z","time spent":"614.580022ms","remote":"127.0.0.1:49980","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-04-28T06:56:14.70755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"550.26292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-grafana-c4c74b5bc-wxngd.18aa72eedab5555d\" ","response":"range_response_count:1 size:895"} {"level":"info","ts":"2026-04-28T06:56:14.707611Z","caller":"traceutil/trace.go:171","msg":"trace[690540959] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-grafana-c4c74b5bc-wxngd.18aa72eedab5555d; range_end:; response_count:1; response_revision:4750; }","duration":"550.363672ms","start":"2026-04-28T06:56:14.157228Z","end":"2026-04-28T06:56:14.707591Z","steps":["trace[690540959] 'agreement among raft nodes before linearized reading' (duration: 550.186878ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:56:14.70761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:56:14.093248Z","time spent":"614.027819ms","remote":"127.0.0.1:49992","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1869,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:56:14.707647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:56:14.157201Z","time spent":"550.438414ms","remote":"127.0.0.1:49808","response type":"/etcdserverpb.KV/Range","request count":0,"request size":92,"response count":1,"response size":918,"request content":"key:\"/registry/events/monitoring/kube-prometheus-stack-grafana-c4c74b5bc-wxngd.18aa72eedab5555d\" "} {"level":"info","ts":"2026-04-28T06:56:14.708018Z","caller":"traceutil/trace.go:171","msg":"trace[1444022910] transaction","detail":"{read_only:false; response_revision:4750; number_of_response:1; }","duration":"381.177884ms","start":"2026-04-28T06:56:14.326824Z","end":"2026-04-28T06:56:14.708002Z","steps":["trace[1444022910] 'process raft request' (duration: 379.456705ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:56:14.708114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:56:14.326787Z","time spent":"381.281236ms","remote":"127.0.0.1:49980","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-04-28T06:56:14.708271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"598.695474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3506"} {"level":"info","ts":"2026-04-28T06:56:14.708309Z","caller":"traceutil/trace.go:171","msg":"trace[1169371074] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:4750; }","duration":"598.775885ms","start":"2026-04-28T06:56:14.109521Z","end":"2026-04-28T06:56:14.708297Z","steps":["trace[1169371074] 'agreement among raft nodes before linearized reading' (duration: 598.670813ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:56:14.708336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:56:14.109498Z","time spent":"598.831116ms","remote":"127.0.0.1:49832","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":3529,"request content":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" "} {"level":"warn","ts":"2026-04-28T06:56:14.70893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"514.443842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-04-28T06:56:14.708991Z","caller":"traceutil/trace.go:171","msg":"trace[1509308999] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4750; }","duration":"514.934913ms","start":"2026-04-28T06:56:14.19404Z","end":"2026-04-28T06:56:14.708975Z","steps":["trace[1509308999] 'agreement among raft nodes before linearized reading' (duration: 514.37488ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:56:14.709031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:56:14.194015Z","time spent":"515.005635ms","remote":"127.0.0.1:49900","response type":"/etcdserverpb.KV/Range","request count":0,"request size":147,"response count":1,"response size":1958,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" "} {"level":"info","ts":"2026-04-28T06:56:14.876811Z","caller":"traceutil/trace.go:171","msg":"trace[140927066] linearizableReadLoop","detail":"{readStateIndex:4924; appliedIndex:4923; }","duration":"148.404ms","start":"2026-04-28T06:56:14.728374Z","end":"2026-04-28T06:56:14.876778Z","steps":["trace[140927066] 'read index received' (duration: 143.368426ms)","trace[140927066] 'applied index is now lower than readState.Index' (duration: 5.029734ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:56:14.876972Z","caller":"traceutil/trace.go:171","msg":"trace[690633095] transaction","detail":"{read_only:false; response_revision:4752; number_of_response:1; }","duration":"149.342571ms","start":"2026-04-28T06:56:14.727618Z","end":"2026-04-28T06:56:14.87696Z","steps":["trace[690633095] 'process raft request' (duration: 143.822347ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:56:14.877331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.935682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-1c71b91e-6bca-43b4-8b0b-ec17564af2bc\" ","response":"range_response_count:1 size:1435"} {"level":"info","ts":"2026-04-28T06:56:14.877369Z","caller":"traceutil/trace.go:171","msg":"trace[1814246859] range","detail":"{range_begin:/registry/persistentvolumes/pvc-1c71b91e-6bca-43b4-8b0b-ec17564af2bc; range_end:; response_count:1; response_revision:4753; }","duration":"149.011723ms","start":"2026-04-28T06:56:14.728348Z","end":"2026-04-28T06:56:14.877359Z","steps":["trace[1814246859] 'agreement among raft nodes before linearized reading' (duration: 148.817739ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:56:14.877376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.127748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0\" ","response":"range_response_count:1 size:1342"} {"level":"info","ts":"2026-04-28T06:56:14.877456Z","caller":"traceutil/trace.go:171","msg":"trace[968954324] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0; range_end:; response_count:1; response_revision:4753; }","duration":"146.238551ms","start":"2026-04-28T06:56:14.731196Z","end":"2026-04-28T06:56:14.877434Z","steps":["trace[968954324] 'agreement among raft nodes before linearized reading' (duration: 146.050696ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:56:14.878533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.221715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/schemareplications/\" range_end:\"/registry/rabbitmq.com/schemareplications0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:56:14.878575Z","caller":"traceutil/trace.go:171","msg":"trace[75109971] range","detail":"{range_begin:/registry/rabbitmq.com/schemareplications/; range_end:/registry/rabbitmq.com/schemareplications0; response_count:0; response_revision:4753; }","duration":"105.270246ms","start":"2026-04-28T06:56:14.773294Z","end":"2026-04-28T06:56:14.878564Z","steps":["trace[75109971] 'agreement among raft nodes before linearized reading' (duration: 105.196064ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:56:17.625523Z","caller":"traceutil/trace.go:171","msg":"trace[425365820] transaction","detail":"{read_only:false; response_revision:4780; number_of_response:1; }","duration":"261.391067ms","start":"2026-04-28T06:56:17.364112Z","end":"2026-04-28T06:56:17.625503Z","steps":["trace[425365820] 'process raft request' (duration: 261.244174ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:56:26.864166Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10836960132255502557,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-28T06:56:27.032265Z","caller":"traceutil/trace.go:171","msg":"trace[1438673929] transaction","detail":"{read_only:false; response_revision:4901; number_of_response:1; }","duration":"742.216167ms","start":"2026-04-28T06:56:26.290024Z","end":"2026-04-28T06:56:27.03224Z","steps":["trace[1438673929] 'process raft request' (duration: 742.075583ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:56:27.032441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:56:26.289988Z","time spent":"742.35385ms","remote":"127.0.0.1:49928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10670,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-28T06:56:27.275633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.829635ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T06:56:27.275969Z","caller":"traceutil/trace.go:171","msg":"trace[1665077330] linearizableReadLoop","detail":"{readStateIndex:5080; appliedIndex:5078; }","duration":"912.462434ms","start":"2026-04-28T06:56:26.363489Z","end":"2026-04-28T06:56:27.275951Z","steps":["trace[1665077330] 'read index received' (duration: 668.680111ms)","trace[1665077330] 'applied index is now lower than readState.Index' (duration: 243.780953ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:56:27.276121Z","caller":"traceutil/trace.go:171","msg":"trace[1822527315] transaction","detail":"{read_only:false; response_revision:4902; number_of_response:1; }","duration":"501.787628ms","start":"2026-04-28T06:56:26.774296Z","end":"2026-04-28T06:56:27.276083Z","steps":["trace[1822527315] 'process raft request' (duration: 320.444042ms)","trace[1822527315] 'compare' (duration: 180.247042ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:56:27.276329Z","caller":"traceutil/trace.go:171","msg":"trace[605292183] transaction","detail":"{read_only:false; response_revision:4903; number_of_response:1; }","duration":"236.102171ms","start":"2026-04-28T06:56:27.040216Z","end":"2026-04-28T06:56:27.276318Z","steps":["trace[605292183] 'process raft request' (duration: 235.501237ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:56:27.276419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:56:26.774263Z","time spent":"501.956362ms","remote":"127.0.0.1:49980","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-04-28T06:56:27.276448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"912.915614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-04-28T06:56:27.276558Z","caller":"traceutil/trace.go:171","msg":"trace[671299746] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4903; }","duration":"913.105969ms","start":"2026-04-28T06:56:26.36343Z","end":"2026-04-28T06:56:27.276536Z","steps":["trace[671299746] 'agreement among raft nodes before linearized reading' (duration: 912.683149ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:56:27.277042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.058799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3243"} {"level":"info","ts":"2026-04-28T06:56:27.277075Z","caller":"traceutil/trace.go:171","msg":"trace[2054691317] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4906; }","duration":"216.120271ms","start":"2026-04-28T06:56:27.060946Z","end":"2026-04-28T06:56:27.277066Z","steps":["trace[2054691317] 'agreement among raft nodes before linearized reading' (duration: 215.985077ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:56:27.277097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"504.98892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-28T06:56:27.277187Z","caller":"traceutil/trace.go:171","msg":"trace[620681136] transaction","detail":"{read_only:false; response_revision:4905; number_of_response:1; }","duration":"236.937739ms","start":"2026-04-28T06:56:27.040242Z","end":"2026-04-28T06:56:27.27718Z","steps":["trace[620681136] 'process raft request' (duration: 236.260634ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:56:27.277222Z","caller":"traceutil/trace.go:171","msg":"trace[1507071928] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4906; }","duration":"505.190204ms","start":"2026-04-28T06:56:26.772005Z","end":"2026-04-28T06:56:27.277195Z","steps":["trace[1507071928] 'agreement among raft nodes before linearized reading' (duration: 504.872067ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:56:27.277276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:56:26.771975Z","time spent":"505.291007ms","remote":"127.0.0.1:49980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-28T06:56:27.277273Z","caller":"traceutil/trace.go:171","msg":"trace[306110873] transaction","detail":"{read_only:false; response_revision:4906; number_of_response:1; }","duration":"236.240243ms","start":"2026-04-28T06:56:27.040999Z","end":"2026-04-28T06:56:27.277239Z","steps":["trace[306110873] 'process raft request' (duration: 235.727822ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:56:27.277403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.814096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:56:27.277435Z","caller":"traceutil/trace.go:171","msg":"trace[1820859366] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4906; }","duration":"295.868028ms","start":"2026-04-28T06:56:26.981558Z","end":"2026-04-28T06:56:27.277426Z","steps":["trace[1820859366] 'agreement among raft nodes before linearized reading' (duration: 295.820247ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:56:27.277573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.986667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:529"} {"level":"info","ts":"2026-04-28T06:56:27.277603Z","caller":"traceutil/trace.go:171","msg":"trace[1307925541] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:4906; }","duration":"287.063489ms","start":"2026-04-28T06:56:26.99053Z","end":"2026-04-28T06:56:27.277594Z","steps":["trace[1307925541] 'agreement among raft nodes before linearized reading' (duration: 286.997438ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:56:27.277659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"498.961185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-28T06:56:27.277705Z","caller":"traceutil/trace.go:171","msg":"trace[1302194220] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4906; }","duration":"499.025326ms","start":"2026-04-28T06:56:26.778667Z","end":"2026-04-28T06:56:27.277692Z","steps":["trace[1302194220] 'agreement among raft nodes before linearized reading' (duration: 498.924123ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:56:27.277756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:56:26.778659Z","time spent":"499.091027ms","remote":"127.0.0.1:49980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-28T06:56:27.277567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.202735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:56:27.277855Z","caller":"traceutil/trace.go:171","msg":"trace[2018221588] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:4906; }","duration":"367.558803ms","start":"2026-04-28T06:56:26.910289Z","end":"2026-04-28T06:56:27.277848Z","steps":["trace[2018221588] 'agreement among raft nodes before linearized reading' (duration: 367.233696ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:56:27.277893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:56:26.910258Z","time spent":"367.626965ms","remote":"127.0.0.1:50028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true "} {"level":"info","ts":"2026-04-28T06:56:27.278935Z","caller":"traceutil/trace.go:171","msg":"trace[801809026] transaction","detail":"{read_only:false; response_revision:4904; number_of_response:1; }","duration":"238.695289ms","start":"2026-04-28T06:56:27.040222Z","end":"2026-04-28T06:56:27.278917Z","steps":["trace[801809026] 'process raft request' (duration: 235.992128ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:56:27.278955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:56:26.363407Z","time spent":"913.218451ms","remote":"127.0.0.1:49900","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1888,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"info","ts":"2026-04-28T06:56:27.483851Z","caller":"traceutil/trace.go:171","msg":"trace[221212334] transaction","detail":"{read_only:false; response_revision:4907; number_of_response:1; }","duration":"198.017742ms","start":"2026-04-28T06:56:27.285794Z","end":"2026-04-28T06:56:27.483812Z","steps":["trace[221212334] 'process raft request' (duration: 98.568751ms)","trace[221212334] 'compare' (duration: 99.284438ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:56:27.532624Z","caller":"traceutil/trace.go:171","msg":"trace[1126623314] transaction","detail":"{read_only:false; response_revision:4908; number_of_response:1; }","duration":"246.71954ms","start":"2026-04-28T06:56:27.285871Z","end":"2026-04-28T06:56:27.53259Z","steps":["trace[1126623314] 'process raft request' (duration: 246.466784ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:56:27.532703Z","caller":"traceutil/trace.go:171","msg":"trace[614615709] linearizableReadLoop","detail":"{readStateIndex:5086; appliedIndex:5084; }","duration":"246.162698ms","start":"2026-04-28T06:56:27.286321Z","end":"2026-04-28T06:56:27.532483Z","steps":["trace[614615709] 'read index received' (duration: 98.010759ms)","trace[614615709] 'applied index is now lower than readState.Index' (duration: 148.150908ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:56:27.532635Z","caller":"traceutil/trace.go:171","msg":"trace[1159608664] transaction","detail":"{read_only:false; response_revision:4909; number_of_response:1; }","duration":"246.143257ms","start":"2026-04-28T06:56:27.286465Z","end":"2026-04-28T06:56:27.532609Z","steps":["trace[1159608664] 'process raft request' (duration: 245.967873ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:56:27.532973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.629818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-89cef545-85d6-4eb2-a1b0-2216a88edc40\" ","response":"range_response_count:1 size:1322"} {"level":"info","ts":"2026-04-28T06:56:27.533019Z","caller":"traceutil/trace.go:171","msg":"trace[1537369786] range","detail":"{range_begin:/registry/persistentvolumes/pvc-89cef545-85d6-4eb2-a1b0-2216a88edc40; range_end:; response_count:1; response_revision:4909; }","duration":"246.72612ms","start":"2026-04-28T06:56:27.286281Z","end":"2026-04-28T06:56:27.533007Z","steps":["trace[1537369786] 'agreement among raft nodes before linearized reading' (duration: 246.531066ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:56:27.533127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.704059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3243"} {"level":"info","ts":"2026-04-28T06:56:27.533178Z","caller":"traceutil/trace.go:171","msg":"trace[1775022387] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4909; }","duration":"108.813122ms","start":"2026-04-28T06:56:27.424352Z","end":"2026-04-28T06:56:27.533165Z","steps":["trace[1775022387] 'agreement among raft nodes before linearized reading' (duration: 108.668509ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:56:27.864389Z","caller":"traceutil/trace.go:171","msg":"trace[2010977981] transaction","detail":"{read_only:false; response_revision:4913; number_of_response:1; }","duration":"164.671091ms","start":"2026-04-28T06:56:27.699679Z","end":"2026-04-28T06:56:27.86435Z","steps":["trace[2010977981] 'process raft request' (duration: 120.656399ms)","trace[2010977981] 'compare' (duration: 43.867969ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:56:28.091211Z","caller":"traceutil/trace.go:171","msg":"trace[703375034] linearizableReadLoop","detail":"{readStateIndex:5093; appliedIndex:5091; }","duration":"109.049177ms","start":"2026-04-28T06:56:27.982136Z","end":"2026-04-28T06:56:28.091185Z","steps":["trace[703375034] 'read index received' (duration: 33.995717ms)","trace[703375034] 'applied index is now lower than readState.Index' (duration: 75.05258ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:56:28.091318Z","caller":"traceutil/trace.go:171","msg":"trace[935581276] transaction","detail":"{read_only:false; response_revision:4915; number_of_response:1; }","duration":"114.396748ms","start":"2026-04-28T06:56:27.976904Z","end":"2026-04-28T06:56:28.091301Z","steps":["trace[935581276] 'process raft request' (duration: 114.228784ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:56:28.091361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.213151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:56:28.091401Z","caller":"traceutil/trace.go:171","msg":"trace[2120015376] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4915; }","duration":"109.288123ms","start":"2026-04-28T06:56:27.982102Z","end":"2026-04-28T06:56:28.09139Z","steps":["trace[2120015376] 'agreement among raft nodes before linearized reading' (duration: 109.217651ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:56:28.091487Z","caller":"traceutil/trace.go:171","msg":"trace[452158316] transaction","detail":"{read_only:false; response_revision:4914; number_of_response:1; }","duration":"134.966121ms","start":"2026-04-28T06:56:27.956508Z","end":"2026-04-28T06:56:28.091475Z","steps":["trace[452158316] 'process raft request' (duration: 59.621153ms)","trace[452158316] 'compare' (duration: 74.842877ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:56:28.410449Z","caller":"traceutil/trace.go:171","msg":"trace[1594565288] linearizableReadLoop","detail":"{readStateIndex:5096; appliedIndex:5093; }","duration":"252.817046ms","start":"2026-04-28T06:56:28.157604Z","end":"2026-04-28T06:56:28.410418Z","steps":["trace[1594565288] 'read index received' (duration: 4.972022ms)","trace[1594565288] 'applied index is now lower than readState.Index' (duration: 247.840944ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:56:28.410619Z","caller":"traceutil/trace.go:171","msg":"trace[27430504] transaction","detail":"{read_only:false; response_revision:4917; number_of_response:1; }","duration":"313.691958ms","start":"2026-04-28T06:56:28.096843Z","end":"2026-04-28T06:56:28.410535Z","steps":["trace[27430504] 'process raft request' (duration: 313.33762ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:56:28.410781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.176464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-28T06:56:28.410785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.187775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:56:28.410823Z","caller":"traceutil/trace.go:171","msg":"trace[1251381348] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4918; }","duration":"253.264966ms","start":"2026-04-28T06:56:28.157548Z","end":"2026-04-28T06:56:28.410813Z","steps":["trace[1251381348] 'agreement among raft nodes before linearized reading' (duration: 253.044731ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:56:28.410832Z","caller":"traceutil/trace.go:171","msg":"trace[834694750] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:4918; }","duration":"249.274366ms","start":"2026-04-28T06:56:28.161547Z","end":"2026-04-28T06:56:28.410821Z","steps":["trace[834694750] 'agreement among raft nodes before linearized reading' (duration: 249.178264ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:56:28.410838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:56:28.096823Z","time spent":"313.916654ms","remote":"127.0.0.1:49980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:56:28.410651Z","caller":"traceutil/trace.go:171","msg":"trace[14852248] transaction","detail":"{read_only:false; response_revision:4918; number_of_response:1; }","duration":"313.696208ms","start":"2026-04-28T06:56:28.096918Z","end":"2026-04-28T06:56:28.410614Z","steps":["trace[14852248] 'process raft request' (duration: 313.445132ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:56:28.411131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:56:28.096909Z","time spent":"314.071137ms","remote":"127.0.0.1:49980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-28T06:56:28.532144Z","caller":"traceutil/trace.go:171","msg":"trace[202056115] linearizableReadLoop","detail":"{readStateIndex:5097; appliedIndex:5096; }","duration":"102.546761ms","start":"2026-04-28T06:56:28.429572Z","end":"2026-04-28T06:56:28.532118Z","steps":["trace[202056115] 'read index received' (duration: 52.950073ms)","trace[202056115] 'applied index is now lower than readState.Index' (duration: 49.595778ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:56:28.53224Z","caller":"traceutil/trace.go:171","msg":"trace[1059747476] transaction","detail":"{read_only:false; response_revision:4919; number_of_response:1; }","duration":"116.978577ms","start":"2026-04-28T06:56:28.415222Z","end":"2026-04-28T06:56:28.5322Z","steps":["trace[1059747476] 'process raft request' (duration: 67.380219ms)","trace[1059747476] 'compare' (duration: 49.408683ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:56:28.53252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.928789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.246\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-28T06:56:28.533408Z","caller":"traceutil/trace.go:171","msg":"trace[1343068478] range","detail":"{range_begin:/registry/masterleases/199.204.45.246; range_end:; response_count:1; response_revision:4919; }","duration":"103.86034ms","start":"2026-04-28T06:56:28.429533Z","end":"2026-04-28T06:56:28.533393Z","steps":["trace[1343068478] 'agreement among raft nodes before linearized reading' (duration: 102.787716ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:56:28.778746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.53548ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-28T06:56:28.779087Z","caller":"traceutil/trace.go:171","msg":"trace[1096163022] transaction","detail":"{read_only:false; response_revision:4925; number_of_response:1; }","duration":"121.362614ms","start":"2026-04-28T06:56:28.657706Z","end":"2026-04-28T06:56:28.779069Z","steps":["trace[1096163022] 'process raft request' (duration: 121.283612ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:56:28.779308Z","caller":"traceutil/trace.go:171","msg":"trace[2013378799] transaction","detail":"{read_only:false; response_revision:4924; number_of_response:1; }","duration":"169.53418ms","start":"2026-04-28T06:56:28.609765Z","end":"2026-04-28T06:56:28.779299Z","steps":["trace[2013378799] 'process raft request' (duration: 169.09681ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:56:28.779472Z","caller":"traceutil/trace.go:171","msg":"trace[1961267616] transaction","detail":"{read_only:false; response_revision:4923; number_of_response:1; }","duration":"172.20336ms","start":"2026-04-28T06:56:28.60724Z","end":"2026-04-28T06:56:28.779444Z","steps":["trace[1961267616] 'process raft request' (duration: 56.866382ms)","trace[1961267616] 'compare' (duration: 114.204313ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:56:29.744115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.46573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-28T06:56:29.744195Z","caller":"traceutil/trace.go:171","msg":"trace[1785841504] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:4927; }","duration":"316.565282ms","start":"2026-04-28T06:56:29.427611Z","end":"2026-04-28T06:56:29.744176Z","steps":["trace[1785841504] 'count revisions from in-memory index tree' (duration: 316.382238ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:56:29.744238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-28T06:56:29.427561Z","time spent":"316.666564ms","remote":"127.0.0.1:41626","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"warn","ts":"2026-04-28T06:56:29.744447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.471901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-28T06:56:29.744523Z","caller":"traceutil/trace.go:171","msg":"trace[401495522] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4927; }","duration":"206.597734ms","start":"2026-04-28T06:56:29.537909Z","end":"2026-04-28T06:56:29.744507Z","steps":["trace[401495522] 'range keys from in-memory index tree' (duration: 206.278937ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:56:29.744597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.179052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-28T06:56:29.744652Z","caller":"traceutil/trace.go:171","msg":"trace[1727721574] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4927; }","duration":"256.269044ms","start":"2026-04-28T06:56:29.488364Z","end":"2026-04-28T06:56:29.744633Z","steps":["trace[1727721574] 'range keys from in-memory index tree' (duration: 256.019098ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:56:36.237603Z","caller":"traceutil/trace.go:171","msg":"trace[207407732] transaction","detail":"{read_only:false; response_revision:4972; number_of_response:1; }","duration":"122.244032ms","start":"2026-04-28T06:56:36.11532Z","end":"2026-04-28T06:56:36.237564Z","steps":["trace[207407732] 'process raft request' (duration: 52.537503ms)","trace[207407732] 'compare' (duration: 69.452263ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:56:37.895277Z","caller":"traceutil/trace.go:171","msg":"trace[1271659759] transaction","detail":"{read_only:false; response_revision:4988; number_of_response:1; }","duration":"100.82455ms","start":"2026-04-28T06:56:37.794436Z","end":"2026-04-28T06:56:37.89526Z","steps":["trace[1271659759] 'process raft request' (duration: 100.712797ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T06:57:04.577133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.81889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-04-28T06:57:04.577228Z","caller":"traceutil/trace.go:171","msg":"trace[918209384] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5240; }","duration":"127.976474ms","start":"2026-04-28T06:57:04.449229Z","end":"2026-04-28T06:57:04.577206Z","steps":["trace[918209384] 'range keys from in-memory index tree' (duration: 127.494543ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:57:04.742399Z","caller":"traceutil/trace.go:171","msg":"trace[2059476890] transaction","detail":"{read_only:false; response_revision:5243; number_of_response:1; }","duration":"122.491939ms","start":"2026-04-28T06:57:04.619884Z","end":"2026-04-28T06:57:04.742376Z","steps":["trace[2059476890] 'process raft request' (duration: 120.595667ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:57:14.54123Z","caller":"traceutil/trace.go:171","msg":"trace[1885855006] linearizableReadLoop","detail":"{readStateIndex:5486; appliedIndex:5485; }","duration":"229.283293ms","start":"2026-04-28T06:57:14.311921Z","end":"2026-04-28T06:57:14.541205Z","steps":["trace[1885855006] 'read index received' (duration: 215.792151ms)","trace[1885855006] 'applied index is now lower than readState.Index' (duration: 13.489952ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:57:14.541268Z","caller":"traceutil/trace.go:171","msg":"trace[1893726119] transaction","detail":"{read_only:false; response_revision:5296; number_of_response:1; }","duration":"252.883683ms","start":"2026-04-28T06:57:14.288362Z","end":"2026-04-28T06:57:14.541245Z","steps":["trace[1893726119] 'process raft request' (duration: 239.320729ms)","trace[1893726119] 'compare' (duration: 13.37017ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:57:14.542352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.414728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2194901"} {"level":"info","ts":"2026-04-28T06:57:14.542406Z","caller":"traceutil/trace.go:171","msg":"trace[1185240769] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:5296; }","duration":"230.525121ms","start":"2026-04-28T06:57:14.311867Z","end":"2026-04-28T06:57:14.542393Z","steps":["trace[1185240769] 'agreement among raft nodes before linearized reading' (duration: 229.428996ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:57:14.56935Z","caller":"traceutil/trace.go:171","msg":"trace[1785272797] transaction","detail":"{read_only:false; response_revision:5297; number_of_response:1; }","duration":"165.477812ms","start":"2026-04-28T06:57:14.403832Z","end":"2026-04-28T06:57:14.56931Z","steps":["trace[1785272797] 'process raft request' (duration: 165.321329ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:57:14.809925Z","caller":"traceutil/trace.go:171","msg":"trace[686945103] transaction","detail":"{read_only:false; response_revision:5298; number_of_response:1; }","duration":"169.992842ms","start":"2026-04-28T06:57:14.639897Z","end":"2026-04-28T06:57:14.80989Z","steps":["trace[686945103] 'process raft request' (duration: 152.670444ms)","trace[686945103] 'compare' (duration: 17.175275ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:57:14.877932Z","caller":"traceutil/trace.go:171","msg":"trace[1823870738] transaction","detail":"{read_only:false; response_revision:5299; number_of_response:1; }","duration":"236.898913ms","start":"2026-04-28T06:57:14.640985Z","end":"2026-04-28T06:57:14.877884Z","steps":["trace[1823870738] 'process raft request' (duration: 236.650978ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:57:14.878111Z","caller":"traceutil/trace.go:171","msg":"trace[1915656649] transaction","detail":"{read_only:false; response_revision:5300; number_of_response:1; }","duration":"232.022904ms","start":"2026-04-28T06:57:14.646068Z","end":"2026-04-28T06:57:14.878091Z","steps":["trace[1915656649] 'process raft request' (duration: 231.736878ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:57:15.036109Z","caller":"traceutil/trace.go:171","msg":"trace[1130038007] transaction","detail":"{read_only:false; response_revision:5301; number_of_response:1; }","duration":"153.32316ms","start":"2026-04-28T06:57:14.882762Z","end":"2026-04-28T06:57:15.036086Z","steps":["trace[1130038007] 'process raft request' (duration: 53.676404ms)","trace[1130038007] 'compare' (duration: 99.38756ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:57:15.036516Z","caller":"traceutil/trace.go:171","msg":"trace[590010153] transaction","detail":"{read_only:false; response_revision:5302; number_of_response:1; }","duration":"152.630564ms","start":"2026-04-28T06:57:14.883851Z","end":"2026-04-28T06:57:15.036481Z","steps":["trace[590010153] 'process raft request' (duration: 152.124423ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:57:16.821268Z","caller":"traceutil/trace.go:171","msg":"trace[1177494339] transaction","detail":"{read_only:false; response_revision:5307; number_of_response:1; }","duration":"273.183957ms","start":"2026-04-28T06:57:16.548031Z","end":"2026-04-28T06:57:16.821215Z","steps":["trace[1177494339] 'process raft request' (duration: 272.952092ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:57:16.828515Z","caller":"traceutil/trace.go:171","msg":"trace[1131154194] transaction","detail":"{read_only:false; response_revision:5308; number_of_response:1; }","duration":"253.299601ms","start":"2026-04-28T06:57:16.57517Z","end":"2026-04-28T06:57:16.82847Z","steps":["trace[1131154194] 'process raft request' (duration: 253.005674ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:57:29.209488Z","caller":"traceutil/trace.go:171","msg":"trace[146756046] linearizableReadLoop","detail":"{readStateIndex:5593; appliedIndex:5592; }","duration":"105.215148ms","start":"2026-04-28T06:57:29.104246Z","end":"2026-04-28T06:57:29.209461Z","steps":["trace[146756046] 'read index received' (duration: 48.555028ms)","trace[146756046] 'applied index is now lower than readState.Index' (duration: 56.6589ms)"],"step_count":2} {"level":"info","ts":"2026-04-28T06:57:29.209862Z","caller":"traceutil/trace.go:171","msg":"trace[935733356] transaction","detail":"{read_only:false; response_revision:5400; number_of_response:1; }","duration":"146.602025ms","start":"2026-04-28T06:57:29.063203Z","end":"2026-04-28T06:57:29.209805Z","steps":["trace[935733356] 'process raft request' (duration: 89.682669ms)","trace[935733356] 'compare' (duration: 56.439895ms)"],"step_count":2} {"level":"warn","ts":"2026-04-28T06:57:29.209863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.592856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-28T06:57:29.210092Z","caller":"traceutil/trace.go:171","msg":"trace[484213431] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5400; }","duration":"105.864522ms","start":"2026-04-28T06:57:29.104199Z","end":"2026-04-28T06:57:29.210063Z","steps":["trace[484213431] 'agreement among raft nodes before linearized reading' (duration: 105.448203ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T06:57:55.317036Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2917} {"level":"info","ts":"2026-04-28T06:57:55.390722Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2917,"took":"72.300907ms","hash":409616243,"current-db-size-bytes":53690368,"current-db-size":"54 MB","current-db-size-in-use-bytes":29138944,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-04-28T06:57:55.390796Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":409616243,"revision":2917,"compact-revision":-1} {"level":"warn","ts":"2026-04-28T07:00:23.353753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.760404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/horizon-db-sync\" ","response":"range_response_count:1 size:4859"} {"level":"warn","ts":"2026-04-28T07:00:23.35386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.091957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-28T07:00:23.353875Z","caller":"traceutil/trace.go:171","msg":"trace[901721606] range","detail":"{range_begin:/registry/jobs/openstack/horizon-db-sync; range_end:; response_count:1; response_revision:6590; }","duration":"190.928599ms","start":"2026-04-28T07:00:23.162918Z","end":"2026-04-28T07:00:23.353847Z","steps":["trace[901721606] 'range keys from in-memory index tree' (duration: 190.55194ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:00:23.353943Z","caller":"traceutil/trace.go:171","msg":"trace[115659280] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:6590; }","duration":"211.25708ms","start":"2026-04-28T07:00:23.142665Z","end":"2026-04-28T07:00:23.353922Z","steps":["trace[115659280] 'range keys from in-memory index tree' (duration: 210.894463ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:00:23.461761Z","caller":"traceutil/trace.go:171","msg":"trace[1432486439] transaction","detail":"{read_only:false; response_revision:6591; number_of_response:1; }","duration":"103.549684ms","start":"2026-04-28T07:00:23.35819Z","end":"2026-04-28T07:00:23.46174Z","steps":["trace[1432486439] 'process raft request' (duration: 103.40691ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:02:44.979496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.626881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-04-28T07:02:44.97961Z","caller":"traceutil/trace.go:171","msg":"trace[2024874320] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:7392; }","duration":"263.784896ms","start":"2026-04-28T07:02:44.715798Z","end":"2026-04-28T07:02:44.979583Z","steps":["trace[2024874320] 'range keys from in-memory index tree' (duration: 263.448256ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:02:55.379903Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5590} {"level":"info","ts":"2026-04-28T07:02:55.610533Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5590,"took":"229.316896ms","hash":2379939110,"current-db-size-bytes":53690368,"current-db-size":"54 MB","current-db-size-in-use-bytes":18403328,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-04-28T07:02:55.610623Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2379939110,"revision":5590,"compact-revision":2917} {"level":"warn","ts":"2026-04-28T07:03:04.53351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.262556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-04-28T07:03:04.53351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.972272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:kube-dns\" ","response":"range_response_count:1 size:674"} {"level":"info","ts":"2026-04-28T07:03:04.533607Z","caller":"traceutil/trace.go:171","msg":"trace[1626341527] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:7492; }","duration":"129.40929ms","start":"2026-04-28T07:03:04.404178Z","end":"2026-04-28T07:03:04.533588Z","steps":["trace[1626341527] 'range keys from in-memory index tree' (duration: 129.03744ms)"],"step_count":1} {"level":"info","ts":"2026-04-28T07:03:04.533637Z","caller":"traceutil/trace.go:171","msg":"trace[325177624] range","detail":"{range_begin:/registry/clusterrolebindings/system:kube-dns; range_end:; response_count:1; response_revision:7492; }","duration":"140.117296ms","start":"2026-04-28T07:03:04.393495Z","end":"2026-04-28T07:03:04.533612Z","steps":["trace[325177624] 'range keys from in-memory index tree' (duration: 139.909151ms)"],"step_count":1} {"level":"warn","ts":"2026-04-28T07:03:04.533931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.90221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:kube-scheduler\" ","response":"range_response_count:1 size:709"} {"level":"info","ts":"2026-04-28T07:03:04.534004Z","caller":"traceutil/trace.go:171","msg":"trace[122777529] range","detail":"{range_begin:/registry/clusterrolebindings/system:kube-scheduler; range_end:; response_count:1; response_revision:7492; }","duration":"101.034424ms","start":"2026-04-28T07:03:04.432958Z","end":"2026-04-28T07:03:04.533992Z","steps":["trace[122777529] 'range keys from in-memory index tree' (duration: 100.816039ms)"],"step_count":1}