{"level":"warn","ts":"2026-04-20T11:55:55.615824Z","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-20T11:55:55.615911Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.153: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.19.213.153:2380","--initial-cluster=instance=https://199.19.213.153:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.153:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.153: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-20T11:55:55.615972Z","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-20T11:55:55.615988Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.153:2380"]} {"level":"info","ts":"2026-04-20T11:55:55.616005Z","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-20T11:55:55.616625Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.153:2379"]} {"level":"info","ts":"2026-04-20T11:55:55.616713Z","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":16,"max-cpu-available":16,"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.19.213.153:2380"],"listen-peer-urls":["https://199.19.213.153:2380"],"advertise-client-urls":["https://199.19.213.153:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.153:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.153: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-20T11:55:55.620606Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.68244ms"} {"level":"info","ts":"2026-04-20T11:55:55.628395Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"36b63984d68b7065","cluster-id":"7c251967b436417d"} {"level":"info","ts":"2026-04-20T11:55:55.628485Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"36b63984d68b7065 switched to configuration voters=()"} {"level":"info","ts":"2026-04-20T11:55:55.628504Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"36b63984d68b7065 became follower at term 0"} {"level":"info","ts":"2026-04-20T11:55:55.628513Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 36b63984d68b7065 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-20T11:55:55.628525Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"36b63984d68b7065 became follower at term 1"} {"level":"info","ts":"2026-04-20T11:55:55.628614Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"36b63984d68b7065 switched to configuration voters=(3942401766507376741)"} {"level":"warn","ts":"2026-04-20T11:55:55.638517Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-20T11:55:55.641776Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-20T11:55:55.644761Z","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-20T11:55:55.647817Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"36b63984d68b7065","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-20T11:55:55.647951Z","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-20T11:55:55.648081Z","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-20T11:55:55.648093Z","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-20T11:55:55.648087Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"36b63984d68b7065","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-20T11:55:55.649928Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"36b63984d68b7065 switched to configuration voters=(3942401766507376741)"} {"level":"info","ts":"2026-04-20T11:55:55.650708Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"7c251967b436417d","local-member-id":"36b63984d68b7065","added-peer-id":"36b63984d68b7065","added-peer-peer-urls":["https://199.19.213.153:2380"]} {"level":"info","ts":"2026-04-20T11:55:55.652773Z","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-20T11:55:55.652933Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.153:2380"} {"level":"info","ts":"2026-04-20T11:55:55.653009Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.153:2380"} {"level":"info","ts":"2026-04-20T11:55:55.653327Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"36b63984d68b7065","initial-advertise-peer-urls":["https://199.19.213.153:2380"],"listen-peer-urls":["https://199.19.213.153:2380"],"advertise-client-urls":["https://199.19.213.153:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.153:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-20T11:55:55.653387Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-20T11:55:56.629683Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"36b63984d68b7065 is starting a new election at term 1"} {"level":"info","ts":"2026-04-20T11:55:56.62973Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"36b63984d68b7065 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-20T11:55:56.629763Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"36b63984d68b7065 received MsgPreVoteResp from 36b63984d68b7065 at term 1"} {"level":"info","ts":"2026-04-20T11:55:56.629782Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"36b63984d68b7065 became candidate at term 2"} {"level":"info","ts":"2026-04-20T11:55:56.629789Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"36b63984d68b7065 received MsgVoteResp from 36b63984d68b7065 at term 2"} {"level":"info","ts":"2026-04-20T11:55:56.629801Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"36b63984d68b7065 became leader at term 2"} {"level":"info","ts":"2026-04-20T11:55:56.629811Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 36b63984d68b7065 elected leader 36b63984d68b7065 at term 2"} {"level":"info","ts":"2026-04-20T11:55:56.630755Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T11:55:56.631484Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"36b63984d68b7065","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.153:2379]}","request-path":"/0/members/36b63984d68b7065/attributes","cluster-id":"7c251967b436417d","publish-timeout":"7s"} {"level":"info","ts":"2026-04-20T11:55:56.63149Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-20T11:55:56.6316Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-20T11:55:56.631749Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-20T11:55:56.631895Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-20T11:55:56.632069Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"7c251967b436417d","local-member-id":"36b63984d68b7065","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T11:55:56.632201Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T11:55:56.632237Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T11:55:56.634675Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-20T11:55:56.636488Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.153:2379"} {"level":"info","ts":"2026-04-20T11:55:59.579749Z","caller":"traceutil/trace.go:171","msg":"trace[23233661] transaction","detail":"{read_only:false; response_revision:189; number_of_response:1; }","duration":"103.989936ms","start":"2026-04-20T11:55:59.475728Z","end":"2026-04-20T11:55:59.579718Z","steps":["trace[23233661] 'process raft request' (duration: 19.213025ms)","trace[23233661] 'compare' (duration: 84.634838ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T11:55:59.893349Z","caller":"traceutil/trace.go:171","msg":"trace[1899469827] linearizableReadLoop","detail":"{readStateIndex:199; appliedIndex:198; }","duration":"220.00314ms","start":"2026-04-20T11:55:59.673306Z","end":"2026-04-20T11:55:59.893309Z","steps":["trace[1899469827] 'read index received' (duration: 219.838477ms)","trace[1899469827] 'applied index is now lower than readState.Index' (duration: 163.353µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T11:55:59.893418Z","caller":"traceutil/trace.go:171","msg":"trace[1807084280] transaction","detail":"{read_only:false; response_revision:194; number_of_response:1; }","duration":"239.861538ms","start":"2026-04-20T11:55:59.653508Z","end":"2026-04-20T11:55:59.893369Z","steps":["trace[1807084280] 'process raft request' (duration: 239.588263ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:55:59.893574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.243754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:disruption-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T11:55:59.893754Z","caller":"traceutil/trace.go:171","msg":"trace[1808425683] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:disruption-controller; range_end:; response_count:0; response_revision:194; }","duration":"220.434017ms","start":"2026-04-20T11:55:59.673289Z","end":"2026-04-20T11:55:59.893723Z","steps":["trace[1808425683] 'agreement among raft nodes before linearized reading' (duration: 220.157032ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:56:00.092582Z","caller":"traceutil/trace.go:171","msg":"trace[745460855] transaction","detail":"{read_only:false; response_revision:198; number_of_response:1; }","duration":"122.064039ms","start":"2026-04-20T11:55:59.970482Z","end":"2026-04-20T11:56:00.092546Z","steps":["trace[745460855] 'process raft request' (duration: 121.854495ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:56:00.467391Z","caller":"traceutil/trace.go:171","msg":"trace[1794478335] transaction","detail":"{read_only:false; response_revision:208; number_of_response:1; }","duration":"147.723587ms","start":"2026-04-20T11:56:00.31963Z","end":"2026-04-20T11:56:00.467353Z","steps":["trace[1794478335] 'process raft request' (duration: 84.367943ms)","trace[1794478335] 'compare' (duration: 63.202011ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T11:56:02.71938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.769982ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-20T11:56:02.719713Z","caller":"traceutil/trace.go:171","msg":"trace[1305732260] transaction","detail":"{read_only:false; number_of_response:0; response_revision:295; }","duration":"115.504719ms","start":"2026-04-20T11:56:02.604178Z","end":"2026-04-20T11:56:02.719682Z","steps":["trace[1305732260] 'process raft request' (duration: 115.451598ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:56:02.719702Z","caller":"traceutil/trace.go:171","msg":"trace[1259674311] transaction","detail":"{read_only:false; response_revision:294; number_of_response:1; }","duration":"237.937319ms","start":"2026-04-20T11:56:02.481687Z","end":"2026-04-20T11:56:02.719624Z","steps":["trace[1259674311] 'process raft request' (duration: 119.275631ms)","trace[1259674311] 'compare' (duration: 117.552178ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T11:56:02.719725Z","caller":"traceutil/trace.go:171","msg":"trace[714901678] transaction","detail":"{read_only:false; response_revision:295; number_of_response:1; }","duration":"221.528898ms","start":"2026-04-20T11:56:02.498138Z","end":"2026-04-20T11:56:02.719667Z","steps":["trace[714901678] 'process raft request' (duration: 221.380945ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:56:02.719874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.508478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-04-20T11:56:02.719721Z","caller":"traceutil/trace.go:171","msg":"trace[2013603694] linearizableReadLoop","detail":"{readStateIndex:301; appliedIndex:298; }","duration":"221.348614ms","start":"2026-04-20T11:56:02.498347Z","end":"2026-04-20T11:56:02.719695Z","steps":["trace[2013603694] 'read index received' (duration: 3.364804ms)","trace[2013603694] 'applied index is now lower than readState.Index' (duration: 217.98081ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T11:56:02.719926Z","caller":"traceutil/trace.go:171","msg":"trace[1446027571] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:295; }","duration":"221.658681ms","start":"2026-04-20T11:56:02.498256Z","end":"2026-04-20T11:56:02.719915Z","steps":["trace[1446027571] 'agreement among raft nodes before linearized reading' (duration: 221.520318ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:56:02.745308Z","caller":"traceutil/trace.go:171","msg":"trace[1319860313] transaction","detail":"{read_only:false; response_revision:296; number_of_response:1; }","duration":"130.483533ms","start":"2026-04-20T11:56:02.614795Z","end":"2026-04-20T11:56:02.745278Z","steps":["trace[1319860313] 'process raft request' (duration: 130.30921ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:56:02.745361Z","caller":"traceutil/trace.go:171","msg":"trace[142533539] transaction","detail":"{read_only:false; number_of_response:0; response_revision:296; }","duration":"130.467342ms","start":"2026-04-20T11:56:02.614877Z","end":"2026-04-20T11:56:02.745345Z","steps":["trace[142533539] 'process raft request' (duration: 130.320699ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:56:02.745385Z","caller":"traceutil/trace.go:171","msg":"trace[1450706792] transaction","detail":"{read_only:false; response_revision:297; number_of_response:1; }","duration":"129.147388ms","start":"2026-04-20T11:56:02.616215Z","end":"2026-04-20T11:56:02.745362Z","steps":["trace[1450706792] 'process raft request' (duration: 129.060116ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:56:02.745496Z","caller":"traceutil/trace.go:171","msg":"trace[702117915] transaction","detail":"{read_only:false; number_of_response:0; response_revision:296; }","duration":"130.540133ms","start":"2026-04-20T11:56:02.614946Z","end":"2026-04-20T11:56:02.745486Z","steps":["trace[702117915] 'process raft request' (duration: 130.281919ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:56:02.745505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.250693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T11:56:02.745542Z","caller":"traceutil/trace.go:171","msg":"trace[1964517131] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:0; response_revision:297; }","duration":"240.323664ms","start":"2026-04-20T11:56:02.505207Z","end":"2026-04-20T11:56:02.74553Z","steps":["trace[1964517131] 'agreement among raft nodes before linearized reading' (duration: 240.255333ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:56:02.745559Z","caller":"traceutil/trace.go:171","msg":"trace[528833284] transaction","detail":"{read_only:false; number_of_response:0; response_revision:296; }","duration":"130.413781ms","start":"2026-04-20T11:56:02.615097Z","end":"2026-04-20T11:56:02.74551Z","steps":["trace[528833284] 'process raft request' (duration: 130.156786ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:56:02.745866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.816434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/resourcequota-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T11:56:02.746638Z","caller":"traceutil/trace.go:171","msg":"trace[1039969733] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/resourcequota-controller; range_end:; response_count:0; response_revision:297; }","duration":"240.603969ms","start":"2026-04-20T11:56:02.506012Z","end":"2026-04-20T11:56:02.746616Z","steps":["trace[1039969733] 'agreement among raft nodes before linearized reading' (duration: 239.407826ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:56:11.150854Z","caller":"traceutil/trace.go:171","msg":"trace[1752366567] linearizableReadLoop","detail":"{readStateIndex:420; appliedIndex:419; }","duration":"356.678411ms","start":"2026-04-20T11:56:10.794144Z","end":"2026-04-20T11:56:11.150823Z","steps":["trace[1752366567] 'read index received' (duration: 356.399756ms)","trace[1752366567] 'applied index is now lower than readState.Index' (duration: 276.675µs)"],"step_count":2} {"level":"warn","ts":"2026-04-20T11:56:11.150875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:56:10.721198Z","time spent":"429.669855ms","remote":"127.0.0.1:54222","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-04-20T11:56:11.151028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.863345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T11:56:11.151112Z","caller":"traceutil/trace.go:171","msg":"trace[1160839961] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:404; }","duration":"356.992247ms","start":"2026-04-20T11:56:10.7941Z","end":"2026-04-20T11:56:11.151092Z","steps":["trace[1160839961] 'agreement among raft nodes before linearized reading' (duration: 356.850494ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:56:11.151116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.891429ms","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-20T11:56:11.151159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:56:10.794079Z","time spent":"357.073349ms","remote":"127.0.0.1:54162","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-20T11:56:11.151187Z","caller":"traceutil/trace.go:171","msg":"trace[487161242] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:404; }","duration":"217.006542ms","start":"2026-04-20T11:56:10.934164Z","end":"2026-04-20T11:56:11.151171Z","steps":["trace[487161242] 'agreement among raft nodes before linearized reading' (duration: 216.868029ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:56:11.55203Z","caller":"traceutil/trace.go:171","msg":"trace[1715921158] transaction","detail":"{read_only:false; response_revision:405; number_of_response:1; }","duration":"399.891954ms","start":"2026-04-20T11:56:11.152106Z","end":"2026-04-20T11:56:11.551998Z","steps":["trace[1715921158] 'process raft request' (duration: 368.290725ms)","trace[1715921158] 'compare' (duration: 31.453945ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T11:56:11.552382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:56:11.152082Z","time spent":"400.022927ms","remote":"127.0.0.1:54222","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T11:56:11.557376Z","caller":"traceutil/trace.go:171","msg":"trace[2057885396] transaction","detail":"{read_only:false; response_revision:406; number_of_response:1; }","duration":"400.818172ms","start":"2026-04-20T11:56:11.156539Z","end":"2026-04-20T11:56:11.557357Z","steps":["trace[2057885396] 'process raft request' (duration: 400.699099ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:56:11.557561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:56:11.156514Z","time spent":"400.917393ms","remote":"127.0.0.1:54446","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T11:56:16.299366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.685042ms","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-20T11:56:16.299479Z","caller":"traceutil/trace.go:171","msg":"trace[1022947369] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:423; }","duration":"201.863785ms","start":"2026-04-20T11:56:16.097592Z","end":"2026-04-20T11:56:16.299456Z","steps":["trace[1022947369] 'range keys from in-memory index tree' (duration: 201.473747ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:56:16.554947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.570224ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-04-20T11:56:16.555229Z","caller":"traceutil/trace.go:171","msg":"trace[547586286] linearizableReadLoop","detail":"{readStateIndex:440; appliedIndex:439; }","duration":"256.237813ms","start":"2026-04-20T11:56:16.298959Z","end":"2026-04-20T11:56:16.555197Z","steps":["trace[547586286] 'read index received' (duration: 87.589918ms)","trace[547586286] 'applied index is now lower than readState.Index' (duration: 168.644495ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T11:56:16.555289Z","caller":"traceutil/trace.go:171","msg":"trace[1439378757] transaction","detail":"{read_only:false; response_revision:424; number_of_response:1; }","duration":"251.570859ms","start":"2026-04-20T11:56:16.303691Z","end":"2026-04-20T11:56:16.555262Z","steps":["trace[1439378757] 'process raft request' (duration: 251.383495ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:56:16.555392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.420507ms","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-20T11:56:16.555425Z","caller":"traceutil/trace.go:171","msg":"trace[914180754] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:424; }","duration":"256.506909ms","start":"2026-04-20T11:56:16.298908Z","end":"2026-04-20T11:56:16.555415Z","steps":["trace[914180754] 'agreement among raft nodes before linearized reading' (duration: 256.373766ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:56:17.075503Z","caller":"traceutil/trace.go:171","msg":"trace[2110666332] linearizableReadLoop","detail":"{readStateIndex:442; appliedIndex:441; }","duration":"282.982894ms","start":"2026-04-20T11:56:16.792487Z","end":"2026-04-20T11:56:17.07547Z","steps":["trace[2110666332] 'read index received' (duration: 253.004799ms)","trace[2110666332] 'applied index is now lower than readState.Index' (duration: 29.976975ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T11:56:17.075532Z","caller":"traceutil/trace.go:171","msg":"trace[907220806] transaction","detail":"{read_only:false; response_revision:425; number_of_response:1; }","duration":"516.347304ms","start":"2026-04-20T11:56:16.559158Z","end":"2026-04-20T11:56:17.075505Z","steps":["trace[907220806] 'process raft request' (duration: 486.340508ms)","trace[907220806] 'compare' (duration: 29.76258ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T11:56:17.075781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.29329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T11:56:17.075873Z","caller":"traceutil/trace.go:171","msg":"trace[405304197] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:425; }","duration":"283.410582ms","start":"2026-04-20T11:56:16.792448Z","end":"2026-04-20T11:56:17.075858Z","steps":["trace[405304197] 'agreement among raft nodes before linearized reading' (duration: 283.28917ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:56:17.076351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:56:16.559142Z","time spent":"516.460297ms","remote":"127.0.0.1:54446","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T11:56:22.476223Z","caller":"traceutil/trace.go:171","msg":"trace[266357002] transaction","detail":"{read_only:false; response_revision:466; number_of_response:1; }","duration":"410.337274ms","start":"2026-04-20T11:56:22.065852Z","end":"2026-04-20T11:56:22.476189Z","steps":["trace[266357002] 'process raft request' (duration: 410.165421ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:56:22.476376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:56:22.065834Z","time spent":"410.469347ms","remote":"127.0.0.1:54446","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T11:56:22.935971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.086717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-20T11:56:22.936001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.519977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T11:56:22.936064Z","caller":"traceutil/trace.go:171","msg":"trace[108904211] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:466; }","duration":"348.21693ms","start":"2026-04-20T11:56:22.587826Z","end":"2026-04-20T11:56:22.936043Z","steps":["trace[108904211] 'range keys from in-memory index tree' (duration: 347.974474ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:56:22.936085Z","caller":"traceutil/trace.go:171","msg":"trace[37081146] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:466; }","duration":"141.650649ms","start":"2026-04-20T11:56:22.794414Z","end":"2026-04-20T11:56:22.936065Z","steps":["trace[37081146] 'range keys from in-memory index tree' (duration: 141.377264ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:56:22.936107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:56:22.587807Z","time spent":"348.291611ms","remote":"127.0.0.1:54446","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":"info","ts":"2026-04-20T11:56:31.188367Z","caller":"traceutil/trace.go:171","msg":"trace[553050362] linearizableReadLoop","detail":"{readStateIndex:506; appliedIndex:505; }","duration":"143.215021ms","start":"2026-04-20T11:56:31.045129Z","end":"2026-04-20T11:56:31.188344Z","steps":["trace[553050362] 'read index received' (duration: 143.111829ms)","trace[553050362] 'applied index is now lower than readState.Index' (duration: 102.122µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T11:56:31.188418Z","caller":"traceutil/trace.go:171","msg":"trace[1951518261] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"168.985841ms","start":"2026-04-20T11:56:31.0194Z","end":"2026-04-20T11:56:31.188386Z","steps":["trace[1951518261] 'process raft request' (duration: 168.805188ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:56:31.18849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.346314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T11:56:31.188534Z","caller":"traceutil/trace.go:171","msg":"trace[1501480967] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium; range_end:; response_count:0; response_revision:486; }","duration":"143.427335ms","start":"2026-04-20T11:56:31.045089Z","end":"2026-04-20T11:56:31.188517Z","steps":["trace[1501480967] 'agreement among raft nodes before linearized reading' (duration: 143.352384ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:56:31.950464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"637.638403ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T11:56:31.950566Z","caller":"traceutil/trace.go:171","msg":"trace[434348359] linearizableReadLoop","detail":"{readStateIndex:507; appliedIndex:506; }","duration":"752.336499ms","start":"2026-04-20T11:56:31.198213Z","end":"2026-04-20T11:56:31.95055Z","steps":["trace[434348359] 'read index received' (duration: 114.513982ms)","trace[434348359] 'applied index is now lower than readState.Index' (duration: 637.821407ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T11:56:31.950666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.53464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T11:56:31.95066Z","caller":"traceutil/trace.go:171","msg":"trace[19193874] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"753.939381ms","start":"2026-04-20T11:56:31.196674Z","end":"2026-04-20T11:56:31.950614Z","steps":["trace[19193874] 'process raft request' (duration: 116.069273ms)","trace[19193874] 'compare' (duration: 637.49934ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T11:56:31.950703Z","caller":"traceutil/trace.go:171","msg":"trace[1461430629] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:487; }","duration":"156.595662ms","start":"2026-04-20T11:56:31.794096Z","end":"2026-04-20T11:56:31.950692Z","steps":["trace[1461430629] 'agreement among raft nodes before linearized reading' (duration: 156.542131ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:56:31.950695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.670847ms","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-20T11:56:31.950758Z","caller":"traceutil/trace.go:171","msg":"trace[107669448] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:487; }","duration":"123.76391ms","start":"2026-04-20T11:56:31.826978Z","end":"2026-04-20T11:56:31.950741Z","steps":["trace[107669448] 'agreement among raft nodes before linearized reading' (duration: 123.672508ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:56:31.95074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"752.489912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/cilium-operator\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T11:56:31.950842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:56:31.196657Z","time spent":"754.091284ms","remote":"127.0.0.1:54446","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T11:56:31.950897Z","caller":"traceutil/trace.go:171","msg":"trace[412307376] range","detail":"{range_begin:/registry/clusterroles/cilium-operator; range_end:; response_count:0; response_revision:487; }","duration":"752.644765ms","start":"2026-04-20T11:56:31.198173Z","end":"2026-04-20T11:56:31.950818Z","steps":["trace[412307376] 'agreement among raft nodes before linearized reading' (duration: 752.464452ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:56:31.950961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:56:31.198153Z","time spent":"752.793238ms","remote":"127.0.0.1:54528","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":0,"response size":27,"request content":"key:\"/registry/clusterroles/cilium-operator\" "} {"level":"warn","ts":"2026-04-20T11:56:34.455256Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8099052861574836357,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-20T11:56:34.648021Z","caller":"traceutil/trace.go:171","msg":"trace[525506759] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"694.515657ms","start":"2026-04-20T11:56:33.953475Z","end":"2026-04-20T11:56:34.64799Z","steps":["trace[525506759] 'process raft request' (duration: 694.367514ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:56:34.648468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:56:33.953451Z","time spent":"694.790422ms","remote":"127.0.0.1:54238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T11:56:34.661836Z","caller":"traceutil/trace.go:171","msg":"trace[1396007940] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"707.827629ms","start":"2026-04-20T11:56:33.953978Z","end":"2026-04-20T11:56:34.661805Z","steps":["trace[1396007940] 'process raft request' (duration: 707.572105ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:56:34.66187Z","caller":"traceutil/trace.go:171","msg":"trace[1492163958] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"705.617597ms","start":"2026-04-20T11:56:33.956214Z","end":"2026-04-20T11:56:34.661831Z","steps":["trace[1492163958] 'process raft request' (duration: 705.505935ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:56:34.661931Z","caller":"traceutil/trace.go:171","msg":"trace[1833707762] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"695.950974ms","start":"2026-04-20T11:56:33.96593Z","end":"2026-04-20T11:56:34.661881Z","steps":["trace[1833707762] 'process raft request' (duration: 695.828221ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:56:34.661969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:56:33.953965Z","time spent":"707.945721ms","remote":"127.0.0.1:54238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T11:56:34.661959Z","caller":"traceutil/trace.go:171","msg":"trace[1613114727] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"695.574507ms","start":"2026-04-20T11:56:33.966356Z","end":"2026-04-20T11:56:34.66193Z","steps":["trace[1613114727] 'process raft request' (duration: 695.442235ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:56:34.662126Z","caller":"traceutil/trace.go:171","msg":"trace[1682399306] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"686.088388ms","start":"2026-04-20T11:56:33.976032Z","end":"2026-04-20T11:56:34.66212Z","steps":["trace[1682399306] 'process raft request' (duration: 685.931175ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:56:34.662168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:56:33.97602Z","time spent":"686.130448ms","remote":"127.0.0.1:54238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T11:56:34.661989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:56:33.956192Z","time spent":"705.740738ms","remote":"127.0.0.1:54238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T11:56:34.662Z","caller":"traceutil/trace.go:171","msg":"trace[1920659099] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"695.120138ms","start":"2026-04-20T11:56:33.966853Z","end":"2026-04-20T11:56:34.661973Z","steps":["trace[1920659099] 'process raft request' (duration: 694.986026ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:56:34.66225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:56:33.966319Z","time spent":"695.801361ms","remote":"127.0.0.1:54238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T11:56:34.662028Z","caller":"traceutil/trace.go:171","msg":"trace[1559604930] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"692.465687ms","start":"2026-04-20T11:56:33.969555Z","end":"2026-04-20T11:56:34.66202Z","steps":["trace[1559604930] 'process raft request' (duration: 692.361685ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:56:34.662345Z","caller":"traceutil/trace.go:171","msg":"trace[2080303347] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"682.472309ms","start":"2026-04-20T11:56:33.979851Z","end":"2026-04-20T11:56:34.662324Z","steps":["trace[2080303347] 'process raft request' (duration: 682.245984ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:56:34.662383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:56:33.969526Z","time spent":"692.834494ms","remote":"127.0.0.1:54238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T11:56:34.662028Z","caller":"traceutil/trace.go:171","msg":"trace[403045827] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"693.398646ms","start":"2026-04-20T11:56:33.968602Z","end":"2026-04-20T11:56:34.662Z","steps":["trace[403045827] 'process raft request' (duration: 693.278804ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:56:34.662468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:56:33.968589Z","time spent":"693.858964ms","remote":"127.0.0.1:54238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T11:56:34.662469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:56:33.979832Z","time spent":"682.580291ms","remote":"127.0.0.1:54238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T11:56:34.662079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:56:33.965914Z","time spent":"696.099237ms","remote":"127.0.0.1:54238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T11:56:34.662547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:56:33.966838Z","time spent":"695.457864ms","remote":"127.0.0.1:54238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T11:56:34.662635Z","caller":"traceutil/trace.go:171","msg":"trace[1888945100] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"521.615232ms","start":"2026-04-20T11:56:34.140994Z","end":"2026-04-20T11:56:34.662609Z","steps":["trace[1888945100] 'process raft request' (duration: 521.51843ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:56:34.662664Z","caller":"traceutil/trace.go:171","msg":"trace[1610684066] linearizableReadLoop","detail":"{readStateIndex:557; appliedIndex:546; }","duration":"707.947901ms","start":"2026-04-20T11:56:33.954661Z","end":"2026-04-20T11:56:34.662609Z","steps":["trace[1610684066] 'read index received' (duration: 693.344024ms)","trace[1610684066] 'applied index is now lower than readState.Index' (duration: 14.602557ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T11:56:34.662722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:56:34.140931Z","time spent":"521.754084ms","remote":"127.0.0.1:54238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T11:56:34.662604Z","caller":"traceutil/trace.go:171","msg":"trace[82538988] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"647.844026ms","start":"2026-04-20T11:56:34.014699Z","end":"2026-04-20T11:56:34.662543Z","steps":["trace[82538988] 'process raft request' (duration: 647.717632ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:56:34.662887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:56:34.014668Z","time spent":"648.181052ms","remote":"127.0.0.1:54238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T11:56:34.662742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"708.076873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-20T11:56:34.662989Z","caller":"traceutil/trace.go:171","msg":"trace[178567805] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:537; }","duration":"708.312637ms","start":"2026-04-20T11:56:33.954637Z","end":"2026-04-20T11:56:34.662949Z","steps":["trace[178567805] 'agreement among raft nodes before linearized reading' (duration: 708.040122ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:56:34.663058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:56:33.954628Z","time spent":"708.41506ms","remote":"127.0.0.1:54446","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-20T11:56:34.669009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.643648ms","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-20T11:56:34.669059Z","caller":"traceutil/trace.go:171","msg":"trace[50349813] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:539; }","duration":"120.73701ms","start":"2026-04-20T11:56:34.548309Z","end":"2026-04-20T11:56:34.669046Z","steps":["trace[50349813] 'agreement among raft nodes before linearized reading' (duration: 120.659728ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:56:34.669075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"709.205174ms","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-20T11:56:34.669161Z","caller":"traceutil/trace.go:171","msg":"trace[1023129794] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:539; }","duration":"709.307635ms","start":"2026-04-20T11:56:33.95983Z","end":"2026-04-20T11:56:34.669138Z","steps":["trace[1023129794] 'agreement among raft nodes before linearized reading' (duration: 709.104682ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:56:34.669215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:56:33.959822Z","time spent":"709.379387ms","remote":"127.0.0.1:54446","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-20T11:56:40.740846Z","caller":"traceutil/trace.go:171","msg":"trace[1583626775] linearizableReadLoop","detail":"{readStateIndex:647; appliedIndex:646; }","duration":"116.493479ms","start":"2026-04-20T11:56:40.624328Z","end":"2026-04-20T11:56:40.740822Z","steps":["trace[1583626775] 'read index received' (duration: 114.838698ms)","trace[1583626775] 'applied index is now lower than readState.Index' (duration: 1.653591ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T11:56:40.740937Z","caller":"traceutil/trace.go:171","msg":"trace[789002798] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"241.093993ms","start":"2026-04-20T11:56:40.499809Z","end":"2026-04-20T11:56:40.740903Z","steps":["trace[789002798] 'process raft request' (duration: 239.650146ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:56:40.741042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.699504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-s5ws4\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-04-20T11:56:40.741225Z","caller":"traceutil/trace.go:171","msg":"trace[29137494] range","detail":"{range_begin:/registry/pods/kube-system/cilium-s5ws4; range_end:; response_count:1; response_revision:621; }","duration":"116.919087ms","start":"2026-04-20T11:56:40.624284Z","end":"2026-04-20T11:56:40.741203Z","steps":["trace[29137494] 'agreement among raft nodes before linearized reading' (duration: 116.659303ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:56:41.360191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.986713ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T11:56:41.360551Z","caller":"traceutil/trace.go:171","msg":"trace[1017863027] linearizableReadLoop","detail":"{readStateIndex:653; appliedIndex:648; }","duration":"566.267834ms","start":"2026-04-20T11:56:40.794267Z","end":"2026-04-20T11:56:41.360535Z","steps":["trace[1017863027] 'read index received' (duration: 236.867183ms)","trace[1017863027] 'applied index is now lower than readState.Index' (duration: 329.399661ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T11:56:41.360694Z","caller":"traceutil/trace.go:171","msg":"trace[1843278612] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"614.75058ms","start":"2026-04-20T11:56:40.745924Z","end":"2026-04-20T11:56:41.360675Z","steps":["trace[1843278612] 'process raft request' (duration: 614.389434ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:56:41.360777Z","caller":"traceutil/trace.go:171","msg":"trace[1725963952] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"562.935221ms","start":"2026-04-20T11:56:40.797802Z","end":"2026-04-20T11:56:41.360737Z","steps":["trace[1725963952] 'process raft request' (duration: 562.695287ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:56:41.360807Z","caller":"traceutil/trace.go:171","msg":"trace[67576183] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"604.915265ms","start":"2026-04-20T11:56:40.755854Z","end":"2026-04-20T11:56:41.360769Z","steps":["trace[67576183] 'process raft request' (duration: 604.557757ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:56:41.360836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:56:40.745903Z","time spent":"614.879713ms","remote":"127.0.0.1:54446","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T11:56:41.360836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"566.558061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T11:56:41.360859Z","caller":"traceutil/trace.go:171","msg":"trace[1332992373] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"594.531019ms","start":"2026-04-20T11:56:40.766284Z","end":"2026-04-20T11:56:41.360815Z","steps":["trace[1332992373] 'process raft request' (duration: 594.169231ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:56:41.360947Z","caller":"traceutil/trace.go:171","msg":"trace[868173391] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:627; }","duration":"566.664603ms","start":"2026-04-20T11:56:40.79425Z","end":"2026-04-20T11:56:41.360915Z","steps":["trace[868173391] 'agreement among raft nodes before linearized reading' (duration: 566.431867ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:56:41.360949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:56:40.755844Z","time spent":"605.031568ms","remote":"127.0.0.1:54222","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T11:56:41.360951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:56:40.797784Z","time spent":"563.075165ms","remote":"127.0.0.1:54370","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":818,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T11:56:41.360995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:56:40.794198Z","time spent":"566.787785ms","remote":"127.0.0.1:54162","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-20T11:56:41.361014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:56:40.766254Z","time spent":"594.680391ms","remote":"127.0.0.1:54346","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T11:56:41.361073Z","caller":"traceutil/trace.go:171","msg":"trace[135144075] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"614.790371ms","start":"2026-04-20T11:56:40.745998Z","end":"2026-04-20T11:56:41.360789Z","steps":["trace[135144075] 'process raft request' (duration: 614.376343ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:56:41.361128Z","caller":"traceutil/trace.go:171","msg":"trace[890765992] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"614.652798ms","start":"2026-04-20T11:56:40.745889Z","end":"2026-04-20T11:56:41.360542Z","steps":["trace[890765992] 'process raft request' (duration: 285.235617ms)","trace[890765992] 'compare' (duration: 328.85398ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T11:56:41.361418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:56:40.745958Z","time spent":"615.207539ms","remote":"127.0.0.1:54446","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T11:56:41.361491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:56:40.745862Z","time spent":"615.555136ms","remote":"127.0.0.1:54446","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T11:56:42.209349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"724.85179ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T11:56:42.20948Z","caller":"traceutil/trace.go:171","msg":"trace[528999021] linearizableReadLoop","detail":"{readStateIndex:655; appliedIndex:654; }","duration":"842.816448ms","start":"2026-04-20T11:56:41.366641Z","end":"2026-04-20T11:56:42.209458Z","steps":["trace[528999021] 'read index received' (duration: 117.735024ms)","trace[528999021] 'applied index is now lower than readState.Index' (duration: 725.079744ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T11:56:42.209748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"837.884705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T11:56:42.209786Z","caller":"traceutil/trace.go:171","msg":"trace[1350782287] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"843.945559ms","start":"2026-04-20T11:56:41.365677Z","end":"2026-04-20T11:56:42.209623Z","steps":["trace[1350782287] 'process raft request' (duration: 118.722532ms)","trace[1350782287] 'compare' (duration: 724.719987ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T11:56:42.209827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"843.167084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-04-20T11:56:42.209819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.398275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T11:56:42.209913Z","caller":"traceutil/trace.go:171","msg":"trace[841316942] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:628; }","duration":"843.291656ms","start":"2026-04-20T11:56:41.366602Z","end":"2026-04-20T11:56:42.209894Z","steps":["trace[841316942] 'agreement among raft nodes before linearized reading' (duration: 843.017361ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:56:42.209817Z","caller":"traceutil/trace.go:171","msg":"trace[143701409] range","detail":"{range_begin:/registry/roles/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:628; }","duration":"837.980116ms","start":"2026-04-20T11:56:41.371813Z","end":"2026-04-20T11:56:42.209794Z","steps":["trace[143701409] 'agreement among raft nodes before linearized reading' (duration: 837.827694ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:56:42.209934Z","caller":"traceutil/trace.go:171","msg":"trace[445804943] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:628; }","duration":"415.575898ms","start":"2026-04-20T11:56:41.794331Z","end":"2026-04-20T11:56:42.209907Z","steps":["trace[445804943] 'agreement among raft nodes before linearized reading' (duration: 415.314074ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:56:42.209968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:56:41.366586Z","time spent":"843.372778ms","remote":"127.0.0.1:54222","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" "} {"level":"warn","ts":"2026-04-20T11:56:42.209975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:56:41.365634Z","time spent":"844.259325ms","remote":"127.0.0.1:54446","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T11:56:42.209992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:56:41.794309Z","time spent":"415.672021ms","remote":"127.0.0.1:54162","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-20T11:56:42.210013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:56:41.371795Z","time spent":"838.203131ms","remote":"127.0.0.1:54520","response type":"/etcdserverpb.KV/Range","request count":0,"request size":73,"response count":0,"response size":27,"request content":"key:\"/registry/roles/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"info","ts":"2026-04-20T11:56:43.284469Z","caller":"traceutil/trace.go:171","msg":"trace[303700621] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"132.143845ms","start":"2026-04-20T11:56:43.152293Z","end":"2026-04-20T11:56:43.284437Z","steps":["trace[303700621] 'process raft request' (duration: 131.558065ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:56:43.542273Z","caller":"traceutil/trace.go:171","msg":"trace[846813661] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"132.500132ms","start":"2026-04-20T11:56:43.409741Z","end":"2026-04-20T11:56:43.542242Z","steps":["trace[846813661] 'process raft request' (duration: 82.983456ms)","trace[846813661] 'compare' (duration: 49.391013ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T11:56:43.763864Z","caller":"traceutil/trace.go:171","msg":"trace[933370649] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"124.008822ms","start":"2026-04-20T11:56:43.639833Z","end":"2026-04-20T11:56:43.763842Z","steps":["trace[933370649] 'process raft request' (duration: 26.361788ms)","trace[933370649] 'compare' (duration: 97.562973ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T11:56:43.826562Z","caller":"traceutil/trace.go:171","msg":"trace[720983126] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"172.446628ms","start":"2026-04-20T11:56:43.654088Z","end":"2026-04-20T11:56:43.826534Z","steps":["trace[720983126] 'process raft request' (duration: 172.311855ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:56:43.894525Z","caller":"traceutil/trace.go:171","msg":"trace[587992530] linearizableReadLoop","detail":"{readStateIndex:670; appliedIndex:668; }","duration":"100.417016ms","start":"2026-04-20T11:56:43.794077Z","end":"2026-04-20T11:56:43.894494Z","steps":["trace[587992530] 'read index received' (duration: 32.368802ms)","trace[587992530] 'applied index is now lower than readState.Index' (duration: 68.046944ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T11:56:43.894596Z","caller":"traceutil/trace.go:171","msg":"trace[1565095272] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"149.711898ms","start":"2026-04-20T11:56:43.744857Z","end":"2026-04-20T11:56:43.894569Z","steps":["trace[1565095272] 'process raft request' (duration: 147.430965ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:56:43.894675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.58455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T11:56:43.894723Z","caller":"traceutil/trace.go:171","msg":"trace[1043260959] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:643; }","duration":"100.684961ms","start":"2026-04-20T11:56:43.794022Z","end":"2026-04-20T11:56:43.894707Z","steps":["trace[1043260959] 'agreement among raft nodes before linearized reading' (duration: 100.593799ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:56:44.203376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.243612ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-20T11:56:44.203503Z","caller":"traceutil/trace.go:171","msg":"trace[1341043659] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"257.489133ms","start":"2026-04-20T11:56:43.945991Z","end":"2026-04-20T11:56:44.20348Z","steps":["trace[1341043659] 'process raft request' (duration: 48.060327ms)","trace[1341043659] 'compare' (duration: 209.064719ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T11:56:44.67026Z","caller":"traceutil/trace.go:171","msg":"trace[1294490569] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"316.586609ms","start":"2026-04-20T11:56:44.353641Z","end":"2026-04-20T11:56:44.670228Z","steps":["trace[1294490569] 'process raft request' (duration: 316.444176ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:56:44.670451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:56:44.3536Z","time spent":"316.754582ms","remote":"127.0.0.1:54256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":763,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T11:56:44.699678Z","caller":"traceutil/trace.go:171","msg":"trace[2142533935] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"102.899613ms","start":"2026-04-20T11:56:44.59674Z","end":"2026-04-20T11:56:44.699639Z","steps":["trace[2142533935] 'process raft request' (duration: 102.102709ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:57:00.056892Z","caller":"traceutil/trace.go:171","msg":"trace[1713828625] linearizableReadLoop","detail":"{readStateIndex:836; appliedIndex:835; }","duration":"452.19323ms","start":"2026-04-20T11:56:59.604673Z","end":"2026-04-20T11:57:00.056866Z","steps":["trace[1713828625] 'read index received' (duration: 452.046326ms)","trace[1713828625] 'applied index is now lower than readState.Index' (duration: 145.714µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T11:57:00.056966Z","caller":"traceutil/trace.go:171","msg":"trace[587266292] transaction","detail":"{read_only:false; response_revision:805; number_of_response:1; }","duration":"467.172023ms","start":"2026-04-20T11:56:59.589759Z","end":"2026-04-20T11:57:00.056931Z","steps":["trace[587266292] 'process raft request' (duration: 466.929228ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:57:00.057209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.501055ms","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-20T11:57:00.057294Z","caller":"traceutil/trace.go:171","msg":"trace[134542448] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:805; }","duration":"452.631388ms","start":"2026-04-20T11:56:59.604643Z","end":"2026-04-20T11:57:00.057275Z","steps":["trace[134542448] 'agreement among raft nodes before linearized reading' (duration: 452.377444ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:57:00.057225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:56:59.589734Z","time spent":"467.394148ms","remote":"127.0.0.1:54446","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T11:57:00.057344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:56:59.604628Z","time spent":"452.704119ms","remote":"127.0.0.1:54446","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":"info","ts":"2026-04-20T11:57:00.094509Z","caller":"traceutil/trace.go:171","msg":"trace[532584123] transaction","detail":"{read_only:false; response_revision:806; number_of_response:1; }","duration":"330.688625ms","start":"2026-04-20T11:56:59.76379Z","end":"2026-04-20T11:57:00.094478Z","steps":["trace[532584123] 'process raft request' (duration: 329.713528ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:57:00.094677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:56:59.763759Z","time spent":"330.830858ms","remote":"127.0.0.1:54410","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T11:57:21.804078Z","caller":"traceutil/trace.go:171","msg":"trace[740694427] transaction","detail":"{read_only:false; response_revision:1158; number_of_response:1; }","duration":"124.09032ms","start":"2026-04-20T11:57:21.679961Z","end":"2026-04-20T11:57:21.804051Z","steps":["trace[740694427] 'process raft request' (duration: 123.869046ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:57:29.864423Z","caller":"traceutil/trace.go:171","msg":"trace[800432264] linearizableReadLoop","detail":"{readStateIndex:1243; appliedIndex:1242; }","duration":"407.653405ms","start":"2026-04-20T11:57:29.456726Z","end":"2026-04-20T11:57:29.86438Z","steps":["trace[800432264] 'read index received' (duration: 407.41012ms)","trace[800432264] 'applied index is now lower than readState.Index' (duration: 241.505µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T11:57:29.864595Z","caller":"traceutil/trace.go:171","msg":"trace[978557605] transaction","detail":"{read_only:false; response_revision:1204; number_of_response:1; }","duration":"433.154463ms","start":"2026-04-20T11:57:29.431382Z","end":"2026-04-20T11:57:29.864536Z","steps":["trace[978557605] 'process raft request' (duration: 432.768585ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:57:29.864685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.926282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T11:57:29.864741Z","caller":"traceutil/trace.go:171","msg":"trace[461652598] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:1204; }","duration":"408.050234ms","start":"2026-04-20T11:57:29.456675Z","end":"2026-04-20T11:57:29.864725Z","steps":["trace[461652598] 'agreement among raft nodes before linearized reading' (duration: 407.921561ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:57:29.864789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:57:29.456653Z","time spent":"408.122886ms","remote":"127.0.0.1:54714","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":2,"response size":29,"request content":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true "} {"level":"warn","ts":"2026-04-20T11:57:29.864898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:57:29.431341Z","time spent":"433.397478ms","remote":"127.0.0.1:54446","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T11:57:44.553647Z","caller":"traceutil/trace.go:171","msg":"trace[1265962363] linearizableReadLoop","detail":"{readStateIndex:1384; appliedIndex:1383; }","duration":"161.106517ms","start":"2026-04-20T11:57:44.392511Z","end":"2026-04-20T11:57:44.553617Z","steps":["trace[1265962363] 'read index received' (duration: 160.996815ms)","trace[1265962363] 'applied index is now lower than readState.Index' (duration: 108.322µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T11:57:44.553766Z","caller":"traceutil/trace.go:171","msg":"trace[1774787356] transaction","detail":"{read_only:false; response_revision:1338; number_of_response:1; }","duration":"204.554517ms","start":"2026-04-20T11:57:44.349157Z","end":"2026-04-20T11:57:44.553712Z","steps":["trace[1774787356] 'process raft request' (duration: 204.228461ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:57:44.553865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.339751ms","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-20T11:57:44.553986Z","caller":"traceutil/trace.go:171","msg":"trace[1506692915] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1338; }","duration":"161.513774ms","start":"2026-04-20T11:57:44.392459Z","end":"2026-04-20T11:57:44.553973Z","steps":["trace[1506692915] 'agreement among raft nodes before linearized reading' (duration: 161.29739ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:57:44.555766Z","caller":"traceutil/trace.go:171","msg":"trace[978751808] transaction","detail":"{read_only:false; response_revision:1339; number_of_response:1; }","duration":"143.700204ms","start":"2026-04-20T11:57:44.412041Z","end":"2026-04-20T11:57:44.555741Z","steps":["trace[978751808] 'process raft request' (duration: 143.575782ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:57:52.300601Z","caller":"traceutil/trace.go:171","msg":"trace[1220729174] transaction","detail":"{read_only:false; response_revision:1465; number_of_response:1; }","duration":"211.223085ms","start":"2026-04-20T11:57:52.089349Z","end":"2026-04-20T11:57:52.300573Z","steps":["trace[1220729174] 'process raft request' (duration: 211.068242ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:57:52.910511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.106931ms","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-20T11:57:52.91063Z","caller":"traceutil/trace.go:171","msg":"trace[265322698] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1466; }","duration":"324.269594ms","start":"2026-04-20T11:57:52.586331Z","end":"2026-04-20T11:57:52.9106Z","steps":["trace[265322698] 'range keys from in-memory index tree' (duration: 323.916687ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:57:52.910689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:57:52.586316Z","time spent":"324.358996ms","remote":"127.0.0.1:54446","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-20T11:57:52.910692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.018662ms","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-20T11:57:52.910742Z","caller":"traceutil/trace.go:171","msg":"trace[45288874] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1466; }","duration":"320.123265ms","start":"2026-04-20T11:57:52.590605Z","end":"2026-04-20T11:57:52.910728Z","steps":["trace[45288874] 'range keys from in-memory index tree' (duration: 319.856669ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:57:52.910744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.326341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/ingress-nginx/ingress-nginx-admission-patch-dkrkw\" ","response":"range_response_count:1 size:4520"} {"level":"warn","ts":"2026-04-20T11:57:52.910795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:57:52.590582Z","time spent":"320.200946ms","remote":"127.0.0.1:54446","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-20T11:57:52.910828Z","caller":"traceutil/trace.go:171","msg":"trace[73510773] range","detail":"{range_begin:/registry/pods/ingress-nginx/ingress-nginx-admission-patch-dkrkw; range_end:; response_count:1; response_revision:1466; }","duration":"395.481934ms","start":"2026-04-20T11:57:52.515324Z","end":"2026-04-20T11:57:52.910806Z","steps":["trace[73510773] 'range keys from in-memory index tree' (duration: 395.126768ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:57:52.910844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.776781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-20T11:57:52.91088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:57:52.515302Z","time spent":"395.568946ms","remote":"127.0.0.1:54346","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":1,"response size":4542,"request content":"key:\"/registry/pods/ingress-nginx/ingress-nginx-admission-patch-dkrkw\" "} {"level":"info","ts":"2026-04-20T11:57:52.910903Z","caller":"traceutil/trace.go:171","msg":"trace[205342111] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1466; }","duration":"148.885833ms","start":"2026-04-20T11:57:52.762002Z","end":"2026-04-20T11:57:52.910888Z","steps":["trace[205342111] 'range keys from in-memory index tree' (duration: 148.635039ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:58:16.103781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.364669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T11:58:16.103882Z","caller":"traceutil/trace.go:171","msg":"trace[143176343] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1862; }","duration":"309.489112ms","start":"2026-04-20T11:58:15.794371Z","end":"2026-04-20T11:58:16.10386Z","steps":["trace[143176343] 'range keys from in-memory index tree' (duration: 309.276668ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:58:16.10395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:58:15.794316Z","time spent":"309.613894ms","remote":"127.0.0.1:54162","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-20T11:58:16.420824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.504567ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-04-20T11:58:16.420955Z","caller":"traceutil/trace.go:171","msg":"trace[739809287] linearizableReadLoop","detail":"{readStateIndex:1922; appliedIndex:1921; }","duration":"448.892447ms","start":"2026-04-20T11:58:15.972036Z","end":"2026-04-20T11:58:16.420929Z","steps":["trace[739809287] 'read index received' (duration: 116.393261ms)","trace[739809287] 'applied index is now lower than readState.Index' (duration: 332.496996ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T11:58:16.421196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.959794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T11:58:16.421262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.226413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:4591"} {"level":"info","ts":"2026-04-20T11:58:16.42128Z","caller":"traceutil/trace.go:171","msg":"trace[1517176426] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1862; }","duration":"316.086896ms","start":"2026-04-20T11:58:16.105166Z","end":"2026-04-20T11:58:16.421253Z","steps":["trace[1517176426] 'agreement among raft nodes before linearized reading' (duration: 315.954904ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:58:16.421334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:58:16.105141Z","time spent":"316.184568ms","remote":"127.0.0.1:54170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-20T11:58:16.421338Z","caller":"traceutil/trace.go:171","msg":"trace[1744496950] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1862; }","duration":"449.341155ms","start":"2026-04-20T11:58:15.97196Z","end":"2026-04-20T11:58:16.421301Z","steps":["trace[1744496950] 'agreement among raft nodes before linearized reading' (duration: 449.128761ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:58:16.421425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:58:15.971934Z","time spent":"449.47632ms","remote":"127.0.0.1:50898","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":4613,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-04-20T11:58:16.421435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.068212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-48216364-9395-4e54-b3b2-4e87f39752d5\" ","response":"range_response_count:1 size:3627"} {"level":"warn","ts":"2026-04-20T11:58:16.421505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.770775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-48216364-9395-4e54-b3b2-4e87f39752d5\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-04-20T11:58:16.421805Z","caller":"traceutil/trace.go:171","msg":"trace[122244829] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-48216364-9395-4e54-b3b2-4e87f39752d5; range_end:; response_count:1; response_revision:1862; }","duration":"313.122471ms","start":"2026-04-20T11:58:16.108659Z","end":"2026-04-20T11:58:16.421781Z","steps":["trace[122244829] 'agreement among raft nodes before linearized reading' (duration: 312.56506ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:58:16.421862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:58:16.10864Z","time spent":"313.211863ms","remote":"127.0.0.1:54346","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3649,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-48216364-9395-4e54-b3b2-4e87f39752d5\" "} {"level":"info","ts":"2026-04-20T11:58:16.421521Z","caller":"traceutil/trace.go:171","msg":"trace[1304640741] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-48216364-9395-4e54-b3b2-4e87f39752d5; range_end:; response_count:1; response_revision:1862; }","duration":"310.217276ms","start":"2026-04-20T11:58:16.111281Z","end":"2026-04-20T11:58:16.421498Z","steps":["trace[1304640741] 'agreement among raft nodes before linearized reading' (duration: 309.893079ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:58:16.421977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:58:16.111257Z","time spent":"310.706375ms","remote":"127.0.0.1:54346","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3649,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-48216364-9395-4e54-b3b2-4e87f39752d5\" "} {"level":"info","ts":"2026-04-20T11:58:16.572279Z","caller":"traceutil/trace.go:171","msg":"trace[682026651] transaction","detail":"{read_only:false; response_revision:1863; number_of_response:1; }","duration":"140.74784ms","start":"2026-04-20T11:58:16.4315Z","end":"2026-04-20T11:58:16.572248Z","steps":["trace[682026651] 'process raft request' (duration: 140.516106ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:58:16.594601Z","caller":"traceutil/trace.go:171","msg":"trace[1313731171] transaction","detail":"{read_only:false; response_revision:1864; number_of_response:1; }","duration":"160.124867ms","start":"2026-04-20T11:58:16.434433Z","end":"2026-04-20T11:58:16.594558Z","steps":["trace[1313731171] 'process raft request' (duration: 159.758471ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:58:21.574447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.329524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:4591"} {"level":"info","ts":"2026-04-20T11:58:21.574528Z","caller":"traceutil/trace.go:171","msg":"trace[1880617532] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1938; }","duration":"121.464127ms","start":"2026-04-20T11:58:21.453045Z","end":"2026-04-20T11:58:21.574509Z","steps":["trace[1880617532] 'range keys from in-memory index tree' (duration: 121.146541ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:58:24.586448Z","caller":"traceutil/trace.go:171","msg":"trace[759337353] transaction","detail":"{read_only:false; response_revision:1958; number_of_response:1; }","duration":"106.510424ms","start":"2026-04-20T11:58:24.479888Z","end":"2026-04-20T11:58:24.586398Z","steps":["trace[759337353] 'process raft request' (duration: 106.359901ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:58:25.130718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.114295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T11:58:25.130799Z","caller":"traceutil/trace.go:171","msg":"trace[667248317] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1961; }","duration":"336.244738ms","start":"2026-04-20T11:58:24.794538Z","end":"2026-04-20T11:58:25.130783Z","steps":["trace[667248317] 'range keys from in-memory index tree' (duration: 336.029034ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:58:25.130828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:58:24.794509Z","time spent":"336.311909ms","remote":"127.0.0.1:54162","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-20T11:58:59.502117Z","caller":"traceutil/trace.go:171","msg":"trace[1437022441] linearizableReadLoop","detail":"{readStateIndex:2278; appliedIndex:2277; }","duration":"210.350467ms","start":"2026-04-20T11:58:59.291742Z","end":"2026-04-20T11:58:59.502092Z","steps":["trace[1437022441] 'read index received' (duration: 210.142044ms)","trace[1437022441] 'applied index is now lower than readState.Index' (duration: 207.573µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T11:58:59.502197Z","caller":"traceutil/trace.go:171","msg":"trace[2063407289] transaction","detail":"{read_only:false; response_revision:2202; number_of_response:1; }","duration":"255.219627ms","start":"2026-04-20T11:58:59.246934Z","end":"2026-04-20T11:58:59.502154Z","steps":["trace[2063407289] 'process raft request' (duration: 255.001592ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:58:59.502389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.336673ms","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-20T11:58:59.5024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.651424ms","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-20T11:58:59.502437Z","caller":"traceutil/trace.go:171","msg":"trace[570295571] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2202; }","duration":"205.432414ms","start":"2026-04-20T11:58:59.296992Z","end":"2026-04-20T11:58:59.502424Z","steps":["trace[570295571] 'agreement among raft nodes before linearized reading' (duration: 205.256111ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:58:59.502471Z","caller":"traceutil/trace.go:171","msg":"trace[473060922] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2202; }","duration":"210.777306ms","start":"2026-04-20T11:58:59.291675Z","end":"2026-04-20T11:58:59.502453Z","steps":["trace[473060922] 'agreement among raft nodes before linearized reading' (duration: 210.560782ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:58:59.502659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.866853ms","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-20T11:58:59.502706Z","caller":"traceutil/trace.go:171","msg":"trace[162843141] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2202; }","duration":"204.946724ms","start":"2026-04-20T11:58:59.297746Z","end":"2026-04-20T11:58:59.502693Z","steps":["trace[162843141] 'agreement among raft nodes before linearized reading' (duration: 204.769781ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:58:59.707414Z","caller":"traceutil/trace.go:171","msg":"trace[1385524473] transaction","detail":"{read_only:false; response_revision:2204; number_of_response:1; }","duration":"200.301858ms","start":"2026-04-20T11:58:59.507075Z","end":"2026-04-20T11:58:59.707377Z","steps":["trace[1385524473] 'process raft request' (duration: 200.032473ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:58:59.707725Z","caller":"traceutil/trace.go:171","msg":"trace[1177844918] transaction","detail":"{read_only:false; response_revision:2205; number_of_response:1; }","duration":"200.571513ms","start":"2026-04-20T11:58:59.507139Z","end":"2026-04-20T11:58:59.70771Z","steps":["trace[1177844918] 'process raft request' (duration: 200.193656ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:58:59.707903Z","caller":"traceutil/trace.go:171","msg":"trace[370322913] transaction","detail":"{read_only:false; response_revision:2206; number_of_response:1; }","duration":"200.38645ms","start":"2026-04-20T11:58:59.507479Z","end":"2026-04-20T11:58:59.707865Z","steps":["trace[370322913] 'process raft request' (duration: 200.121215ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:58:59.707994Z","caller":"traceutil/trace.go:171","msg":"trace[130887875] transaction","detail":"{read_only:false; response_revision:2208; number_of_response:1; }","duration":"198.987814ms","start":"2026-04-20T11:58:59.508968Z","end":"2026-04-20T11:58:59.707956Z","steps":["trace[130887875] 'process raft request' (duration: 198.81104ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:58:59.707919Z","caller":"traceutil/trace.go:171","msg":"trace[1289165130] linearizableReadLoop","detail":"{readStateIndex:2285; appliedIndex:2278; }","duration":"195.12829ms","start":"2026-04-20T11:58:59.512748Z","end":"2026-04-20T11:58:59.707876Z","steps":["trace[1289165130] 'read index received' (duration: 24.35254ms)","trace[1289165130] 'applied index is now lower than readState.Index' (duration: 170.7744ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T11:58:59.708044Z","caller":"traceutil/trace.go:171","msg":"trace[1533654573] transaction","detail":"{read_only:false; response_revision:2209; number_of_response:1; }","duration":"197.399103ms","start":"2026-04-20T11:58:59.510629Z","end":"2026-04-20T11:58:59.708028Z","steps":["trace[1533654573] 'process raft request' (duration: 197.203198ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:58:59.707904Z","caller":"traceutil/trace.go:171","msg":"trace[414888680] transaction","detail":"{read_only:false; response_revision:2207; number_of_response:1; }","duration":"200.081193ms","start":"2026-04-20T11:58:59.507794Z","end":"2026-04-20T11:58:59.707875Z","steps":["trace[414888680] 'process raft request' (duration: 199.93311ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:58:59.708172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.397186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18a80ecb57c6d02f\" ","response":"range_response_count:1 size:846"} {"level":"info","ts":"2026-04-20T11:58:59.708232Z","caller":"traceutil/trace.go:171","msg":"trace[608671875] range","detail":"{range_begin:/registry/events/openstack/percona-xtradb-haproxy-0.18a80ecb57c6d02f; range_end:; response_count:1; response_revision:2209; }","duration":"195.504487ms","start":"2026-04-20T11:58:59.512711Z","end":"2026-04-20T11:58:59.708216Z","steps":["trace[608671875] 'agreement among raft nodes before linearized reading' (duration: 195.259313ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:59:37.689536Z","caller":"traceutil/trace.go:171","msg":"trace[1548691240] transaction","detail":"{read_only:false; response_revision:2516; number_of_response:1; }","duration":"162.768858ms","start":"2026-04-20T11:59:37.526745Z","end":"2026-04-20T11:59:37.689514Z","steps":["trace[1548691240] 'process raft request' (duration: 162.644906ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T11:59:38.854941Z","caller":"traceutil/trace.go:171","msg":"trace[1847245774] linearizableReadLoop","detail":"{readStateIndex:2621; appliedIndex:2620; }","duration":"268.753336ms","start":"2026-04-20T11:59:38.58616Z","end":"2026-04-20T11:59:38.854913Z","steps":["trace[1847245774] 'read index received' (duration: 268.488221ms)","trace[1847245774] 'applied index is now lower than readState.Index' (duration: 263.935µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T11:59:38.854997Z","caller":"traceutil/trace.go:171","msg":"trace[1080749366] transaction","detail":"{read_only:false; response_revision:2527; number_of_response:1; }","duration":"469.559413ms","start":"2026-04-20T11:59:38.385404Z","end":"2026-04-20T11:59:38.854964Z","steps":["trace[1080749366] 'process raft request' (duration: 469.35499ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T11:59:38.855115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.93668ms","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-20T11:59:38.855143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T11:59:38.385366Z","time spent":"469.702285ms","remote":"127.0.0.1:54676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16428,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T11:59:38.855155Z","caller":"traceutil/trace.go:171","msg":"trace[1741395273] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:2527; }","duration":"269.051302ms","start":"2026-04-20T11:59:38.586091Z","end":"2026-04-20T11:59:38.855143Z","steps":["trace[1741395273] 'agreement among raft nodes before linearized reading' (duration: 268.95824ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:00:12.302591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.765794ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T12:00:12.302766Z","caller":"traceutil/trace.go:171","msg":"trace[628332475] linearizableReadLoop","detail":"{readStateIndex:2864; appliedIndex:2863; }","duration":"282.474927ms","start":"2026-04-20T12:00:12.020272Z","end":"2026-04-20T12:00:12.302747Z","steps":["trace[628332475] 'read index received' (duration: 141.258484ms)","trace[628332475] 'applied index is now lower than readState.Index' (duration: 141.214883ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T12:00:12.302856Z","caller":"traceutil/trace.go:171","msg":"trace[1434666759] transaction","detail":"{read_only:false; response_revision:2755; number_of_response:1; }","duration":"419.278302ms","start":"2026-04-20T12:00:11.883565Z","end":"2026-04-20T12:00:12.302843Z","steps":["trace[1434666759] 'process raft request' (duration: 277.986198ms)","trace[1434666759] 'compare' (duration: 140.450157ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T12:00:12.303121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.791289ms","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":"warn","ts":"2026-04-20T12:00:12.303118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.489677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-20T12:00:12.303194Z","caller":"traceutil/trace.go:171","msg":"trace[1810638414] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2755; }","duration":"229.88018ms","start":"2026-04-20T12:00:12.073291Z","end":"2026-04-20T12:00:12.303171Z","steps":["trace[1810638414] 'agreement among raft nodes before linearized reading' (duration: 229.669666ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:00:12.303144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.867664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1296"} {"level":"info","ts":"2026-04-20T12:00:12.303213Z","caller":"traceutil/trace.go:171","msg":"trace[1632397842] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2755; }","duration":"246.64442ms","start":"2026-04-20T12:00:12.056544Z","end":"2026-04-20T12:00:12.303189Z","steps":["trace[1632397842] 'agreement among raft nodes before linearized reading' (duration: 246.402106ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:00:12.303277Z","caller":"traceutil/trace.go:171","msg":"trace[1638252078] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2755; }","duration":"283.059658ms","start":"2026-04-20T12:00:12.02019Z","end":"2026-04-20T12:00:12.30325Z","steps":["trace[1638252078] 'agreement among raft nodes before linearized reading' (duration: 282.794743ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:00:12.303356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.938609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:523"} {"level":"warn","ts":"2026-04-20T12:00:12.303391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T12:00:11.883545Z","time spent":"419.346794ms","remote":"127.0.0.1:54446","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T12:00:12.303412Z","caller":"traceutil/trace.go:171","msg":"trace[705627400] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:2755; }","duration":"236.01056ms","start":"2026-04-20T12:00:12.06738Z","end":"2026-04-20T12:00:12.303391Z","steps":["trace[705627400] 'agreement among raft nodes before linearized reading' (duration: 235.661694ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:00:12.303473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.975539ms","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-20T12:00:12.30358Z","caller":"traceutil/trace.go:171","msg":"trace[1686631384] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2755; }","duration":"241.169613ms","start":"2026-04-20T12:00:12.062392Z","end":"2026-04-20T12:00:12.303561Z","steps":["trace[1686631384] 'agreement among raft nodes before linearized reading' (duration: 240.773625ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:00:12.800781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.030749ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T12:00:12.801055Z","caller":"traceutil/trace.go:171","msg":"trace[977375281] transaction","detail":"{read_only:false; response_revision:2756; number_of_response:1; }","duration":"493.083617ms","start":"2026-04-20T12:00:12.307915Z","end":"2026-04-20T12:00:12.800999Z","steps":["trace[977375281] 'process raft request' (duration: 248.703581ms)","trace[977375281] 'compare' (duration: 243.820245ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T12:00:12.801231Z","caller":"traceutil/trace.go:171","msg":"trace[1020731608] linearizableReadLoop","detail":"{readStateIndex:2868; appliedIndex:2864; }","duration":"492.010325ms","start":"2026-04-20T12:00:12.309196Z","end":"2026-04-20T12:00:12.801206Z","steps":["trace[1020731608] 'read index received' (duration: 247.531697ms)","trace[1020731608] 'applied index is now lower than readState.Index' (duration: 244.477078ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T12:00:12.801331Z","caller":"traceutil/trace.go:171","msg":"trace[469359769] transaction","detail":"{read_only:false; response_revision:2758; number_of_response:1; }","duration":"492.660369ms","start":"2026-04-20T12:00:12.308634Z","end":"2026-04-20T12:00:12.801294Z","steps":["trace[469359769] 'process raft request' (duration: 492.453915ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:00:12.801371Z","caller":"traceutil/trace.go:171","msg":"trace[807380055] transaction","detail":"{read_only:false; response_revision:2759; number_of_response:1; }","duration":"492.670309ms","start":"2026-04-20T12:00:12.308685Z","end":"2026-04-20T12:00:12.801355Z","steps":["trace[807380055] 'process raft request' (duration: 492.465165ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:00:12.80142Z","caller":"traceutil/trace.go:171","msg":"trace[1057323707] transaction","detail":"{read_only:false; response_revision:2757; number_of_response:1; }","duration":"493.356292ms","start":"2026-04-20T12:00:12.30805Z","end":"2026-04-20T12:00:12.801406Z","steps":["trace[1057323707] 'process raft request' (duration: 492.866433ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:00:12.801462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T12:00:12.30868Z","time spent":"492.7303ms","remote":"127.0.0.1:54446","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T12:00:12.801488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T12:00:12.308627Z","time spent":"492.781901ms","remote":"127.0.0.1:54446","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T12:00:12.801494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T12:00:12.308027Z","time spent":"493.427984ms","remote":"127.0.0.1:54446","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T12:00:12.80135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T12:00:12.307893Z","time spent":"493.27457ms","remote":"127.0.0.1:54446","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T12:00:12.801585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.990381ms","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-20T12:00:12.801717Z","caller":"traceutil/trace.go:171","msg":"trace[1380166197] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:2759; }","duration":"369.124124ms","start":"2026-04-20T12:00:12.432522Z","end":"2026-04-20T12:00:12.801646Z","steps":["trace[1380166197] 'agreement among raft nodes before linearized reading' (duration: 368.994811ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:00:12.801759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.73327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:659"} {"level":"warn","ts":"2026-04-20T12:00:12.801772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T12:00:12.432459Z","time spent":"369.301657ms","remote":"127.0.0.1:54632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":29,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"info","ts":"2026-04-20T12:00:12.801789Z","caller":"traceutil/trace.go:171","msg":"trace[2137482391] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:2759; }","duration":"357.883332ms","start":"2026-04-20T12:00:12.443895Z","end":"2026-04-20T12:00:12.801778Z","steps":["trace[2137482391] 'agreement among raft nodes before linearized reading' (duration: 357.741109ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:00:12.801812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T12:00:12.443882Z","time spent":"357.924063ms","remote":"127.0.0.1:54298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":1,"response size":681,"request content":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" "} {"level":"warn","ts":"2026-04-20T12:00:12.801901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.495493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:644"} {"level":"warn","ts":"2026-04-20T12:00:12.801947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"492.74398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak\" ","response":"range_response_count:1 size:1249"} {"level":"info","ts":"2026-04-20T12:00:12.801976Z","caller":"traceutil/trace.go:171","msg":"trace[997105804] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2759; }","duration":"363.593745ms","start":"2026-04-20T12:00:12.438359Z","end":"2026-04-20T12:00:12.801952Z","steps":["trace[997105804] 'agreement among raft nodes before linearized reading' (duration: 363.38394ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:00:12.801995Z","caller":"traceutil/trace.go:171","msg":"trace[822236627] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak; range_end:; response_count:1; response_revision:2759; }","duration":"492.808042ms","start":"2026-04-20T12:00:12.30917Z","end":"2026-04-20T12:00:12.801978Z","steps":["trace[822236627] 'agreement among raft nodes before linearized reading' (duration: 492.604557ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:00:12.802022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T12:00:12.438347Z","time spent":"363.666488ms","remote":"127.0.0.1:54298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":666,"request content":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-04-20T12:00:12.802029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T12:00:12.309161Z","time spent":"492.861583ms","remote":"127.0.0.1:54356","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":1271,"request content":"key:\"/registry/services/specs/auth-system/keycloak\" "} {"level":"info","ts":"2026-04-20T12:00:13.082059Z","caller":"traceutil/trace.go:171","msg":"trace[448045766] transaction","detail":"{read_only:false; response_revision:2760; number_of_response:1; }","duration":"274.573612ms","start":"2026-04-20T12:00:12.807455Z","end":"2026-04-20T12:00:13.082029Z","steps":["trace[448045766] 'process raft request' (duration: 259.375292ms)","trace[448045766] 'compare' (duration: 15.081126ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T12:00:13.08463Z","caller":"traceutil/trace.go:171","msg":"trace[2083765745] transaction","detail":"{read_only:false; response_revision:2762; number_of_response:1; }","duration":"244.488398ms","start":"2026-04-20T12:00:12.840115Z","end":"2026-04-20T12:00:13.084603Z","steps":["trace[2083765745] 'process raft request' (duration: 244.431907ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:00:13.084897Z","caller":"traceutil/trace.go:171","msg":"trace[587888067] transaction","detail":"{read_only:false; response_revision:2761; number_of_response:1; }","duration":"277.359697ms","start":"2026-04-20T12:00:12.807503Z","end":"2026-04-20T12:00:13.084863Z","steps":["trace[587888067] 'process raft request' (duration: 276.916767ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:00:40.95741Z","caller":"traceutil/trace.go:171","msg":"trace[1351400353] linearizableReadLoop","detail":"{readStateIndex:3164; appliedIndex:3163; }","duration":"162.557478ms","start":"2026-04-20T12:00:40.794832Z","end":"2026-04-20T12:00:40.957389Z","steps":["trace[1351400353] 'read index received' (duration: 83.315383ms)","trace[1351400353] 'applied index is now lower than readState.Index' (duration: 79.241435ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T12:00:40.957479Z","caller":"traceutil/trace.go:171","msg":"trace[1302255222] transaction","detail":"{read_only:false; response_revision:3040; number_of_response:1; }","duration":"212.913269ms","start":"2026-04-20T12:00:40.744533Z","end":"2026-04-20T12:00:40.957446Z","steps":["trace[1302255222] 'process raft request' (duration: 212.710695ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:00:40.957558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T12:00:40.957592Z","caller":"traceutil/trace.go:171","msg":"trace[1226511855] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3040; }","duration":"162.773401ms","start":"2026-04-20T12:00:40.794809Z","end":"2026-04-20T12:00:40.957583Z","steps":["trace[1226511855] 'agreement among raft nodes before linearized reading' (duration: 162.69968ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:01:14.782737Z","caller":"traceutil/trace.go:171","msg":"trace[1438746519] transaction","detail":"{read_only:false; response_revision:3346; number_of_response:1; }","duration":"150.205387ms","start":"2026-04-20T12:01:14.632509Z","end":"2026-04-20T12:01:14.782714Z","steps":["trace[1438746519] 'process raft request' (duration: 149.976142ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:02:19.354375Z","caller":"traceutil/trace.go:171","msg":"trace[1128096966] transaction","detail":"{read_only:false; response_revision:3876; number_of_response:1; }","duration":"164.279922ms","start":"2026-04-20T12:02:19.190052Z","end":"2026-04-20T12:02:19.354332Z","steps":["trace[1128096966] 'process raft request' (duration: 128.350781ms)","trace[1128096966] 'compare' (duration: 35.729727ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T12:02:30.240648Z","caller":"traceutil/trace.go:171","msg":"trace[1244329044] linearizableReadLoop","detail":"{readStateIndex:4548; appliedIndex:4546; }","duration":"203.138418ms","start":"2026-04-20T12:02:30.037495Z","end":"2026-04-20T12:02:30.240633Z","steps":["trace[1244329044] 'read index received' (duration: 34.097394ms)","trace[1244329044] 'applied index is now lower than readState.Index' (duration: 169.040254ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T12:02:30.240738Z","caller":"traceutil/trace.go:171","msg":"trace[1096902378] transaction","detail":"{read_only:false; response_revision:4365; number_of_response:1; }","duration":"192.93416ms","start":"2026-04-20T12:02:30.047798Z","end":"2026-04-20T12:02:30.240732Z","steps":["trace[1096902378] 'process raft request' (duration: 192.785817ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:02:30.240699Z","caller":"traceutil/trace.go:171","msg":"trace[1227094866] transaction","detail":"{read_only:false; response_revision:4363; number_of_response:1; }","duration":"203.647419ms","start":"2026-04-20T12:02:30.037008Z","end":"2026-04-20T12:02:30.240655Z","steps":["trace[1227094866] 'process raft request' (duration: 152.286568ms)","trace[1227094866] 'compare' (duration: 50.932942ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T12:02:30.240966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.457874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:8 size:66976"} {"level":"info","ts":"2026-04-20T12:02:30.240991Z","caller":"traceutil/trace.go:171","msg":"trace[830781714] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:8; response_revision:4365; }","duration":"203.513246ms","start":"2026-04-20T12:02:30.037472Z","end":"2026-04-20T12:02:30.240985Z","steps":["trace[830781714] 'agreement among raft nodes before linearized reading' (duration: 203.378643ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:02:30.240705Z","caller":"traceutil/trace.go:171","msg":"trace[629074465] transaction","detail":"{read_only:false; response_revision:4364; number_of_response:1; }","duration":"203.474775ms","start":"2026-04-20T12:02:30.037173Z","end":"2026-04-20T12:02:30.240648Z","steps":["trace[629074465] 'process raft request' (duration: 203.334253ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:02:30.241796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.185288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0\" ","response":"range_response_count:1 size:650"} {"level":"info","ts":"2026-04-20T12:02:30.24183Z","caller":"traceutil/trace.go:171","msg":"trace[783305949] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0; range_end:; response_count:1; response_revision:4366; }","duration":"204.231618ms","start":"2026-04-20T12:02:30.037589Z","end":"2026-04-20T12:02:30.24182Z","steps":["trace[783305949] 'agreement among raft nodes before linearized reading' (duration: 204.138647ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:02:31.033754Z","caller":"traceutil/trace.go:171","msg":"trace[25464610] linearizableReadLoop","detail":"{readStateIndex:4570; appliedIndex:4569; }","duration":"231.173174ms","start":"2026-04-20T12:02:30.802551Z","end":"2026-04-20T12:02:31.033724Z","steps":["trace[25464610] 'read index received' (duration: 195.029ms)","trace[25464610] 'applied index is now lower than readState.Index' (duration: 36.142904ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T12:02:31.034066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.49602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheuses/monitoring/kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:7465"} {"level":"info","ts":"2026-04-20T12:02:31.034113Z","caller":"traceutil/trace.go:171","msg":"trace[1999369583] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheuses/monitoring/kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:4385; }","duration":"231.591342ms","start":"2026-04-20T12:02:30.80251Z","end":"2026-04-20T12:02:31.034101Z","steps":["trace[1999369583] 'agreement among raft nodes before linearized reading' (duration: 231.320357ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:02:31.034067Z","caller":"traceutil/trace.go:171","msg":"trace[1841894218] transaction","detail":"{read_only:false; response_revision:4385; number_of_response:1; }","duration":"234.635251ms","start":"2026-04-20T12:02:30.799399Z","end":"2026-04-20T12:02:31.034034Z","steps":["trace[1841894218] 'process raft request' (duration: 198.1486ms)","trace[1841894218] 'compare' (duration: 35.998542ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T12:02:33.764324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.336839ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T12:02:33.764558Z","caller":"traceutil/trace.go:171","msg":"trace[2074985944] linearizableReadLoop","detail":"{readStateIndex:4644; appliedIndex:4641; }","duration":"139.249982ms","start":"2026-04-20T12:02:33.62529Z","end":"2026-04-20T12:02:33.76454Z","steps":["trace[2074985944] 'read index received' (duration: 26.103819ms)","trace[2074985944] 'applied index is now lower than readState.Index' (duration: 113.145333ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T12:02:33.764658Z","caller":"traceutil/trace.go:171","msg":"trace[1137099194] transaction","detail":"{read_only:false; response_revision:4454; number_of_response:1; }","duration":"418.733849ms","start":"2026-04-20T12:02:33.345905Z","end":"2026-04-20T12:02:33.764639Z","steps":["trace[1137099194] 'process raft request' (duration: 418.530144ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:02:33.764714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T12:02:33.345853Z","time spent":"418.835071ms","remote":"127.0.0.1:54356","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1298,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T12:02:33.764825Z","caller":"traceutil/trace.go:171","msg":"trace[1844539887] transaction","detail":"{read_only:false; response_revision:4455; number_of_response:1; }","duration":"418.860671ms","start":"2026-04-20T12:02:33.345956Z","end":"2026-04-20T12:02:33.764816Z","steps":["trace[1844539887] 'process raft request' (duration: 418.552685ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:02:33.764868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T12:02:33.34593Z","time spent":"418.920143ms","remote":"127.0.0.1:54356","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1224,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T12:02:33.765064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.781354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:525"} {"level":"info","ts":"2026-04-20T12:02:33.765115Z","caller":"traceutil/trace.go:171","msg":"trace[1428849927] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:4455; }","duration":"139.851254ms","start":"2026-04-20T12:02:33.625238Z","end":"2026-04-20T12:02:33.76509Z","steps":["trace[1428849927] 'agreement among raft nodes before linearized reading' (duration: 139.697272ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:02:33.765057Z","caller":"traceutil/trace.go:171","msg":"trace[2016350559] transaction","detail":"{read_only:false; response_revision:4453; number_of_response:1; }","duration":"419.484963ms","start":"2026-04-20T12:02:33.345531Z","end":"2026-04-20T12:02:33.765016Z","steps":["trace[2016350559] 'process raft request' (duration: 212.373208ms)","trace[2016350559] 'compare' (duration: 206.072785ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T12:02:33.765278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T12:02:33.345518Z","time spent":"419.652207ms","remote":"127.0.0.1:54244","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":131112,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T12:02:33.765334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.376861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-20T12:02:33.765362Z","caller":"traceutil/trace.go:171","msg":"trace[118152110] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4455; }","duration":"127.441722ms","start":"2026-04-20T12:02:33.637912Z","end":"2026-04-20T12:02:33.765353Z","steps":["trace[118152110] 'agreement among raft nodes before linearized reading' (duration: 127.363541ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:02:33.765701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.832009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-04-20T12:02:33.76575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.86115ms","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-20T12:02:33.765796Z","caller":"traceutil/trace.go:171","msg":"trace[258598736] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4455; }","duration":"111.004432ms","start":"2026-04-20T12:02:33.65477Z","end":"2026-04-20T12:02:33.765775Z","steps":["trace[258598736] 'agreement among raft nodes before linearized reading' (duration: 110.740917ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:02:33.765842Z","caller":"traceutil/trace.go:171","msg":"trace[1103276089] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4455; }","duration":"110.976702ms","start":"2026-04-20T12:02:33.654841Z","end":"2026-04-20T12:02:33.765818Z","steps":["trace[1103276089] 'agreement among raft nodes before linearized reading' (duration: 110.641356ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:02:36.814904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.468255ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-04-20T12:02:36.815071Z","caller":"traceutil/trace.go:171","msg":"trace[756745940] linearizableReadLoop","detail":"{readStateIndex:4773; appliedIndex:4772; }","duration":"523.858057ms","start":"2026-04-20T12:02:36.291166Z","end":"2026-04-20T12:02:36.815024Z","steps":["trace[756745940] 'read index received' (duration: 128.30626ms)","trace[756745940] 'applied index is now lower than readState.Index' (duration: 395.550027ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T12:02:36.815707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"524.526051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:25 size:781816"} {"level":"info","ts":"2026-04-20T12:02:36.815771Z","caller":"traceutil/trace.go:171","msg":"trace[1607556142] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:25; response_revision:4580; }","duration":"524.640753ms","start":"2026-04-20T12:02:36.291114Z","end":"2026-04-20T12:02:36.815754Z","steps":["trace[1607556142] 'agreement among raft nodes before linearized reading' (duration: 524.043011ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:02:36.815831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T12:02:36.291089Z","time spent":"524.725635ms","remote":"127.0.0.1:54282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":25,"response size":781838,"request content":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" "} {"level":"warn","ts":"2026-04-20T12:02:36.910919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"587.220983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:1219"} {"level":"warn","ts":"2026-04-20T12:02:36.910952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"481.568483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T12:02:36.91101Z","caller":"traceutil/trace.go:171","msg":"trace[1853043385] transaction","detail":"{read_only:false; response_revision:4581; number_of_response:1; }","duration":"480.595326ms","start":"2026-04-20T12:02:36.430396Z","end":"2026-04-20T12:02:36.910991Z","steps":["trace[1853043385] 'process raft request' (duration: 480.237958ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:02:36.91104Z","caller":"traceutil/trace.go:171","msg":"trace[1115956758] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:4581; }","duration":"481.722416ms","start":"2026-04-20T12:02:36.429293Z","end":"2026-04-20T12:02:36.911016Z","steps":["trace[1115956758] 'agreement among raft nodes before linearized reading' (duration: 481.510502ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:02:36.91105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.224467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:645"} {"level":"warn","ts":"2026-04-20T12:02:36.911091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"587.352065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/vector\" ","response":"range_response_count:1 size:894"} {"level":"warn","ts":"2026-04-20T12:02:36.911052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.842223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:660"} {"level":"warn","ts":"2026-04-20T12:02:36.911101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T12:02:36.429257Z","time spent":"481.832828ms","remote":"127.0.0.1:54742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":41,"response size":29,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true "} {"level":"info","ts":"2026-04-20T12:02:36.911127Z","caller":"traceutil/trace.go:171","msg":"trace[450142792] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/vector; range_end:; response_count:1; response_revision:4581; }","duration":"587.433817ms","start":"2026-04-20T12:02:36.323685Z","end":"2026-04-20T12:02:36.911118Z","steps":["trace[450142792] 'agreement among raft nodes before linearized reading' (duration: 587.347775ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:02:36.911159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T12:02:36.323663Z","time spent":"587.489268ms","remote":"127.0.0.1:54370","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":916,"request content":"key:\"/registry/serviceaccounts/monitoring/vector\" "} {"level":"info","ts":"2026-04-20T12:02:36.911154Z","caller":"traceutil/trace.go:171","msg":"trace[1384015410] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4581; }","duration":"298.980676ms","start":"2026-04-20T12:02:36.61215Z","end":"2026-04-20T12:02:36.911131Z","steps":["trace[1384015410] 'agreement among raft nodes before linearized reading' (duration: 298.785493ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:02:36.911012Z","caller":"traceutil/trace.go:171","msg":"trace[1488936585] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:4581; }","duration":"587.362186ms","start":"2026-04-20T12:02:36.323629Z","end":"2026-04-20T12:02:36.910991Z","steps":["trace[1488936585] 'agreement among raft nodes before linearized reading' (duration: 587.145102ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:02:36.911275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T12:02:36.32361Z","time spent":"587.656481ms","remote":"127.0.0.1:54370","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":1241,"request content":"key:\"/registry/serviceaccounts/monitoring/kube-prometheus-stack-prometheus\" "} {"level":"info","ts":"2026-04-20T12:02:36.911094Z","caller":"traceutil/trace.go:171","msg":"trace[66989914] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4581; }","duration":"343.330769ms","start":"2026-04-20T12:02:36.567753Z","end":"2026-04-20T12:02:36.911083Z","steps":["trace[66989914] 'agreement among raft nodes before linearized reading' (duration: 343.205087ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:02:36.911384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T12:02:36.567719Z","time spent":"343.657236ms","remote":"127.0.0.1:54298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":667,"request content":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-04-20T12:02:36.911355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"560.892149ms","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":"warn","ts":"2026-04-20T12:02:36.911423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"575.017294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-2b09c25b-099f-4808-a5e6-ebda22c4ea45\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-04-20T12:02:36.911578Z","caller":"traceutil/trace.go:171","msg":"trace[895182655] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-2b09c25b-099f-4808-a5e6-ebda22c4ea45; range_end:; response_count:1; response_revision:4581; }","duration":"575.233779ms","start":"2026-04-20T12:02:36.33629Z","end":"2026-04-20T12:02:36.911524Z","steps":["trace[895182655] 'agreement among raft nodes before linearized reading' (duration: 574.737389ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:02:36.910964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.110647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-fktnl\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-04-20T12:02:36.911651Z","caller":"traceutil/trace.go:171","msg":"trace[702103287] range","detail":"{range_begin:/registry/pods/openstack/keepalived-fktnl; range_end:; response_count:1; response_revision:4581; }","duration":"244.854801ms","start":"2026-04-20T12:02:36.666786Z","end":"2026-04-20T12:02:36.911641Z","steps":["trace[702103287] 'agreement among raft nodes before linearized reading' (duration: 244.098757ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:02:36.911647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T12:02:36.336263Z","time spent":"575.370322ms","remote":"127.0.0.1:54346","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3623,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-2b09c25b-099f-4808-a5e6-ebda22c4ea45\" "} {"level":"info","ts":"2026-04-20T12:02:36.91148Z","caller":"traceutil/trace.go:171","msg":"trace[1778851799] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:4581; }","duration":"561.085523ms","start":"2026-04-20T12:02:36.35037Z","end":"2026-04-20T12:02:36.911456Z","steps":["trace[1778851799] 'agreement among raft nodes before linearized reading' (duration: 560.728607ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:02:36.911717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T12:02:36.350362Z","time spent":"561.340038ms","remote":"127.0.0.1:34926","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-20T12:02:36.911108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T12:02:36.430371Z","time spent":"480.674117ms","remote":"127.0.0.1:54446","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T12:02:36.911083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"568.824204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-2b09c25b-099f-4808-a5e6-ebda22c4ea45\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-04-20T12:02:36.911876Z","caller":"traceutil/trace.go:171","msg":"trace[230141564] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-2b09c25b-099f-4808-a5e6-ebda22c4ea45; range_end:; response_count:1; response_revision:4581; }","duration":"569.64557ms","start":"2026-04-20T12:02:36.342208Z","end":"2026-04-20T12:02:36.911854Z","steps":["trace[230141564] 'agreement among raft nodes before linearized reading' (duration: 568.766343ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:02:36.911945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T12:02:36.342197Z","time spent":"569.730541ms","remote":"127.0.0.1:54346","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3623,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-2b09c25b-099f-4808-a5e6-ebda22c4ea45\" "} {"level":"info","ts":"2026-04-20T12:02:37.365468Z","caller":"traceutil/trace.go:171","msg":"trace[1370450540] transaction","detail":"{read_only:false; response_revision:4587; number_of_response:1; }","duration":"124.042927ms","start":"2026-04-20T12:02:37.241399Z","end":"2026-04-20T12:02:37.365442Z","steps":["trace[1370450540] 'process raft request' (duration: 123.901834ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:02:37.49754Z","caller":"traceutil/trace.go:171","msg":"trace[1207312758] transaction","detail":"{read_only:false; response_revision:4588; number_of_response:1; }","duration":"210.806028ms","start":"2026-04-20T12:02:37.286708Z","end":"2026-04-20T12:02:37.497514Z","steps":["trace[1207312758] 'process raft request' (duration: 210.655155ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:02:39.855082Z","caller":"traceutil/trace.go:171","msg":"trace[1588760029] transaction","detail":"{read_only:false; response_revision:4640; number_of_response:1; }","duration":"185.865401ms","start":"2026-04-20T12:02:39.669173Z","end":"2026-04-20T12:02:39.855039Z","steps":["trace[1588760029] 'process raft request' (duration: 153.947579ms)","trace[1588760029] 'compare' (duration: 31.717858ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T12:02:43.339871Z","caller":"traceutil/trace.go:171","msg":"trace[607505281] transaction","detail":"{read_only:false; response_revision:4708; number_of_response:1; }","duration":"179.847484ms","start":"2026-04-20T12:02:43.159992Z","end":"2026-04-20T12:02:43.33984Z","steps":["trace[607505281] 'process raft request' (duration: 178.61386ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:02:50.145211Z","caller":"traceutil/trace.go:171","msg":"trace[209265892] linearizableReadLoop","detail":"{readStateIndex:5030; appliedIndex:5029; }","duration":"173.806556ms","start":"2026-04-20T12:02:49.97138Z","end":"2026-04-20T12:02:50.145186Z","steps":["trace[209265892] 'read index received' (duration: 77.286886ms)","trace[209265892] 'applied index is now lower than readState.Index' (duration: 96.5187ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T12:02:50.145515Z","caller":"traceutil/trace.go:171","msg":"trace[579481754] transaction","detail":"{read_only:false; response_revision:4827; number_of_response:1; }","duration":"174.220984ms","start":"2026-04-20T12:02:49.971278Z","end":"2026-04-20T12:02:50.145499Z","steps":["trace[579481754] 'process raft request' (duration: 144.317932ms)","trace[579481754] 'compare' (duration: 29.419353ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T12:02:50.145905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.056485ms","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-20T12:02:50.145962Z","caller":"traceutil/trace.go:171","msg":"trace[672301651] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4828; }","duration":"146.144578ms","start":"2026-04-20T12:02:49.999803Z","end":"2026-04-20T12:02:50.145948Z","steps":["trace[672301651] 'agreement among raft nodes before linearized reading' (duration: 145.993645ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:02:50.146009Z","caller":"traceutil/trace.go:171","msg":"trace[721531021] transaction","detail":"{read_only:false; response_revision:4828; number_of_response:1; }","duration":"170.817588ms","start":"2026-04-20T12:02:49.975147Z","end":"2026-04-20T12:02:50.145965Z","steps":["trace[721531021] 'process raft request' (duration: 169.992133ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:02:50.14609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.709673ms","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-20T12:02:50.146119Z","caller":"traceutil/trace.go:171","msg":"trace[1772971773] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4828; }","duration":"174.737874ms","start":"2026-04-20T12:02:49.971372Z","end":"2026-04-20T12:02:50.146109Z","steps":["trace[1772971773] 'agreement among raft nodes before linearized reading' (duration: 174.666242ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:02:51.077868Z","caller":"traceutil/trace.go:171","msg":"trace[1169907329] linearizableReadLoop","detail":"{readStateIndex:5034; appliedIndex:5033; }","duration":"438.526364ms","start":"2026-04-20T12:02:50.639316Z","end":"2026-04-20T12:02:51.077842Z","steps":["trace[1169907329] 'read index received' (duration: 438.386782ms)","trace[1169907329] 'applied index is now lower than readState.Index' (duration: 138.302µs)"],"step_count":2} {"level":"warn","ts":"2026-04-20T12:02:51.078067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.722319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/permissions/\" range_end:\"/registry/rabbitmq.com/permissions0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T12:02:51.078107Z","caller":"traceutil/trace.go:171","msg":"trace[1721215628] range","detail":"{range_begin:/registry/rabbitmq.com/permissions/; range_end:/registry/rabbitmq.com/permissions0; response_count:0; response_revision:4831; }","duration":"438.82008ms","start":"2026-04-20T12:02:50.639276Z","end":"2026-04-20T12:02:51.078096Z","steps":["trace[1721215628] 'agreement among raft nodes before linearized reading' (duration: 438.704518ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:02:51.078149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T12:02:50.639254Z","time spent":"438.884892ms","remote":"127.0.0.1:50778","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":27,"request content":"key:\"/registry/rabbitmq.com/permissions/\" range_end:\"/registry/rabbitmq.com/permissions0\" count_only:true "} {"level":"info","ts":"2026-04-20T12:02:51.078035Z","caller":"traceutil/trace.go:171","msg":"trace[110784998] transaction","detail":"{read_only:false; response_revision:4831; number_of_response:1; }","duration":"598.340068ms","start":"2026-04-20T12:02:50.479657Z","end":"2026-04-20T12:02:51.077997Z","steps":["trace[110784998] 'process raft request' (duration: 598.028032ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:02:51.078362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T12:02:50.479637Z","time spent":"598.637804ms","remote":"127.0.0.1:54346","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8036,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T12:02:51.123969Z","caller":"traceutil/trace.go:171","msg":"trace[164666519] transaction","detail":"{read_only:false; response_revision:4832; number_of_response:1; }","duration":"273.609912ms","start":"2026-04-20T12:02:50.850333Z","end":"2026-04-20T12:02:51.123943Z","steps":["trace[164666519] 'process raft request' (duration: 264.035065ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:02:51.124141Z","caller":"traceutil/trace.go:171","msg":"trace[1405816679] transaction","detail":"{read_only:false; response_revision:4833; number_of_response:1; }","duration":"120.740023ms","start":"2026-04-20T12:02:51.003373Z","end":"2026-04-20T12:02:51.124113Z","steps":["trace[1405816679] 'process raft request' (duration: 120.523209ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:02:51.124416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.11347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-04-20T12:02:51.124521Z","caller":"traceutil/trace.go:171","msg":"trace[1568891055] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:4833; }","duration":"199.253053ms","start":"2026-04-20T12:02:50.92524Z","end":"2026-04-20T12:02:51.124493Z","steps":["trace[1568891055] 'agreement among raft nodes before linearized reading' (duration: 199.024079ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:02:51.124552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.725992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.153\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-20T12:02:51.124623Z","caller":"traceutil/trace.go:171","msg":"trace[2011794928] range","detail":"{range_begin:/registry/masterleases/199.19.213.153; range_end:; response_count:1; response_revision:4833; }","duration":"374.985007ms","start":"2026-04-20T12:02:50.749616Z","end":"2026-04-20T12:02:51.124601Z","steps":["trace[2011794928] 'agreement among raft nodes before linearized reading' (duration: 374.659271ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:02:51.124717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T12:02:50.749589Z","time spent":"375.110759ms","remote":"127.0.0.1:54222","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.19.213.153\" "} {"level":"info","ts":"2026-04-20T12:02:52.795229Z","caller":"traceutil/trace.go:171","msg":"trace[1526155572] transaction","detail":"{read_only:false; response_revision:4859; number_of_response:1; }","duration":"303.671366ms","start":"2026-04-20T12:02:52.491507Z","end":"2026-04-20T12:02:52.795179Z","steps":["trace[1526155572] 'process raft request' (duration: 303.495703ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:02:52.795394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T12:02:52.491484Z","time spent":"303.826131ms","remote":"127.0.0.1:54346","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6189,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T12:02:55.453493Z","caller":"traceutil/trace.go:171","msg":"trace[965827569] transaction","detail":"{read_only:false; response_revision:4908; number_of_response:1; }","duration":"103.788272ms","start":"2026-04-20T12:02:55.349682Z","end":"2026-04-20T12:02:55.45347Z","steps":["trace[965827569] 'process raft request' (duration: 92.023543ms)","trace[965827569] 'compare' (duration: 11.680158ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T12:02:55.455239Z","caller":"traceutil/trace.go:171","msg":"trace[2120921140] transaction","detail":"{read_only:false; number_of_response:1; response_revision:4908; }","duration":"104.868023ms","start":"2026-04-20T12:02:55.350345Z","end":"2026-04-20T12:02:55.455213Z","steps":["trace[2120921140] 'process raft request' (duration: 104.791452ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:02:57.951325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.070241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T12:02:57.951456Z","caller":"traceutil/trace.go:171","msg":"trace[1806934939] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5006; }","duration":"157.266274ms","start":"2026-04-20T12:02:57.794167Z","end":"2026-04-20T12:02:57.951433Z","steps":["trace[1806934939] 'range keys from in-memory index tree' (duration: 156.975878ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:02:57.951614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.329452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f692c9d8-7910-48cb-8a93-41846eefe22a\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-04-20T12:02:57.951705Z","caller":"traceutil/trace.go:171","msg":"trace[301849865] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-f692c9d8-7910-48cb-8a93-41846eefe22a; range_end:; response_count:1; response_revision:5006; }","duration":"144.465385ms","start":"2026-04-20T12:02:57.80722Z","end":"2026-04-20T12:02:57.951685Z","steps":["trace[301849865] 'range keys from in-memory index tree' (duration: 144.147579ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:02:58.461716Z","caller":"traceutil/trace.go:171","msg":"trace[868804672] linearizableReadLoop","detail":"{readStateIndex:5216; appliedIndex:5215; }","duration":"280.366004ms","start":"2026-04-20T12:02:58.181322Z","end":"2026-04-20T12:02:58.461688Z","steps":["trace[868804672] 'read index received' (duration: 280.118449ms)","trace[868804672] 'applied index is now lower than readState.Index' (duration: 246.455µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T12:02:58.46184Z","caller":"traceutil/trace.go:171","msg":"trace[235668931] transaction","detail":"{read_only:false; response_revision:5007; number_of_response:1; }","duration":"372.805905ms","start":"2026-04-20T12:02:58.089022Z","end":"2026-04-20T12:02:58.461828Z","steps":["trace[235668931] 'process raft request' (duration: 372.478338ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:02:58.461948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T12:02:58.089002Z","time spent":"372.865686ms","remote":"127.0.0.1:54446","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T12:02:58.462169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.445878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:524"} {"level":"info","ts":"2026-04-20T12:02:58.462249Z","caller":"traceutil/trace.go:171","msg":"trace[1195767458] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:5007; }","duration":"275.552689ms","start":"2026-04-20T12:02:58.186676Z","end":"2026-04-20T12:02:58.462229Z","steps":["trace[1195767458] 'agreement among raft nodes before linearized reading' (duration: 275.339675ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:02:58.462359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.716946ms","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-20T12:02:58.462438Z","caller":"traceutil/trace.go:171","msg":"trace[1001832289] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5007; }","duration":"230.850208ms","start":"2026-04-20T12:02:58.231568Z","end":"2026-04-20T12:02:58.462419Z","steps":["trace[1001832289] 'agreement among raft nodes before linearized reading' (duration: 230.600973ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:02:58.46246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.141979ms","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-20T12:02:58.46249Z","caller":"traceutil/trace.go:171","msg":"trace[1278719322] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5007; }","duration":"281.24652ms","start":"2026-04-20T12:02:58.181235Z","end":"2026-04-20T12:02:58.462481Z","steps":["trace[1278719322] 'agreement among raft nodes before linearized reading' (duration: 281.161848ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:02:58.462528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.704364ms","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-20T12:02:58.462609Z","caller":"traceutil/trace.go:171","msg":"trace[1012954429] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5007; }","duration":"269.828067ms","start":"2026-04-20T12:02:58.192761Z","end":"2026-04-20T12:02:58.462589Z","steps":["trace[1012954429] 'agreement among raft nodes before linearized reading' (duration: 269.552412ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:02:58.870592Z","caller":"traceutil/trace.go:171","msg":"trace[1627767860] transaction","detail":"{read_only:false; response_revision:5008; number_of_response:1; }","duration":"403.623665ms","start":"2026-04-20T12:02:58.46692Z","end":"2026-04-20T12:02:58.870544Z","steps":["trace[1627767860] 'process raft request' (duration: 403.37647ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:02:58.870865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T12:02:58.466892Z","time spent":"403.823788ms","remote":"127.0.0.1:54446","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T12:02:59.078276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.166373ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T12:02:59.078575Z","caller":"traceutil/trace.go:171","msg":"trace[141825097] linearizableReadLoop","detail":"{readStateIndex:5220; appliedIndex:5216; }","duration":"541.893009ms","start":"2026-04-20T12:02:58.536656Z","end":"2026-04-20T12:02:59.078549Z","steps":["trace[141825097] 'read index received' (duration: 333.558289ms)","trace[141825097] 'applied index is now lower than readState.Index' (duration: 208.3333ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T12:02:59.078578Z","caller":"traceutil/trace.go:171","msg":"trace[226126267] transaction","detail":"{read_only:false; response_revision:5009; number_of_response:1; }","duration":"611.388363ms","start":"2026-04-20T12:02:58.467114Z","end":"2026-04-20T12:02:59.078502Z","steps":["trace[226126267] 'process raft request' (duration: 435.867163ms)","trace[226126267] 'compare' (duration: 174.937299ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T12:02:59.078771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T12:02:58.467092Z","time spent":"611.573016ms","remote":"127.0.0.1:54446","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T12:02:59.078717Z","caller":"traceutil/trace.go:171","msg":"trace[967055668] transaction","detail":"{read_only:false; response_revision:5011; number_of_response:1; }","duration":"610.513165ms","start":"2026-04-20T12:02:58.468158Z","end":"2026-04-20T12:02:59.078671Z","steps":["trace[967055668] 'process raft request' (duration: 610.342432ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:02:59.078892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"542.221765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f692c9d8-7910-48cb-8a93-41846eefe22a\" ","response":"range_response_count:1 size:3646"} {"level":"warn","ts":"2026-04-20T12:02:59.078907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.480663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T12:02:59.078917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.8967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T12:02:59.078963Z","caller":"traceutil/trace.go:171","msg":"trace[203954740] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-f692c9d8-7910-48cb-8a93-41846eefe22a; range_end:; response_count:1; response_revision:5011; }","duration":"542.339316ms","start":"2026-04-20T12:02:58.536606Z","end":"2026-04-20T12:02:59.078945Z","steps":["trace[203954740] 'agreement among raft nodes before linearized reading' (duration: 542.068201ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:02:59.078977Z","caller":"traceutil/trace.go:171","msg":"trace[1366672064] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5011; }","duration":"284.575334ms","start":"2026-04-20T12:02:58.794384Z","end":"2026-04-20T12:02:59.078959Z","steps":["trace[1366672064] 'agreement among raft nodes before linearized reading' (duration: 284.474192ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:02:59.078961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T12:02:58.46814Z","time spent":"610.75125ms","remote":"127.0.0.1:54446","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T12:02:59.07901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T12:02:58.53659Z","time spent":"542.407788ms","remote":"127.0.0.1:54346","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3668,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f692c9d8-7910-48cb-8a93-41846eefe22a\" "} {"level":"info","ts":"2026-04-20T12:02:59.079053Z","caller":"traceutil/trace.go:171","msg":"trace[22754344] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:5011; }","duration":"209.995582ms","start":"2026-04-20T12:02:58.868974Z","end":"2026-04-20T12:02:59.078969Z","steps":["trace[22754344] 'agreement among raft nodes before linearized reading' (duration: 209.807168ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:02:59.079096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.697956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11499"} {"level":"info","ts":"2026-04-20T12:02:59.07881Z","caller":"traceutil/trace.go:171","msg":"trace[1013502849] transaction","detail":"{read_only:false; response_revision:5010; number_of_response:1; }","duration":"611.218079ms","start":"2026-04-20T12:02:58.467557Z","end":"2026-04-20T12:02:59.078775Z","steps":["trace[1013502849] 'process raft request' (duration: 610.849082ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:02:59.079169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.369955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f692c9d8-7910-48cb-8a93-41846eefe22a\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-04-20T12:02:59.079202Z","caller":"traceutil/trace.go:171","msg":"trace[957585112] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5011; }","duration":"250.783097ms","start":"2026-04-20T12:02:58.828359Z","end":"2026-04-20T12:02:59.079142Z","steps":["trace[957585112] 'agreement among raft nodes before linearized reading' (duration: 250.46647ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:02:59.079312Z","caller":"traceutil/trace.go:171","msg":"trace[1376898103] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-f692c9d8-7910-48cb-8a93-41846eefe22a; range_end:; response_count:1; response_revision:5011; }","duration":"122.607339ms","start":"2026-04-20T12:02:58.956662Z","end":"2026-04-20T12:02:59.079269Z","steps":["trace[1376898103] 'agreement among raft nodes before linearized reading' (duration: 122.140439ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:02:59.079378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T12:02:58.467531Z","time spent":"611.691548ms","remote":"127.0.0.1:54446","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T12:02:59.079664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.299576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3198"} {"level":"info","ts":"2026-04-20T12:02:59.079732Z","caller":"traceutil/trace.go:171","msg":"trace[79087257] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:5011; }","duration":"291.425579ms","start":"2026-04-20T12:02:58.788292Z","end":"2026-04-20T12:02:59.079718Z","steps":["trace[79087257] 'agreement among raft nodes before linearized reading' (duration: 290.46922ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:02:59.278722Z","caller":"traceutil/trace.go:171","msg":"trace[1985471591] linearizableReadLoop","detail":"{readStateIndex:5221; appliedIndex:5220; }","duration":"190.878709ms","start":"2026-04-20T12:02:59.087816Z","end":"2026-04-20T12:02:59.278695Z","steps":["trace[1985471591] 'read index received' (duration: 190.783217ms)","trace[1985471591] 'applied index is now lower than readState.Index' (duration: 94.292µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T12:02:59.278876Z","caller":"traceutil/trace.go:171","msg":"trace[1359397029] transaction","detail":"{read_only:false; response_revision:5012; number_of_response:1; }","duration":"191.94646ms","start":"2026-04-20T12:02:59.086885Z","end":"2026-04-20T12:02:59.278831Z","steps":["trace[1359397029] 'process raft request' (duration: 191.639265ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:02:59.279001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.156894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/rabbitmq-keystone-nodes\" ","response":"range_response_count:1 size:1265"} {"level":"info","ts":"2026-04-20T12:02:59.279062Z","caller":"traceutil/trace.go:171","msg":"trace[302983384] range","detail":"{range_begin:/registry/services/specs/openstack/rabbitmq-keystone-nodes; range_end:; response_count:1; response_revision:5012; }","duration":"191.246546ms","start":"2026-04-20T12:02:59.087802Z","end":"2026-04-20T12:02:59.279049Z","steps":["trace[302983384] 'agreement among raft nodes before linearized reading' (duration: 191.039063ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:02:59.292112Z","caller":"traceutil/trace.go:171","msg":"trace[377659116] transaction","detail":"{read_only:false; response_revision:5013; number_of_response:1; }","duration":"165.093987ms","start":"2026-04-20T12:02:59.126986Z","end":"2026-04-20T12:02:59.29208Z","steps":["trace[377659116] 'process raft request' (duration: 164.840631ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:02:59.292146Z","caller":"traceutil/trace.go:171","msg":"trace[1244011903] transaction","detail":"{read_only:false; response_revision:5014; number_of_response:1; }","duration":"138.845386ms","start":"2026-04-20T12:02:59.153277Z","end":"2026-04-20T12:02:59.292122Z","steps":["trace[1244011903] 'process raft request' (duration: 138.697263ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:02:59.292583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.398455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-fktnl\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-04-20T12:02:59.292631Z","caller":"traceutil/trace.go:171","msg":"trace[1018476692] range","detail":"{range_begin:/registry/pods/openstack/keepalived-fktnl; range_end:; response_count:1; response_revision:5014; }","duration":"103.497787ms","start":"2026-04-20T12:02:59.189122Z","end":"2026-04-20T12:02:59.29262Z","steps":["trace[1018476692] 'agreement among raft nodes before linearized reading' (duration: 102.944837ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:03:09.44073Z","caller":"traceutil/trace.go:171","msg":"trace[2121322230] transaction","detail":"{read_only:false; response_revision:5151; number_of_response:1; }","duration":"106.588117ms","start":"2026-04-20T12:03:09.334097Z","end":"2026-04-20T12:03:09.440686Z","steps":["trace[2121322230] 'process raft request' (duration: 106.326402ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:03:09.765212Z","caller":"traceutil/trace.go:171","msg":"trace[783543451] transaction","detail":"{read_only:false; response_revision:5157; number_of_response:1; }","duration":"184.654019ms","start":"2026-04-20T12:03:09.580506Z","end":"2026-04-20T12:03:09.76516Z","steps":["trace[783543451] 'process raft request' (duration: 184.382834ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:03:09.881922Z","caller":"traceutil/trace.go:171","msg":"trace[2049266177] transaction","detail":"{read_only:false; response_revision:5158; number_of_response:1; }","duration":"104.174959ms","start":"2026-04-20T12:03:09.77771Z","end":"2026-04-20T12:03:09.881885Z","steps":["trace[2049266177] 'process raft request' (duration: 95.825067ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:03:45.995305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.919368ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T12:03:45.995423Z","caller":"traceutil/trace.go:171","msg":"trace[1000049782] linearizableReadLoop","detail":"{readStateIndex:5762; appliedIndex:5761; }","duration":"200.786464ms","start":"2026-04-20T12:03:45.794619Z","end":"2026-04-20T12:03:45.995405Z","steps":["trace[1000049782] 'read index received' (duration: 33.5957ms)","trace[1000049782] 'applied index is now lower than readState.Index' (duration: 167.189344ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T12:03:45.995554Z","caller":"traceutil/trace.go:171","msg":"trace[799969037] transaction","detail":"{read_only:false; response_revision:5533; number_of_response:1; }","duration":"236.32736ms","start":"2026-04-20T12:03:45.759202Z","end":"2026-04-20T12:03:45.995529Z","steps":["trace[799969037] 'process raft request' (duration: 69.094316ms)","trace[799969037] 'compare' (duration: 166.718614ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T12:03:45.995773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.15001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T12:03:45.995851Z","caller":"traceutil/trace.go:171","msg":"trace[2072183453] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5533; }","duration":"201.256151ms","start":"2026-04-20T12:03:45.794576Z","end":"2026-04-20T12:03:45.995832Z","steps":["trace[2072183453] 'agreement among raft nodes before linearized reading' (duration: 201.134899ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:03:46.695963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"606.350766ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-04-20T12:03:46.696123Z","caller":"traceutil/trace.go:171","msg":"trace[1669273974] linearizableReadLoop","detail":"{readStateIndex:5763; appliedIndex:5762; }","duration":"700.614861ms","start":"2026-04-20T12:03:45.995467Z","end":"2026-04-20T12:03:46.696082Z","steps":["trace[1669273974] 'read index received' (duration: 94.068681ms)","trace[1669273974] 'applied index is now lower than readState.Index' (duration: 606.5438ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T12:03:46.696385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"843.777308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-db-sync\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T12:03:46.696414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"804.885098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:660"} {"level":"info","ts":"2026-04-20T12:03:46.696439Z","caller":"traceutil/trace.go:171","msg":"trace[1574530225] range","detail":"{range_begin:/registry/jobs/openstack/keystone-db-sync; range_end:; response_count:0; response_revision:5533; }","duration":"843.862699ms","start":"2026-04-20T12:03:45.852564Z","end":"2026-04-20T12:03:46.696426Z","steps":["trace[1574530225] 'agreement among raft nodes before linearized reading' (duration: 843.768288ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:03:46.69649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"843.479563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-fernet-setup\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T12:03:46.696519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"840.328531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-credential-setup\" ","response":"range_response_count:1 size:6156"} {"level":"warn","ts":"2026-04-20T12:03:46.696571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"699.000581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T12:03:46.696606Z","caller":"traceutil/trace.go:171","msg":"trace[908202222] range","detail":"{range_begin:/registry/jobs/openstack/keystone-fernet-setup; range_end:; response_count:0; response_revision:5533; }","duration":"843.626365ms","start":"2026-04-20T12:03:45.852953Z","end":"2026-04-20T12:03:46.69658Z","steps":["trace[908202222] 'agreement among raft nodes before linearized reading' (duration: 843.448372ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:03:46.69662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"870.275183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11499"} {"level":"info","ts":"2026-04-20T12:03:46.696624Z","caller":"traceutil/trace.go:171","msg":"trace[823611736] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5533; }","duration":"699.106134ms","start":"2026-04-20T12:03:45.997502Z","end":"2026-04-20T12:03:46.696609Z","steps":["trace[823611736] 'agreement among raft nodes before linearized reading' (duration: 699.021842ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:03:46.696634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"805.910567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:645"} {"level":"info","ts":"2026-04-20T12:03:46.69667Z","caller":"traceutil/trace.go:171","msg":"trace[203125611] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5533; }","duration":"870.381025ms","start":"2026-04-20T12:03:45.826277Z","end":"2026-04-20T12:03:46.696658Z","steps":["trace[203125611] 'agreement among raft nodes before linearized reading' (duration: 870.220812ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:03:46.696672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T12:03:45.852942Z","time spent":"843.716237ms","remote":"127.0.0.1:54410","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":27,"request content":"key:\"/registry/jobs/openstack/keystone-fernet-setup\" "} {"level":"info","ts":"2026-04-20T12:03:46.696706Z","caller":"traceutil/trace.go:171","msg":"trace[1172889586] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5533; }","duration":"805.979889ms","start":"2026-04-20T12:03:45.890702Z","end":"2026-04-20T12:03:46.696682Z","steps":["trace[1172889586] 'agreement among raft nodes before linearized reading' (duration: 805.791966ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:03:46.696722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T12:03:45.826246Z","time spent":"870.465326ms","remote":"127.0.0.1:54346","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":11521,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-04-20T12:03:46.696765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T12:03:45.890612Z","time spent":"806.140481ms","remote":"127.0.0.1:54298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":667,"request content":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-04-20T12:03:46.696522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T12:03:45.852551Z","time spent":"843.954241ms","remote":"127.0.0.1:54410","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":27,"request content":"key:\"/registry/jobs/openstack/keystone-db-sync\" "} {"level":"warn","ts":"2026-04-20T12:03:46.69668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T12:03:45.997481Z","time spent":"699.190386ms","remote":"127.0.0.1:54170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-20T12:03:46.696611Z","caller":"traceutil/trace.go:171","msg":"trace[1982341790] range","detail":"{range_begin:/registry/jobs/openstack/keystone-credential-setup; range_end:; response_count:1; response_revision:5533; }","duration":"840.448914ms","start":"2026-04-20T12:03:45.856141Z","end":"2026-04-20T12:03:46.69659Z","steps":["trace[1982341790] 'agreement among raft nodes before linearized reading' (duration: 840.22203ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:03:46.696999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T12:03:45.85613Z","time spent":"840.801571ms","remote":"127.0.0.1:54410","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":6178,"request content":"key:\"/registry/jobs/openstack/keystone-credential-setup\" "} {"level":"info","ts":"2026-04-20T12:03:46.696468Z","caller":"traceutil/trace.go:171","msg":"trace[1405130466] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:5533; }","duration":"804.958189ms","start":"2026-04-20T12:03:45.891491Z","end":"2026-04-20T12:03:46.696449Z","steps":["trace[1405130466] 'agreement among raft nodes before linearized reading' (duration: 804.762575ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:03:46.696901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.00169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/\" range_end:\"/registry/rabbitmq.com/rabbitmqclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-20T12:03:46.697146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T12:03:45.891481Z","time spent":"805.645131ms","remote":"127.0.0.1:54298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":1,"response size":682,"request content":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" "} {"level":"info","ts":"2026-04-20T12:03:46.697255Z","caller":"traceutil/trace.go:171","msg":"trace[1896798720] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/; range_end:/registry/rabbitmq.com/rabbitmqclusters0; response_count:0; response_revision:5533; }","duration":"220.382086ms","start":"2026-04-20T12:03:46.476797Z","end":"2026-04-20T12:03:46.697179Z","steps":["trace[1896798720] 'agreement among raft nodes before linearized reading' (duration: 219.759445ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:03:46.699791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"904.752048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:57 size:2575989"} {"level":"info","ts":"2026-04-20T12:03:46.699849Z","caller":"traceutil/trace.go:171","msg":"trace[265616684] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:57; response_revision:5533; }","duration":"904.82483ms","start":"2026-04-20T12:03:45.795011Z","end":"2026-04-20T12:03:46.699835Z","steps":["trace[265616684] 'agreement among raft nodes before linearized reading' (duration: 901.561288ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:03:46.699886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T12:03:45.795006Z","time spent":"904.86997ms","remote":"127.0.0.1:54298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":57,"response size":2576011,"request content":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" "} {"level":"info","ts":"2026-04-20T12:03:46.816349Z","caller":"traceutil/trace.go:171","msg":"trace[378018894] transaction","detail":"{read_only:false; response_revision:5535; number_of_response:1; }","duration":"110.968872ms","start":"2026-04-20T12:03:46.705353Z","end":"2026-04-20T12:03:46.816322Z","steps":["trace[378018894] 'process raft request' (duration: 103.348578ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:03:46.816481Z","caller":"traceutil/trace.go:171","msg":"trace[2085038242] transaction","detail":"{read_only:false; response_revision:5536; number_of_response:1; }","duration":"110.877062ms","start":"2026-04-20T12:03:46.705559Z","end":"2026-04-20T12:03:46.816436Z","steps":["trace[2085038242] 'process raft request' (duration: 110.684478ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:05:56.662558Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3216} {"level":"info","ts":"2026-04-20T12:05:56.749313Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3216,"took":"85.379491ms","hash":352344274,"current-db-size-bytes":60878848,"current-db-size":"61 MB","current-db-size-in-use-bytes":33710080,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-04-20T12:05:56.749402Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":352344274,"revision":3216,"compact-revision":-1} {"level":"info","ts":"2026-04-20T12:08:52.142105Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002180.wal"} {"level":"info","ts":"2026-04-20T12:09:08.2797Z","caller":"traceutil/trace.go:171","msg":"trace[1125059619] linearizableReadLoop","detail":"{readStateIndex:8692; appliedIndex:8691; }","duration":"334.274313ms","start":"2026-04-20T12:09:07.945402Z","end":"2026-04-20T12:09:08.279676Z","steps":["trace[1125059619] 'read index received' (duration: 334.246913ms)","trace[1125059619] 'applied index is now lower than readState.Index' (duration: 26.66µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T12:09:08.279784Z","caller":"traceutil/trace.go:171","msg":"trace[1198221419] transaction","detail":"{read_only:false; response_revision:8323; number_of_response:1; }","duration":"334.64698ms","start":"2026-04-20T12:09:07.945111Z","end":"2026-04-20T12:09:08.279758Z","steps":["trace[1198221419] 'process raft request' (duration: 334.408675ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:09:08.279884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.458956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpools/\" range_end:\"/registry/ceph.rook.io/cephblockpools0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T12:09:08.279933Z","caller":"traceutil/trace.go:171","msg":"trace[680725598] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpools/; range_end:/registry/ceph.rook.io/cephblockpools0; response_count:0; response_revision:8323; }","duration":"334.526547ms","start":"2026-04-20T12:09:07.945394Z","end":"2026-04-20T12:09:08.279921Z","steps":["trace[680725598] 'agreement among raft nodes before linearized reading' (duration: 334.418116ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:09:08.279939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T12:09:07.945084Z","time spent":"334.753262ms","remote":"127.0.0.1:54446","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T12:09:08.27997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T12:09:07.945351Z","time spent":"334.610169ms","remote":"127.0.0.1:39776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":0,"response size":27,"request content":"key:\"/registry/ceph.rook.io/cephblockpools/\" range_end:\"/registry/ceph.rook.io/cephblockpools0\" limit:500 "} {"level":"info","ts":"2026-04-20T12:09:08.532052Z","caller":"traceutil/trace.go:171","msg":"trace[2047525706] linearizableReadLoop","detail":"{readStateIndex:8693; appliedIndex:8692; }","duration":"252.233108ms","start":"2026-04-20T12:09:08.279779Z","end":"2026-04-20T12:09:08.532012Z","steps":["trace[2047525706] 'read index received' (duration: 251.906602ms)","trace[2047525706] 'applied index is now lower than readState.Index' (duration: 325.136µs)"],"step_count":2} {"level":"warn","ts":"2026-04-20T12:09:08.532146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"547.474981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemmirrors/\" range_end:\"/registry/ceph.rook.io/cephfilesystemmirrors0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T12:09:08.532209Z","caller":"traceutil/trace.go:171","msg":"trace[1846536804] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemmirrors/; range_end:/registry/ceph.rook.io/cephfilesystemmirrors0; response_count:0; response_revision:8324; }","duration":"547.586324ms","start":"2026-04-20T12:09:07.984606Z","end":"2026-04-20T12:09:08.532193Z","steps":["trace[1846536804] 'agreement among raft nodes before linearized reading' (duration: 547.43342ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:09:08.5322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"547.777366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemmirrors/\" range_end:\"/registry/ceph.rook.io/cephfilesystemmirrors0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T12:09:08.532257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T12:09:07.984597Z","time spent":"547.647715ms","remote":"127.0.0.1:39798","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":0,"response size":27,"request content":"key:\"/registry/ceph.rook.io/cephfilesystemmirrors/\" range_end:\"/registry/ceph.rook.io/cephfilesystemmirrors0\" limit:500 "} {"level":"info","ts":"2026-04-20T12:09:08.532297Z","caller":"traceutil/trace.go:171","msg":"trace[1026375941] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemmirrors/; range_end:/registry/ceph.rook.io/cephfilesystemmirrors0; response_count:0; response_revision:8324; }","duration":"547.941101ms","start":"2026-04-20T12:09:07.984334Z","end":"2026-04-20T12:09:08.532275Z","steps":["trace[1026375941] 'agreement among raft nodes before linearized reading' (duration: 547.743166ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:09:08.532308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"568.655118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzones/\" range_end:\"/registry/ceph.rook.io/cephobjectzones0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T12:09:08.532372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T12:09:07.984311Z","time spent":"548.040852ms","remote":"127.0.0.1:39798","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":0,"response size":27,"request content":"key:\"/registry/ceph.rook.io/cephfilesystemmirrors/\" range_end:\"/registry/ceph.rook.io/cephfilesystemmirrors0\" limit:10000 "} {"level":"info","ts":"2026-04-20T12:09:08.532368Z","caller":"traceutil/trace.go:171","msg":"trace[2095537047] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzones/; range_end:/registry/ceph.rook.io/cephobjectzones0; response_count:0; response_revision:8324; }","duration":"568.73033ms","start":"2026-04-20T12:09:07.963617Z","end":"2026-04-20T12:09:08.532347Z","steps":["trace[2095537047] 'agreement among raft nodes before linearized reading' (duration: 568.624068ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:09:08.532435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"547.981341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemmirrors/\" range_end:\"/registry/ceph.rook.io/cephfilesystemmirrors0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T12:09:08.532471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"568.868942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzones/\" range_end:\"/registry/ceph.rook.io/cephobjectzones0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T12:09:08.532525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"568.908353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzones/\" range_end:\"/registry/ceph.rook.io/cephobjectzones0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T12:09:08.532236Z","caller":"traceutil/trace.go:171","msg":"trace[1704239469] transaction","detail":"{read_only:false; response_revision:8324; number_of_response:1; }","duration":"256.220803ms","start":"2026-04-20T12:09:08.275967Z","end":"2026-04-20T12:09:08.532187Z","steps":["trace[1704239469] 'process raft request' (duration: 255.809436ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:09:08.532808Z","caller":"traceutil/trace.go:171","msg":"trace[1454397927] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzones/; range_end:/registry/ceph.rook.io/cephobjectzones0; response_count:0; response_revision:8324; }","duration":"569.262819ms","start":"2026-04-20T12:09:07.963518Z","end":"2026-04-20T12:09:08.532781Z","steps":["trace[1454397927] 'agreement among raft nodes before linearized reading' (duration: 568.7745ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:09:08.532816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"450.026028ms","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-20T12:09:08.532515Z","caller":"traceutil/trace.go:171","msg":"trace[918405068] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemmirrors/; range_end:/registry/ceph.rook.io/cephfilesystemmirrors0; response_count:0; response_revision:8324; }","duration":"548.102304ms","start":"2026-04-20T12:09:07.984395Z","end":"2026-04-20T12:09:08.532498Z","steps":["trace[918405068] 'agreement among raft nodes before linearized reading' (duration: 547.92374ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:09:08.534822Z","caller":"traceutil/trace.go:171","msg":"trace[1537046723] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:8324; }","duration":"452.102307ms","start":"2026-04-20T12:09:08.082701Z","end":"2026-04-20T12:09:08.534803Z","steps":["trace[1537046723] 'agreement among raft nodes before linearized reading' (duration: 449.648462ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:09:08.532782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.842719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/barbican-api-78cf4dc855-h5dk9.18a80f5911e00d6c\" ","response":"range_response_count:1 size:1135"} {"level":"warn","ts":"2026-04-20T12:09:08.534845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T12:09:07.963507Z","time spent":"571.307547ms","remote":"127.0.0.1:39792","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":0,"response size":27,"request content":"key:\"/registry/ceph.rook.io/cephobjectzones/\" range_end:\"/registry/ceph.rook.io/cephobjectzones0\" limit:10000 "} {"level":"warn","ts":"2026-04-20T12:09:08.534879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T12:09:08.082672Z","time spent":"452.193669ms","remote":"127.0.0.1:54446","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-20T12:09:08.532454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T12:09:07.963608Z","time spent":"568.831191ms","remote":"127.0.0.1:39792","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":0,"response size":27,"request content":"key:\"/registry/ceph.rook.io/cephobjectzones/\" range_end:\"/registry/ceph.rook.io/cephobjectzones0\" limit:500 "} {"level":"info","ts":"2026-04-20T12:09:08.53494Z","caller":"traceutil/trace.go:171","msg":"trace[333721350] range","detail":"{range_begin:/registry/events/openstack/barbican-api-78cf4dc855-h5dk9.18a80f5911e00d6c; range_end:; response_count:1; response_revision:8324; }","duration":"309.307305ms","start":"2026-04-20T12:09:08.225591Z","end":"2026-04-20T12:09:08.534899Z","steps":["trace[333721350] 'agreement among raft nodes before linearized reading' (duration: 306.644776ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:09:08.534841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T12:09:07.984389Z","time spent":"550.420386ms","remote":"127.0.0.1:39798","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":27,"request content":"key:\"/registry/ceph.rook.io/cephfilesystemmirrors/\" range_end:\"/registry/ceph.rook.io/cephfilesystemmirrors0\" count_only:true "} {"level":"warn","ts":"2026-04-20T12:09:08.53504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T12:09:08.225552Z","time spent":"309.46875ms","remote":"127.0.0.1:54256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":1157,"request content":"key:\"/registry/events/openstack/barbican-api-78cf4dc855-h5dk9.18a80f5911e00d6c\" "} {"level":"info","ts":"2026-04-20T12:09:08.53543Z","caller":"traceutil/trace.go:171","msg":"trace[1607459533] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzones/; range_end:/registry/ceph.rook.io/cephobjectzones0; response_count:0; response_revision:8324; }","duration":"571.850938ms","start":"2026-04-20T12:09:07.963556Z","end":"2026-04-20T12:09:08.535407Z","steps":["trace[1607459533] 'agreement among raft nodes before linearized reading' (duration: 568.892783ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:09:08.535506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T12:09:07.963534Z","time spent":"571.956229ms","remote":"127.0.0.1:39792","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":27,"request content":"key:\"/registry/ceph.rook.io/cephobjectzones/\" range_end:\"/registry/ceph.rook.io/cephobjectzones0\" count_only:true "} {"level":"info","ts":"2026-04-20T12:09:09.190422Z","caller":"traceutil/trace.go:171","msg":"trace[1479305475] linearizableReadLoop","detail":"{readStateIndex:8702; appliedIndex:8701; }","duration":"150.659949ms","start":"2026-04-20T12:09:09.03973Z","end":"2026-04-20T12:09:09.19039Z","steps":["trace[1479305475] 'read index received' (duration: 150.384433ms)","trace[1479305475] 'applied index is now lower than readState.Index' (duration: 274.386µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T12:09:09.190598Z","caller":"traceutil/trace.go:171","msg":"trace[923304836] transaction","detail":"{read_only:false; response_revision:8332; number_of_response:1; }","duration":"166.83967ms","start":"2026-04-20T12:09:09.023708Z","end":"2026-04-20T12:09:09.190548Z","steps":["trace[923304836] 'process raft request' (duration: 166.489733ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:09:09.190752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.986204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:525"} {"level":"info","ts":"2026-04-20T12:09:09.190796Z","caller":"traceutil/trace.go:171","msg":"trace[2089794261] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:8332; }","duration":"151.113737ms","start":"2026-04-20T12:09:09.03967Z","end":"2026-04-20T12:09:09.190784Z","steps":["trace[2089794261] 'agreement among raft nodes before linearized reading' (duration: 150.898663ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:09:09.190844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.708005ms","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-20T12:09:09.190928Z","caller":"traceutil/trace.go:171","msg":"trace[288192761] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8332; }","duration":"138.812937ms","start":"2026-04-20T12:09:09.052094Z","end":"2026-04-20T12:09:09.190907Z","steps":["trace[288192761] 'agreement among raft nodes before linearized reading' (duration: 138.610463ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:09:09.554338Z","caller":"traceutil/trace.go:171","msg":"trace[1028192355] linearizableReadLoop","detail":"{readStateIndex:8705; appliedIndex:8702; }","duration":"318.431387ms","start":"2026-04-20T12:09:09.235879Z","end":"2026-04-20T12:09:09.554311Z","steps":["trace[1028192355] 'read index received' (duration: 251.78255ms)","trace[1028192355] 'applied index is now lower than readState.Index' (duration: 66.647177ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T12:09:09.554461Z","caller":"traceutil/trace.go:171","msg":"trace[383676040] transaction","detail":"{read_only:false; response_revision:8334; number_of_response:1; }","duration":"358.252262ms","start":"2026-04-20T12:09:09.196163Z","end":"2026-04-20T12:09:09.554415Z","steps":["trace[383676040] 'process raft request' (duration: 357.977446ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:09:09.554473Z","caller":"traceutil/trace.go:171","msg":"trace[500411499] transaction","detail":"{read_only:false; response_revision:8333; number_of_response:1; }","duration":"358.6261ms","start":"2026-04-20T12:09:09.195811Z","end":"2026-04-20T12:09:09.554437Z","steps":["trace[500411499] 'process raft request' (duration: 291.82081ms)","trace[500411499] 'compare' (duration: 65.78159ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T12:09:09.554675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T12:09:09.19579Z","time spent":"358.771052ms","remote":"127.0.0.1:54446","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T12:09:09.554686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T12:09:09.196152Z","time spent":"358.403405ms","remote":"127.0.0.1:54446","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T12:09:09.55487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.975327ms","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-20T12:09:09.554931Z","caller":"traceutil/trace.go:171","msg":"trace[1310854765] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8335; }","duration":"319.092869ms","start":"2026-04-20T12:09:09.235821Z","end":"2026-04-20T12:09:09.554914Z","steps":["trace[1310854765] 'agreement among raft nodes before linearized reading' (duration: 318.59924ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:09:09.554937Z","caller":"traceutil/trace.go:171","msg":"trace[1726003909] transaction","detail":"{read_only:false; response_revision:8335; number_of_response:1; }","duration":"358.044708ms","start":"2026-04-20T12:09:09.196853Z","end":"2026-04-20T12:09:09.554898Z","steps":["trace[1726003909] 'process raft request' (duration: 357.410865ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:09:09.555143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T12:09:09.235808Z","time spent":"319.228561ms","remote":"127.0.0.1:54446","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-20T12:09:09.555182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T12:09:09.196827Z","time spent":"358.226552ms","remote":"127.0.0.1:54446","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T12:09:36.711559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.792686ms","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-20T12:09:36.711729Z","caller":"traceutil/trace.go:171","msg":"trace[1475310] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:8567; }","duration":"124.044141ms","start":"2026-04-20T12:09:36.58765Z","end":"2026-04-20T12:09:36.711695Z","steps":["trace[1475310] 'count revisions from in-memory index tree' (duration: 123.584982ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:09:38.395159Z","caller":"traceutil/trace.go:171","msg":"trace[115140021] linearizableReadLoop","detail":"{readStateIndex:8961; appliedIndex:8960; }","duration":"169.315057ms","start":"2026-04-20T12:09:38.225809Z","end":"2026-04-20T12:09:38.395124Z","steps":["trace[115140021] 'read index received' (duration: 169.129154ms)","trace[115140021] 'applied index is now lower than readState.Index' (duration: 184.363µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T12:09:38.395271Z","caller":"traceutil/trace.go:171","msg":"trace[582380658] transaction","detail":"{read_only:false; response_revision:8578; number_of_response:1; }","duration":"429.176398ms","start":"2026-04-20T12:09:37.966024Z","end":"2026-04-20T12:09:38.3952Z","steps":["trace[582380658] 'process raft request' (duration: 428.882382ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:09:38.395443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.601302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/barbican-api-78cf4dc855-h5dk9.18a80f5911e00d6c\" ","response":"range_response_count:1 size:1135"} {"level":"info","ts":"2026-04-20T12:09:38.395498Z","caller":"traceutil/trace.go:171","msg":"trace[1735680036] range","detail":"{range_begin:/registry/events/openstack/barbican-api-78cf4dc855-h5dk9.18a80f5911e00d6c; range_end:; response_count:1; response_revision:8578; }","duration":"169.727655ms","start":"2026-04-20T12:09:38.225755Z","end":"2026-04-20T12:09:38.395482Z","steps":["trace[1735680036] 'agreement among raft nodes before linearized reading' (duration: 169.49827ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:09:38.395528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T12:09:37.965999Z","time spent":"429.394411ms","remote":"127.0.0.1:54446","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T12:09:51.334607Z","caller":"traceutil/trace.go:171","msg":"trace[736909046] transaction","detail":"{read_only:false; response_revision:8704; number_of_response:1; }","duration":"136.830999ms","start":"2026-04-20T12:09:51.197722Z","end":"2026-04-20T12:09:51.334553Z","steps":["trace[736909046] 'process raft request' (duration: 111.564807ms)","trace[736909046] 'compare' (duration: 24.869004ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T12:09:51.334664Z","caller":"traceutil/trace.go:171","msg":"trace[832246517] transaction","detail":"{read_only:false; response_revision:8705; number_of_response:1; }","duration":"136.193977ms","start":"2026-04-20T12:09:51.198447Z","end":"2026-04-20T12:09:51.334641Z","steps":["trace[832246517] 'process raft request' (duration: 136.037464ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:09:52.319802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.132989ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T12:09:52.319972Z","caller":"traceutil/trace.go:171","msg":"trace[1495360649] transaction","detail":"{read_only:false; response_revision:8711; number_of_response:1; }","duration":"511.761872ms","start":"2026-04-20T12:09:51.808178Z","end":"2026-04-20T12:09:52.31994Z","steps":["trace[1495360649] 'process raft request' (duration: 315.335768ms)","trace[1495360649] 'compare' (duration: 195.967446ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T12:09:52.32008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T12:09:51.808144Z","time spent":"511.885506ms","remote":"127.0.0.1:54446","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T12:09:52.379767Z","caller":"traceutil/trace.go:171","msg":"trace[1280093835] transaction","detail":"{read_only:false; response_revision:8712; number_of_response:1; }","duration":"570.868129ms","start":"2026-04-20T12:09:51.808846Z","end":"2026-04-20T12:09:52.379714Z","steps":["trace[1280093835] 'process raft request' (duration: 570.590204ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:09:52.380054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T12:09:51.808823Z","time spent":"571.068803ms","remote":"127.0.0.1:54446","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T12:09:52.380148Z","caller":"traceutil/trace.go:171","msg":"trace[43132653] transaction","detail":"{read_only:false; response_revision:8713; number_of_response:1; }","duration":"570.822517ms","start":"2026-04-20T12:09:51.809247Z","end":"2026-04-20T12:09:52.38007Z","steps":["trace[43132653] 'process raft request' (duration: 570.39847ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:09:52.380183Z","caller":"traceutil/trace.go:171","msg":"trace[1936493143] transaction","detail":"{read_only:false; number_of_response:0; response_revision:8713; }","duration":"569.249008ms","start":"2026-04-20T12:09:51.810903Z","end":"2026-04-20T12:09:52.380152Z","steps":["trace[1936493143] 'process raft request' (duration: 569.051644ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:09:52.380261Z","caller":"traceutil/trace.go:171","msg":"trace[664000524] transaction","detail":"{read_only:false; response_revision:8714; number_of_response:1; }","duration":"414.264369ms","start":"2026-04-20T12:09:51.965967Z","end":"2026-04-20T12:09:52.380231Z","steps":["trace[664000524] 'process raft request' (duration: 414.072215ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:09:52.380787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T12:09:51.965944Z","time spent":"414.789149ms","remote":"127.0.0.1:54256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":746,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T12:09:52.380346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T12:09:51.810894Z","time spent":"569.382611ms","remote":"127.0.0.1:54676","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":27,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T12:09:52.380399Z","caller":"traceutil/trace.go:171","msg":"trace[845400743] transaction","detail":"{read_only:false; response_revision:8715; number_of_response:1; }","duration":"403.296864ms","start":"2026-04-20T12:09:51.977092Z","end":"2026-04-20T12:09:52.380389Z","steps":["trace[845400743] 'process raft request' (duration: 403.052579ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:09:52.380378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T12:09:51.809241Z","time spent":"571.022262ms","remote":"127.0.0.1:54446","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T12:09:52.380611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"552.631487ms","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-20T12:09:52.381279Z","caller":"traceutil/trace.go:171","msg":"trace[114112913] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8715; }","duration":"553.338121ms","start":"2026-04-20T12:09:51.827916Z","end":"2026-04-20T12:09:52.381254Z","steps":["trace[114112913] 'agreement among raft nodes before linearized reading' (duration: 552.536026ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:09:52.380395Z","caller":"traceutil/trace.go:171","msg":"trace[642554524] linearizableReadLoop","detail":"{readStateIndex:9105; appliedIndex:9101; }","duration":"552.387203ms","start":"2026-04-20T12:09:51.82795Z","end":"2026-04-20T12:09:52.380337Z","steps":["trace[642554524] 'read index received' (duration: 295.711521ms)","trace[642554524] 'applied index is now lower than readState.Index' (duration: 256.674182ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T12:09:52.381352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T12:09:51.827901Z","time spent":"553.431192ms","remote":"127.0.0.1:54446","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-20T12:09:52.381497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.655484ms","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-20T12:09:52.381564Z","caller":"traceutil/trace.go:171","msg":"trace[667369957] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:8715; }","duration":"192.774616ms","start":"2026-04-20T12:09:52.18877Z","end":"2026-04-20T12:09:52.381545Z","steps":["trace[667369957] 'agreement among raft nodes before linearized reading' (duration: 192.661264ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:09:52.38159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.457501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-ljjgq\" ","response":"range_response_count:1 size:4612"} {"level":"warn","ts":"2026-04-20T12:09:52.381516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T12:09:51.977076Z","time spent":"404.034817ms","remote":"127.0.0.1:54410","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2999,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T12:09:52.381593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.096645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-fktnl\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-04-20T12:09:52.381816Z","caller":"traceutil/trace.go:171","msg":"trace[166550805] range","detail":"{range_begin:/registry/pods/openstack/keepalived-fktnl; range_end:; response_count:1; response_revision:8715; }","duration":"361.37959ms","start":"2026-04-20T12:09:52.020418Z","end":"2026-04-20T12:09:52.381798Z","steps":["trace[166550805] 'agreement among raft nodes before linearized reading' (duration: 361.041153ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:09:52.381876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T12:09:52.020388Z","time spent":"361.469971ms","remote":"127.0.0.1:54346","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6546,"request content":"key:\"/registry/pods/openstack/keepalived-fktnl\" "} {"level":"info","ts":"2026-04-20T12:09:52.381707Z","caller":"traceutil/trace.go:171","msg":"trace[1380016060] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-ljjgq; range_end:; response_count:1; response_revision:8715; }","duration":"416.570994ms","start":"2026-04-20T12:09:51.965073Z","end":"2026-04-20T12:09:52.381644Z","steps":["trace[1380016060] 'agreement among raft nodes before linearized reading' (duration: 416.351869ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:09:52.381943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T12:09:51.965048Z","time spent":"416.884429ms","remote":"127.0.0.1:54346","response type":"/etcdserverpb.KV/Range","request count":0,"request size":57,"response count":1,"response size":4634,"request content":"key:\"/registry/pods/openstack/rook-ceph-detect-version-ljjgq\" "} {"level":"info","ts":"2026-04-20T12:09:53.434889Z","caller":"traceutil/trace.go:171","msg":"trace[397104802] transaction","detail":"{read_only:false; response_revision:8720; number_of_response:1; }","duration":"126.859684ms","start":"2026-04-20T12:09:53.307983Z","end":"2026-04-20T12:09:53.434843Z","steps":["trace[397104802] 'process raft request' (duration: 126.64247ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:09:53.476377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.866926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:660"} {"level":"info","ts":"2026-04-20T12:09:53.476487Z","caller":"traceutil/trace.go:171","msg":"trace[807731281] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:8721; }","duration":"124.039841ms","start":"2026-04-20T12:09:53.352424Z","end":"2026-04-20T12:09:53.476464Z","steps":["trace[807731281] 'agreement among raft nodes before linearized reading' (duration: 123.744444ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:09:53.476651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.739255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:645"} {"level":"info","ts":"2026-04-20T12:09:53.476765Z","caller":"traceutil/trace.go:171","msg":"trace[553923641] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8721; }","duration":"116.912017ms","start":"2026-04-20T12:09:53.359825Z","end":"2026-04-20T12:09:53.476737Z","steps":["trace[553923641] 'agreement among raft nodes before linearized reading' (duration: 116.53594ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:09:53.591114Z","caller":"traceutil/trace.go:171","msg":"trace[930188929] transaction","detail":"{read_only:false; response_revision:8722; number_of_response:1; }","duration":"105.205168ms","start":"2026-04-20T12:09:53.485878Z","end":"2026-04-20T12:09:53.591083Z","steps":["trace[930188929] 'process raft request' (duration: 48.780283ms)","trace[930188929] 'compare' (duration: 56.197081ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T12:09:53.592911Z","caller":"traceutil/trace.go:171","msg":"trace[539281391] transaction","detail":"{read_only:false; response_revision:8723; number_of_response:1; }","duration":"106.853679ms","start":"2026-04-20T12:09:53.486032Z","end":"2026-04-20T12:09:53.592885Z","steps":["trace[539281391] 'process raft request' (duration: 105.000134ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:10:56.669671Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6526} {"level":"info","ts":"2026-04-20T12:10:56.777588Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6526,"took":"105.480042ms","hash":3789959633,"current-db-size-bytes":60878848,"current-db-size":"61 MB","current-db-size-in-use-bytes":26619904,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-04-20T12:10:56.7777Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3789959633,"revision":6526,"compact-revision":3216} {"level":"info","ts":"2026-04-20T12:11:03.884566Z","caller":"traceutil/trace.go:171","msg":"trace[1695203928] linearizableReadLoop","detail":"{readStateIndex:9894; appliedIndex:9893; }","duration":"397.492236ms","start":"2026-04-20T12:11:03.487035Z","end":"2026-04-20T12:11:03.884527Z","steps":["trace[1695203928] 'read index received' (duration: 397.293802ms)","trace[1695203928] 'applied index is now lower than readState.Index' (duration: 197.004µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T12:11:03.884693Z","caller":"traceutil/trace.go:171","msg":"trace[1619315639] transaction","detail":"{read_only:false; response_revision:9469; number_of_response:1; }","duration":"471.716585ms","start":"2026-04-20T12:11:03.41293Z","end":"2026-04-20T12:11:03.884647Z","steps":["trace[1619315639] 'process raft request' (duration: 471.311927ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:11:03.88481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.73892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T12:11:03.884861Z","caller":"traceutil/trace.go:171","msg":"trace[327478927] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:9469; }","duration":"397.869662ms","start":"2026-04-20T12:11:03.486976Z","end":"2026-04-20T12:11:03.884846Z","steps":["trace[327478927] 'agreement among raft nodes before linearized reading' (duration: 397.73747ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:11:03.884905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T12:11:03.486948Z","time spent":"397.945434ms","remote":"127.0.0.1:50860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":29,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-04-20T12:11:03.884929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T12:11:03.412899Z","time spent":"471.909028ms","remote":"127.0.0.1:54446","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T12:11:03.974076Z","caller":"traceutil/trace.go:171","msg":"trace[1817983432] transaction","detail":"{read_only:false; response_revision:9470; number_of_response:1; }","duration":"236.718378ms","start":"2026-04-20T12:11:03.737307Z","end":"2026-04-20T12:11:03.974026Z","steps":["trace[1817983432] 'process raft request' (duration: 203.920735ms)","trace[1817983432] 'compare' (duration: 32.539248ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T12:11:03.974144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.626427ms","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-20T12:11:03.974216Z","caller":"traceutil/trace.go:171","msg":"trace[2054719062] 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:9470; }","duration":"374.700819ms","start":"2026-04-20T12:11:03.599493Z","end":"2026-04-20T12:11:03.974194Z","steps":["trace[2054719062] 'agreement among raft nodes before linearized reading' (duration: 374.433814ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T12:11:03.974258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T12:11:03.599482Z","time spent":"374.76666ms","remote":"127.0.0.1:54332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":147,"response count":1,"response size":1957,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" "} {"level":"warn","ts":"2026-04-20T12:11:03.974281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.068839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:356"} {"level":"info","ts":"2026-04-20T12:11:03.974548Z","caller":"traceutil/trace.go:171","msg":"trace[2126273184] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:9470; }","duration":"272.324484ms","start":"2026-04-20T12:11:03.702162Z","end":"2026-04-20T12:11:03.974487Z","steps":["trace[2126273184] 'agreement among raft nodes before linearized reading' (duration: 271.893216ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:11:04.610274Z","caller":"traceutil/trace.go:171","msg":"trace[1362467851] transaction","detail":"{read_only:false; number_of_response:0; response_revision:9472; }","duration":"123.712514ms","start":"2026-04-20T12:11:04.486489Z","end":"2026-04-20T12:11:04.610202Z","steps":["trace[1362467851] 'process raft request' (duration: 123.475759ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:11:04.610369Z","caller":"traceutil/trace.go:171","msg":"trace[2010934237] transaction","detail":"{read_only:false; response_revision:9473; number_of_response:1; }","duration":"122.819438ms","start":"2026-04-20T12:11:04.487496Z","end":"2026-04-20T12:11:04.610315Z","steps":["trace[2010934237] 'process raft request' (duration: 122.587583ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:11:14.91132Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"36b63984d68b7065","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-20T12:11:14.917674Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-20T12:11:14.917773Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-20T12:13:21.321152Z","caller":"traceutil/trace.go:171","msg":"trace[464561915] transaction","detail":"{read_only:false; response_revision:10754; number_of_response:1; }","duration":"130.782075ms","start":"2026-04-20T12:13:21.19033Z","end":"2026-04-20T12:13:21.321112Z","steps":["trace[464561915] 'process raft request' (duration: 130.5379ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T12:15:56.676707Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":9418} {"level":"info","ts":"2026-04-20T12:15:56.770984Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":9418,"took":"91.253274ms","hash":2959598819,"current-db-size-bytes":60878848,"current-db-size":"61 MB","current-db-size-in-use-bytes":25665536,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-04-20T12:15:56.771056Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2959598819,"revision":9418,"compact-revision":6526}