{"level":"warn","ts":"2026-05-27T09:54:05.244795Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-27T09:54:05.244922Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.130: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.130:2380","--initial-cluster=instance=https://199.19.213.130:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.130:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.130: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-05-27T09:54:05.245017Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-27T09:54:05.245028Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.130:2380"]} {"level":"info","ts":"2026-05-27T09:54:05.245064Z","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-05-27T09:54:05.245888Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.130:2379"]} {"level":"info","ts":"2026-05-27T09:54:05.245995Z","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.130:2380"],"listen-peer-urls":["https://199.19.213.130:2380"],"advertise-client-urls":["https://199.19.213.130:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.130:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.130: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-05-27T09:54:05.252294Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.330914ms"} {"level":"info","ts":"2026-05-27T09:54:05.260806Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"a727d7e7ce5fe36a","cluster-id":"db8a5e7d54164af4"} {"level":"info","ts":"2026-05-27T09:54:05.261127Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a727d7e7ce5fe36a switched to configuration voters=()"} {"level":"info","ts":"2026-05-27T09:54:05.261177Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a727d7e7ce5fe36a became follower at term 0"} {"level":"info","ts":"2026-05-27T09:54:05.261206Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft a727d7e7ce5fe36a [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-27T09:54:05.261224Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a727d7e7ce5fe36a became follower at term 1"} {"level":"info","ts":"2026-05-27T09:54:05.261332Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a727d7e7ce5fe36a switched to configuration voters=(12044833119025488746)"} {"level":"warn","ts":"2026-05-27T09:54:05.267849Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-27T09:54:05.269672Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-27T09:54:05.270911Z","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-05-27T09:54:05.27354Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"a727d7e7ce5fe36a","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-27T09:54:05.281023Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"a727d7e7ce5fe36a","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-27T09:54:05.280892Z","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-05-27T09:54:05.281371Z","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-05-27T09:54:05.281415Z","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-05-27T09:54:05.292071Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a727d7e7ce5fe36a switched to configuration voters=(12044833119025488746)"} {"level":"info","ts":"2026-05-27T09:54:05.292303Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"db8a5e7d54164af4","local-member-id":"a727d7e7ce5fe36a","added-peer-id":"a727d7e7ce5fe36a","added-peer-peer-urls":["https://199.19.213.130:2380"]} {"level":"info","ts":"2026-05-27T09:54:05.2926Z","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-05-27T09:54:05.292831Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.130:2380"} {"level":"info","ts":"2026-05-27T09:54:05.292853Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.130:2380"} {"level":"info","ts":"2026-05-27T09:54:05.29294Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"a727d7e7ce5fe36a","initial-advertise-peer-urls":["https://199.19.213.130:2380"],"listen-peer-urls":["https://199.19.213.130:2380"],"advertise-client-urls":["https://199.19.213.130:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.130:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-27T09:54:05.293017Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-27T09:54:05.562196Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a727d7e7ce5fe36a is starting a new election at term 1"} {"level":"info","ts":"2026-05-27T09:54:05.562321Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a727d7e7ce5fe36a became pre-candidate at term 1"} {"level":"info","ts":"2026-05-27T09:54:05.562392Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a727d7e7ce5fe36a received MsgPreVoteResp from a727d7e7ce5fe36a at term 1"} {"level":"info","ts":"2026-05-27T09:54:05.562419Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a727d7e7ce5fe36a became candidate at term 2"} {"level":"info","ts":"2026-05-27T09:54:05.562437Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a727d7e7ce5fe36a received MsgVoteResp from a727d7e7ce5fe36a at term 2"} {"level":"info","ts":"2026-05-27T09:54:05.562464Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a727d7e7ce5fe36a became leader at term 2"} {"level":"info","ts":"2026-05-27T09:54:05.562483Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: a727d7e7ce5fe36a elected leader a727d7e7ce5fe36a at term 2"} {"level":"info","ts":"2026-05-27T09:54:05.563771Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"a727d7e7ce5fe36a","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.130:2379]}","request-path":"/0/members/a727d7e7ce5fe36a/attributes","cluster-id":"db8a5e7d54164af4","publish-timeout":"7s"} {"level":"info","ts":"2026-05-27T09:54:05.563849Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-27T09:54:05.56418Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-27T09:54:05.564166Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-27T09:54:05.564404Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-27T09:54:05.564523Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-27T09:54:05.567668Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-27T09:54:05.567826Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"db8a5e7d54164af4","local-member-id":"a727d7e7ce5fe36a","cluster-version":"3.5"} {"level":"info","ts":"2026-05-27T09:54:05.567997Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-27T09:54:05.568061Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-27T09:54:05.569475Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.130:2379"} {"level":"warn","ts":"2026-05-27T09:54:24.477081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.280872ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-05-27T09:54:24.839411Z","caller":"traceutil/trace.go:171","msg":"trace[483371523] transaction","detail":"{read_only:false; response_revision:373; number_of_response:1; }","duration":"297.585524ms","start":"2026-05-27T09:54:24.541787Z","end":"2026-05-27T09:54:24.839373Z","steps":["trace[483371523] 'process raft request' (duration: 297.351449ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:54:25.133207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.046077ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-27T09:54:25.133336Z","caller":"traceutil/trace.go:171","msg":"trace[1498781568] transaction","detail":"{read_only:false; response_revision:374; number_of_response:1; }","duration":"289.527107ms","start":"2026-05-27T09:54:24.843787Z","end":"2026-05-27T09:54:25.133314Z","steps":["trace[1498781568] 'process raft request' (duration: 159.779124ms)","trace[1498781568] 'compare' (duration: 128.882323ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T09:54:25.141576Z","caller":"traceutil/trace.go:171","msg":"trace[24065833] transaction","detail":"{read_only:false; response_revision:375; number_of_response:1; }","duration":"291.716128ms","start":"2026-05-27T09:54:24.84984Z","end":"2026-05-27T09:54:25.141557Z","steps":["trace[24065833] 'process raft request' (duration: 291.595086ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:54:49.832809Z","caller":"traceutil/trace.go:171","msg":"trace[1520412857] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"165.473138ms","start":"2026-05-27T09:54:49.66731Z","end":"2026-05-27T09:54:49.832783Z","steps":["trace[1520412857] 'process raft request' (duration: 165.293304ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:54:49.840823Z","caller":"traceutil/trace.go:171","msg":"trace[965860905] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"114.853327ms","start":"2026-05-27T09:54:49.725911Z","end":"2026-05-27T09:54:49.840765Z","steps":["trace[965860905] 'process raft request' (duration: 114.217721ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:54:49.840861Z","caller":"traceutil/trace.go:171","msg":"trace[1682238825] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"172.684175ms","start":"2026-05-27T09:54:49.668125Z","end":"2026-05-27T09:54:49.840809Z","steps":["trace[1682238825] 'process raft request' (duration: 171.816234ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:54:50.094273Z","caller":"traceutil/trace.go:171","msg":"trace[1348099642] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"238.411594ms","start":"2026-05-27T09:54:49.855826Z","end":"2026-05-27T09:54:50.094237Z","steps":["trace[1348099642] 'process raft request' (duration: 141.140054ms)","trace[1348099642] 'compare' (duration: 97.068296ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T09:54:50.692757Z","caller":"traceutil/trace.go:171","msg":"trace[1374066514] linearizableReadLoop","detail":"{readStateIndex:619; appliedIndex:618; }","duration":"138.439723ms","start":"2026-05-27T09:54:50.554286Z","end":"2026-05-27T09:54:50.692726Z","steps":["trace[1374066514] 'read index received' (duration: 137.328057ms)","trace[1374066514] 'applied index is now lower than readState.Index' (duration: 1.110686ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T09:54:50.692906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.592137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T09:54:50.692939Z","caller":"traceutil/trace.go:171","msg":"trace[41430114] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:595; }","duration":"138.682009ms","start":"2026-05-27T09:54:50.554245Z","end":"2026-05-27T09:54:50.692927Z","steps":["trace[41430114] 'agreement among raft nodes before linearized reading' (duration: 138.601077ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:54:50.692961Z","caller":"traceutil/trace.go:171","msg":"trace[1631845541] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"421.039529ms","start":"2026-05-27T09:54:50.271861Z","end":"2026-05-27T09:54:50.692901Z","steps":["trace[1631845541] 'process raft request' (duration: 420.17229ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:54:50.693523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:54:50.271826Z","time spent":"421.5356ms","remote":"127.0.0.1:35270","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036313,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T09:54:52.022922Z","caller":"traceutil/trace.go:171","msg":"trace[398381906] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"111.474058ms","start":"2026-05-27T09:54:51.911416Z","end":"2026-05-27T09:54:52.02289Z","steps":["trace[398381906] 'process raft request' (duration: 111.270324ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:54:52.030325Z","caller":"traceutil/trace.go:171","msg":"trace[2066216647] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"113.092746ms","start":"2026-05-27T09:54:51.917196Z","end":"2026-05-27T09:54:52.030289Z","steps":["trace[2066216647] 'process raft request' (duration: 112.86033ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:55:49.279318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.461245ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/ingress-nginx/ingress-nginx-admission-create-54btz\" ","response":"range_response_count:1 size:4197"} {"level":"info","ts":"2026-05-27T09:55:49.27945Z","caller":"traceutil/trace.go:171","msg":"trace[1673088757] range","detail":"{range_begin:/registry/pods/ingress-nginx/ingress-nginx-admission-create-54btz; range_end:; response_count:1; response_revision:1289; }","duration":"168.67146ms","start":"2026-05-27T09:55:49.110752Z","end":"2026-05-27T09:55:49.279424Z","steps":["trace[1673088757] 'range keys from in-memory index tree' (duration: 168.272481ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:56:19.682518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.279257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T09:56:19.682613Z","caller":"traceutil/trace.go:171","msg":"trace[1573310713] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1817; }","duration":"128.428632ms","start":"2026-05-27T09:56:19.55417Z","end":"2026-05-27T09:56:19.682598Z","steps":["trace[1573310713] 'range keys from in-memory index tree' (duration: 128.077533ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:58:36.872985Z","caller":"traceutil/trace.go:171","msg":"trace[1543250685] transaction","detail":"{read_only:false; response_revision:2798; number_of_response:1; }","duration":"118.165035ms","start":"2026-05-27T09:58:36.754778Z","end":"2026-05-27T09:58:36.872943Z","steps":["trace[1543250685] 'process raft request' (duration: 116.964827ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:02:44.687353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.17095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config\" ","response":"range_response_count:1 size:708"} {"level":"info","ts":"2026-05-27T10:02:44.687456Z","caller":"traceutil/trace.go:171","msg":"trace[1349174209] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config; range_end:; response_count:1; response_revision:4917; }","duration":"109.311192ms","start":"2026-05-27T10:02:44.578123Z","end":"2026-05-27T10:02:44.687435Z","steps":["trace[1349174209] 'range keys from in-memory index tree' (duration: 109.023786ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:02:44.68745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.407339ms","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-05-27T10:02:44.687566Z","caller":"traceutil/trace.go:171","msg":"trace[1663378252] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4917; }","duration":"106.594693ms","start":"2026-05-27T10:02:44.580948Z","end":"2026-05-27T10:02:44.687543Z","steps":["trace[1663378252] 'range keys from in-memory index tree' (duration: 106.230985ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:02:49.021254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.087023ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-27T10:02:49.021397Z","caller":"traceutil/trace.go:171","msg":"trace[64458439] linearizableReadLoop","detail":"{readStateIndex:5255; appliedIndex:5254; }","duration":"125.989179ms","start":"2026-05-27T10:02:48.895386Z","end":"2026-05-27T10:02:49.021376Z","steps":["trace[64458439] 'read index received' (duration: 59.262µs)","trace[64458439] 'applied index is now lower than readState.Index' (duration: 125.928657ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T10:02:49.021574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.184253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-05-27T10:02:49.021618Z","caller":"traceutil/trace.go:171","msg":"trace[539445786] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5065; }","duration":"126.259544ms","start":"2026-05-27T10:02:48.895346Z","end":"2026-05-27T10:02:49.021606Z","steps":["trace[539445786] 'agreement among raft nodes before linearized reading' (duration: 126.08991ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:02:49.021734Z","caller":"traceutil/trace.go:171","msg":"trace[954329184] transaction","detail":"{read_only:false; response_revision:5065; number_of_response:1; }","duration":"189.223779ms","start":"2026-05-27T10:02:48.832486Z","end":"2026-05-27T10:02:49.02171Z","steps":["trace[954329184] 'process raft request' (duration: 51.578374ms)","trace[954329184] 'compare' (duration: 136.809247ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T10:02:50.637669Z","caller":"traceutil/trace.go:171","msg":"trace[862871572] linearizableReadLoop","detail":"{readStateIndex:5274; appliedIndex:5273; }","duration":"125.532389ms","start":"2026-05-27T10:02:50.512101Z","end":"2026-05-27T10:02:50.637634Z","steps":["trace[862871572] 'read index received' (duration: 107.019831ms)","trace[862871572] 'applied index is now lower than readState.Index' (duration: 18.511468ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T10:02:50.637811Z","caller":"traceutil/trace.go:171","msg":"trace[1477849658] transaction","detail":"{read_only:false; number_of_response:0; response_revision:5082; }","duration":"136.667814ms","start":"2026-05-27T10:02:50.501107Z","end":"2026-05-27T10:02:50.637775Z","steps":["trace[1477849658] 'process raft request' (duration: 117.979333ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:02:50.638145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.00845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-27T10:02:50.638254Z","caller":"traceutil/trace.go:171","msg":"trace[2144611190] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5082; }","duration":"126.160563ms","start":"2026-05-27T10:02:50.51207Z","end":"2026-05-27T10:02:50.63823Z","steps":["trace[2144611190] 'agreement among raft nodes before linearized reading' (duration: 125.773224ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:03:04.533035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.117524ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"warn","ts":"2026-05-27T10:03:08.901985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.007794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-27T10:03:08.90211Z","caller":"traceutil/trace.go:171","msg":"trace[261464905] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5467; }","duration":"137.201408ms","start":"2026-05-27T10:03:08.764884Z","end":"2026-05-27T10:03:08.902085Z","steps":["trace[261464905] 'range keys from in-memory index tree' (duration: 136.792359ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:03:08.902076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.418758ms","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-05-27T10:03:08.902247Z","caller":"traceutil/trace.go:171","msg":"trace[1716312120] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5467; }","duration":"155.635823ms","start":"2026-05-27T10:03:08.746556Z","end":"2026-05-27T10:03:08.902192Z","steps":["trace[1716312120] 'range keys from in-memory index tree' (duration: 155.153913ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:03:09.165316Z","caller":"traceutil/trace.go:171","msg":"trace[270488107] transaction","detail":"{read_only:false; response_revision:5472; number_of_response:1; }","duration":"167.313659ms","start":"2026-05-27T10:03:08.997967Z","end":"2026-05-27T10:03:09.165281Z","steps":["trace[270488107] 'process raft request' (duration: 82.465958ms)","trace[270488107] 'compare' (duration: 84.558544ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T10:03:09.34211Z","caller":"traceutil/trace.go:171","msg":"trace[85646218] transaction","detail":"{read_only:false; response_revision:5474; number_of_response:1; }","duration":"170.926578ms","start":"2026-05-27T10:03:09.171153Z","end":"2026-05-27T10:03:09.342079Z","steps":["trace[85646218] 'process raft request' (duration: 170.866207ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:03:09.342263Z","caller":"traceutil/trace.go:171","msg":"trace[144581621] transaction","detail":"{read_only:false; response_revision:5473; number_of_response:1; }","duration":"171.634814ms","start":"2026-05-27T10:03:09.170497Z","end":"2026-05-27T10:03:09.342132Z","steps":["trace[144581621] 'process raft request' (duration: 111.891553ms)","trace[144581621] 'compare' (duration: 59.468825ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T10:03:09.382938Z","caller":"traceutil/trace.go:171","msg":"trace[1964214056] transaction","detail":"{read_only:false; response_revision:5475; number_of_response:1; }","duration":"125.693496ms","start":"2026-05-27T10:03:09.257212Z","end":"2026-05-27T10:03:09.382906Z","steps":["trace[1964214056] 'process raft request' (duration: 125.505102ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:04:05.887043Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2961} {"level":"info","ts":"2026-05-27T10:04:05.958864Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2961,"took":"69.989905ms","hash":1964255863,"current-db-size-bytes":56066048,"current-db-size":"56 MB","current-db-size-in-use-bytes":33394688,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-05-27T10:04:05.958934Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1964255863,"revision":2961,"compact-revision":-1} {"level":"info","ts":"2026-05-27T10:09:05.891871Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6010} {"level":"info","ts":"2026-05-27T10:09:05.985176Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6010,"took":"91.216494ms","hash":4239244178,"current-db-size-bytes":56066048,"current-db-size":"56 MB","current-db-size-in-use-bytes":21782528,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-05-27T10:09:05.985254Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4239244178,"revision":6010,"compact-revision":2961} {"level":"info","ts":"2026-05-27T10:09:30.646004Z","caller":"traceutil/trace.go:171","msg":"trace[1243513089] transaction","detail":"{read_only:false; response_revision:8386; number_of_response:1; }","duration":"123.235939ms","start":"2026-05-27T10:09:30.522741Z","end":"2026-05-27T10:09:30.645977Z","steps":["trace[1243513089] 'process raft request' (duration: 123.056846ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:09:30.877414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.953312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-27T10:09:30.877506Z","caller":"traceutil/trace.go:171","msg":"trace[1450257475] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:8386; }","duration":"128.095275ms","start":"2026-05-27T10:09:30.749388Z","end":"2026-05-27T10:09:30.877483Z","steps":["trace[1450257475] 'count revisions from in-memory index tree' (duration: 127.85607ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:10:01.761899Z","caller":"traceutil/trace.go:171","msg":"trace[1722400408] linearizableReadLoop","detail":"{readStateIndex:8910; appliedIndex:8909; }","duration":"119.06502ms","start":"2026-05-27T10:10:01.642797Z","end":"2026-05-27T10:10:01.761862Z","steps":["trace[1722400408] 'read index received' (duration: 118.795934ms)","trace[1722400408] 'applied index is now lower than readState.Index' (duration: 267.425µs)"],"step_count":2} {"level":"info","ts":"2026-05-27T10:10:01.76204Z","caller":"traceutil/trace.go:171","msg":"trace[1349640773] transaction","detail":"{read_only:false; response_revision:8607; number_of_response:1; }","duration":"200.947523ms","start":"2026-05-27T10:10:01.561035Z","end":"2026-05-27T10:10:01.761983Z","steps":["trace[1349640773] 'process raft request' (duration: 200.584415ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:10:01.762326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.487529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-27T10:10:01.7625Z","caller":"traceutil/trace.go:171","msg":"trace[1643634389] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:8607; }","duration":"119.725525ms","start":"2026-05-27T10:10:01.642751Z","end":"2026-05-27T10:10:01.762477Z","steps":["trace[1643634389] 'agreement among raft nodes before linearized reading' (duration: 119.314665ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:10:01.817982Z","caller":"traceutil/trace.go:171","msg":"trace[949456155] transaction","detail":"{read_only:false; response_revision:8608; number_of_response:1; }","duration":"114.628433ms","start":"2026-05-27T10:10:01.703324Z","end":"2026-05-27T10:10:01.817952Z","steps":["trace[949456155] 'process raft request' (duration: 114.266666ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:10:01.818175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.765782ms","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-05-27T10:10:01.818262Z","caller":"traceutil/trace.go:171","msg":"trace[914808936] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8608; }","duration":"106.888507ms","start":"2026-05-27T10:10:01.711352Z","end":"2026-05-27T10:10:01.818241Z","steps":["trace[914808936] 'agreement among raft nodes before linearized reading' (duration: 106.653221ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:10:01.818671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.880134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-05-27T10:10:01.81883Z","caller":"traceutil/trace.go:171","msg":"trace[503587853] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8608; }","duration":"118.120399ms","start":"2026-05-27T10:10:01.700679Z","end":"2026-05-27T10:10:01.818799Z","steps":["trace[503587853] 'agreement among raft nodes before linearized reading' (duration: 117.134246ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:10:01.958666Z","caller":"traceutil/trace.go:171","msg":"trace[438054617] transaction","detail":"{read_only:false; response_revision:8611; number_of_response:1; }","duration":"129.153968ms","start":"2026-05-27T10:10:01.829486Z","end":"2026-05-27T10:10:01.95864Z","steps":["trace[438054617] 'process raft request' (duration: 129.082957ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:10:01.95889Z","caller":"traceutil/trace.go:171","msg":"trace[2094536919] transaction","detail":"{read_only:false; response_revision:8610; number_of_response:1; }","duration":"133.708697ms","start":"2026-05-27T10:10:01.825008Z","end":"2026-05-27T10:10:01.958717Z","steps":["trace[2094536919] 'process raft request' (duration: 76.711952ms)","trace[2094536919] 'compare' (duration: 56.578316ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T10:10:12.060026Z","caller":"traceutil/trace.go:171","msg":"trace[790173832] transaction","detail":"{read_only:false; response_revision:8704; number_of_response:1; }","duration":"208.668911ms","start":"2026-05-27T10:10:11.851326Z","end":"2026-05-27T10:10:12.059995Z","steps":["trace[790173832] 'process raft request' (duration: 208.452086ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:10:12.060092Z","caller":"traceutil/trace.go:171","msg":"trace[974985130] transaction","detail":"{read_only:false; response_revision:8705; number_of_response:1; }","duration":"196.235292ms","start":"2026-05-27T10:10:11.863804Z","end":"2026-05-27T10:10:12.06004Z","steps":["trace[974985130] 'process raft request' (duration: 196.137249ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:10:12.176331Z","caller":"traceutil/trace.go:171","msg":"trace[44430343] transaction","detail":"{read_only:false; response_revision:8707; number_of_response:1; }","duration":"111.196289ms","start":"2026-05-27T10:10:12.065106Z","end":"2026-05-27T10:10:12.176302Z","steps":["trace[44430343] 'process raft request' (duration: 111.132648ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:10:12.176626Z","caller":"traceutil/trace.go:171","msg":"trace[2021002110] transaction","detail":"{read_only:false; response_revision:8706; number_of_response:1; }","duration":"111.738201ms","start":"2026-05-27T10:10:12.064874Z","end":"2026-05-27T10:10:12.176612Z","steps":["trace[2021002110] 'process raft request' (duration: 83.248584ms)","trace[2021002110] 'compare' (duration: 27.597338ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T10:10:12.905808Z","caller":"traceutil/trace.go:171","msg":"trace[1672552967] transaction","detail":"{read_only:false; response_revision:8710; number_of_response:1; }","duration":"160.118729ms","start":"2026-05-27T10:10:12.745655Z","end":"2026-05-27T10:10:12.905774Z","steps":["trace[1672552967] 'process raft request' (duration: 159.806062ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:10:13.051093Z","caller":"traceutil/trace.go:171","msg":"trace[1373857928] transaction","detail":"{read_only:false; response_revision:8711; number_of_response:1; }","duration":"132.47098ms","start":"2026-05-27T10:10:12.918594Z","end":"2026-05-27T10:10:13.051065Z","steps":["trace[1373857928] 'process raft request' (duration: 132.304746ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:10:49.16432Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000024a1.wal"} {"level":"info","ts":"2026-05-27T10:11:28.584569Z","caller":"traceutil/trace.go:171","msg":"trace[433876429] transaction","detail":"{read_only:false; response_revision:9453; number_of_response:1; }","duration":"226.64052ms","start":"2026-05-27T10:11:28.357898Z","end":"2026-05-27T10:11:28.584539Z","steps":["trace[433876429] 'process raft request' (duration: 226.475757ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:11:28.600395Z","caller":"traceutil/trace.go:171","msg":"trace[1008203704] linearizableReadLoop","detail":"{readStateIndex:9783; appliedIndex:9781; }","duration":"207.435494ms","start":"2026-05-27T10:11:28.392944Z","end":"2026-05-27T10:11:28.600379Z","steps":["trace[1008203704] 'read index received' (duration: 191.445227ms)","trace[1008203704] 'applied index is now lower than readState.Index' (duration: 15.988587ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T10:11:28.600639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.617288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterbackups/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterbackups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T10:11:28.600818Z","caller":"traceutil/trace.go:171","msg":"trace[1876501840] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterbackups/; range_end:/registry/pxc.percona.com/perconaxtradbclusterbackups0; response_count:0; response_revision:9454; }","duration":"207.859643ms","start":"2026-05-27T10:11:28.392882Z","end":"2026-05-27T10:11:28.600742Z","steps":["trace[1876501840] 'agreement among raft nodes before linearized reading' (duration: 207.581707ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:11:28.600502Z","caller":"traceutil/trace.go:171","msg":"trace[30297329] transaction","detail":"{read_only:false; response_revision:9454; number_of_response:1; }","duration":"207.901644ms","start":"2026-05-27T10:11:28.392543Z","end":"2026-05-27T10:11:28.600444Z","steps":["trace[30297329] 'process raft request' (duration: 207.767271ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:11:28.720283Z","caller":"traceutil/trace.go:171","msg":"trace[713598186] transaction","detail":"{read_only:false; response_revision:9455; number_of_response:1; }","duration":"114.956711ms","start":"2026-05-27T10:11:28.605272Z","end":"2026-05-27T10:11:28.720229Z","steps":["trace[713598186] 'process raft request' (duration: 34.733542ms)","trace[713598186] 'compare' (duration: 79.466242ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T10:11:28.720483Z","caller":"traceutil/trace.go:171","msg":"trace[911488461] transaction","detail":"{read_only:false; response_revision:9456; number_of_response:1; }","duration":"114.128383ms","start":"2026-05-27T10:11:28.606304Z","end":"2026-05-27T10:11:28.720432Z","steps":["trace[911488461] 'process raft request' (duration: 113.849537ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:11:57.846755Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"a727d7e7ce5fe36a","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-27T10:11:57.852308Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-05-27T10:11:57.852444Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-05-27T10:14:05.897841Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8120} {"level":"info","ts":"2026-05-27T10:14:05.962623Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8120,"took":"61.38904ms","hash":3331962636,"current-db-size-bytes":56066048,"current-db-size":"56 MB","current-db-size-in-use-bytes":29196288,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-05-27T10:14:05.962701Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3331962636,"revision":8120,"compact-revision":6010} {"level":"warn","ts":"2026-05-27T10:15:13.206394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.204896ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-27T10:15:13.207074Z","caller":"traceutil/trace.go:171","msg":"trace[1777378762] transaction","detail":"{read_only:false; response_revision:11309; number_of_response:1; }","duration":"201.999488ms","start":"2026-05-27T10:15:13.005005Z","end":"2026-05-27T10:15:13.207004Z","steps":["trace[1777378762] 'process raft request' (duration: 26.022615ms)","trace[1777378762] 'compare' (duration: 175.032393ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T10:15:15.101894Z","caller":"traceutil/trace.go:171","msg":"trace[564125249] linearizableReadLoop","detail":"{readStateIndex:11703; appliedIndex:11702; }","duration":"173.789787ms","start":"2026-05-27T10:15:14.928068Z","end":"2026-05-27T10:15:15.101858Z","steps":["trace[564125249] 'read index received' (duration: 173.518811ms)","trace[564125249] 'applied index is now lower than readState.Index' (duration: 269.496µs)"],"step_count":2} {"level":"info","ts":"2026-05-27T10:15:15.101924Z","caller":"traceutil/trace.go:171","msg":"trace[354861431] transaction","detail":"{read_only:false; response_revision:11317; number_of_response:1; }","duration":"174.192925ms","start":"2026-05-27T10:15:14.927701Z","end":"2026-05-27T10:15:15.101894Z","steps":["trace[354861431] 'process raft request' (duration: 173.907259ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:15:15.102139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.041722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-27T10:15:15.102193Z","caller":"traceutil/trace.go:171","msg":"trace[2080528297] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:11317; }","duration":"174.151174ms","start":"2026-05-27T10:15:14.928032Z","end":"2026-05-27T10:15:15.102183Z","steps":["trace[2080528297] 'agreement among raft nodes before linearized reading' (duration: 173.94933ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:19:05.908864Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10649} {"level":"info","ts":"2026-05-27T10:19:05.995894Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10649,"took":"82.67098ms","hash":494299639,"current-db-size-bytes":56066048,"current-db-size":"56 MB","current-db-size-in-use-bytes":26836992,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-05-27T10:19:05.995978Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":494299639,"revision":10649,"compact-revision":8120} {"level":"info","ts":"2026-05-27T10:21:34.780824Z","caller":"traceutil/trace.go:171","msg":"trace[2070170616] transaction","detail":"{read_only:false; response_revision:13865; number_of_response:1; }","duration":"118.778241ms","start":"2026-05-27T10:21:34.662013Z","end":"2026-05-27T10:21:34.780791Z","steps":["trace[2070170616] 'process raft request' (duration: 118.528226ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:21:43.007086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.826717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T10:21:43.007217Z","caller":"traceutil/trace.go:171","msg":"trace[39188065] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:13922; }","duration":"351.008161ms","start":"2026-05-27T10:21:42.656185Z","end":"2026-05-27T10:21:43.007193Z","steps":["trace[39188065] 'range keys from in-memory index tree' (duration: 350.660403ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:21:43.007265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T10:21:42.656159Z","time spent":"351.095523ms","remote":"127.0.0.1:35682","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":29,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"info","ts":"2026-05-27T10:21:43.007321Z","caller":"traceutil/trace.go:171","msg":"trace[423120130] linearizableReadLoop","detail":"{readStateIndex:14400; appliedIndex:14399; }","duration":"158.152774ms","start":"2026-05-27T10:21:42.849131Z","end":"2026-05-27T10:21:43.007284Z","steps":["trace[423120130] 'read index received' (duration: 90.11088ms)","trace[423120130] 'applied index is now lower than readState.Index' (duration: 68.040774ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T10:21:43.007401Z","caller":"traceutil/trace.go:171","msg":"trace[120786303] transaction","detail":"{read_only:false; response_revision:13923; number_of_response:1; }","duration":"198.421856ms","start":"2026-05-27T10:21:42.808943Z","end":"2026-05-27T10:21:43.007365Z","steps":["trace[120786303] 'process raft request' (duration: 130.426853ms)","trace[120786303] 'compare' (duration: 67.771038ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T10:21:43.008089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.941592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-mwcnq\" ","response":"range_response_count:1 size:6530"} {"level":"info","ts":"2026-05-27T10:21:43.008213Z","caller":"traceutil/trace.go:171","msg":"trace[127544516] range","detail":"{range_begin:/registry/pods/openstack/keepalived-mwcnq; range_end:; response_count:1; response_revision:13923; }","duration":"159.100635ms","start":"2026-05-27T10:21:42.849083Z","end":"2026-05-27T10:21:43.008184Z","steps":["trace[127544516] 'agreement among raft nodes before linearized reading' (duration: 158.315078ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:22:04.608153Z","caller":"traceutil/trace.go:171","msg":"trace[1973163239] linearizableReadLoop","detail":"{readStateIndex:14717; appliedIndex:14716; }","duration":"255.729658ms","start":"2026-05-27T10:22:04.352396Z","end":"2026-05-27T10:22:04.608126Z","steps":["trace[1973163239] 'read index received' (duration: 246.750503ms)","trace[1973163239] 'applied index is now lower than readState.Index' (duration: 8.977375ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T10:22:04.608445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.021814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-05-27T10:22:04.608511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.888575ms","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-05-27T10:22:04.608544Z","caller":"traceutil/trace.go:171","msg":"trace[1825857056] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:14225; }","duration":"256.160077ms","start":"2026-05-27T10:22:04.352361Z","end":"2026-05-27T10:22:04.608522Z","steps":["trace[1825857056] 'agreement among raft nodes before linearized reading' (duration: 255.899041ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:22:04.60859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.302017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-05-27T10:22:04.6086Z","caller":"traceutil/trace.go:171","msg":"trace[463324992] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:14225; }","duration":"252.008717ms","start":"2026-05-27T10:22:04.35657Z","end":"2026-05-27T10:22:04.608579Z","steps":["trace[463324992] 'agreement among raft nodes before linearized reading' (duration: 251.743961ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:22:04.608696Z","caller":"traceutil/trace.go:171","msg":"trace[1576870331] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:14225; }","duration":"230.464661ms","start":"2026-05-27T10:22:04.378209Z","end":"2026-05-27T10:22:04.608674Z","steps":["trace[1576870331] 'agreement among raft nodes before linearized reading' (duration: 230.234316ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:22:44.991671Z","caller":"traceutil/trace.go:171","msg":"trace[767767918] transaction","detail":"{read_only:false; response_revision:14733; number_of_response:1; }","duration":"135.246197ms","start":"2026-05-27T10:22:44.856388Z","end":"2026-05-27T10:22:44.991634Z","steps":["trace[767767918] 'process raft request' (duration: 135.010912ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:22:45.003454Z","caller":"traceutil/trace.go:171","msg":"trace[1697901920] transaction","detail":"{read_only:false; response_revision:14734; number_of_response:1; }","duration":"145.497679ms","start":"2026-05-27T10:22:44.857944Z","end":"2026-05-27T10:22:45.003442Z","steps":["trace[1697901920] 'process raft request' (duration: 145.399797ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:22:45.003784Z","caller":"traceutil/trace.go:171","msg":"trace[403954800] linearizableReadLoop","detail":"{readStateIndex:15237; appliedIndex:15234; }","duration":"123.872111ms","start":"2026-05-27T10:22:44.879899Z","end":"2026-05-27T10:22:45.003771Z","steps":["trace[403954800] 'read index received' (duration: 111.484733ms)","trace[403954800] 'applied index is now lower than readState.Index' (duration: 12.386488ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T10:22:45.003858Z","caller":"traceutil/trace.go:171","msg":"trace[2007408381] transaction","detail":"{read_only:false; response_revision:14735; number_of_response:1; }","duration":"144.368045ms","start":"2026-05-27T10:22:44.859481Z","end":"2026-05-27T10:22:45.003849Z","steps":["trace[2007408381] 'process raft request' (duration: 143.931904ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:22:45.004255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.351062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"info","ts":"2026-05-27T10:22:45.004286Z","caller":"traceutil/trace.go:171","msg":"trace[1860627897] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:14735; }","duration":"124.446524ms","start":"2026-05-27T10:22:44.87983Z","end":"2026-05-27T10:22:45.004277Z","steps":["trace[1860627897] 'agreement among raft nodes before linearized reading' (duration: 124.325801ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:23:29.770002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.454727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T10:23:29.77014Z","caller":"traceutil/trace.go:171","msg":"trace[1084306224] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15036; }","duration":"126.655641ms","start":"2026-05-27T10:23:29.643455Z","end":"2026-05-27T10:23:29.770111Z","steps":["trace[1084306224] 'range keys from in-memory index tree' (duration: 126.110889ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:23:29.770479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.69764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-05-27T10:23:29.770583Z","caller":"traceutil/trace.go:171","msg":"trace[575147249] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15036; }","duration":"125.849064ms","start":"2026-05-27T10:23:29.644708Z","end":"2026-05-27T10:23:29.770557Z","steps":["trace[575147249] 'range keys from in-memory index tree' (duration: 125.523927ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:23:29.770669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.216486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T10:23:29.770721Z","caller":"traceutil/trace.go:171","msg":"trace[841386163] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:15036; }","duration":"129.265817ms","start":"2026-05-27T10:23:29.641442Z","end":"2026-05-27T10:23:29.770707Z","steps":["trace[841386163] 'range keys from in-memory index tree' (duration: 129.159155ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:23:29.770838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.319415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T10:23:29.7709Z","caller":"traceutil/trace.go:171","msg":"trace[497226575] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:15036; }","duration":"127.428958ms","start":"2026-05-27T10:23:29.643455Z","end":"2026-05-27T10:23:29.770884Z","steps":["trace[497226575] 'range keys from in-memory index tree' (duration: 127.12186ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:23:29.943804Z","caller":"traceutil/trace.go:171","msg":"trace[877283264] transaction","detail":"{read_only:false; response_revision:15038; number_of_response:1; }","duration":"169.013228ms","start":"2026-05-27T10:23:29.774749Z","end":"2026-05-27T10:23:29.943762Z","steps":["trace[877283264] 'process raft request' (duration: 151.751204ms)","trace[877283264] 'compare' (duration: 16.998799ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T10:24:05.916341Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12814} {"level":"info","ts":"2026-05-27T10:24:05.994651Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12814,"took":"75.948033ms","hash":1763356506,"current-db-size-bytes":56066048,"current-db-size":"56 MB","current-db-size-in-use-bytes":30019584,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-27T10:24:05.994748Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1763356506,"revision":12814,"compact-revision":10649} {"level":"info","ts":"2026-05-27T10:29:05.924218Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15254} {"level":"info","ts":"2026-05-27T10:29:06.022913Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15254,"took":"95.043796ms","hash":562366247,"current-db-size-bytes":56066048,"current-db-size":"56 MB","current-db-size-in-use-bytes":31490048,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-27T10:29:06.023001Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":562366247,"revision":15254,"compact-revision":12814} {"level":"info","ts":"2026-05-27T10:29:19.27103Z","caller":"traceutil/trace.go:171","msg":"trace[833061561] transaction","detail":"{read_only:false; response_revision:17626; number_of_response:1; }","duration":"156.073696ms","start":"2026-05-27T10:29:19.114933Z","end":"2026-05-27T10:29:19.271007Z","steps":["trace[833061561] 'process raft request' (duration: 155.951273ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:29:19.271933Z","caller":"traceutil/trace.go:171","msg":"trace[258071771] linearizableReadLoop","detail":"{readStateIndex:18227; appliedIndex:18225; }","duration":"153.00419ms","start":"2026-05-27T10:29:19.118916Z","end":"2026-05-27T10:29:19.27192Z","steps":["trace[258071771] 'read index received' (duration: 152.053179ms)","trace[258071771] 'applied index is now lower than readState.Index' (duration: 950.021µs)"],"step_count":2} {"level":"info","ts":"2026-05-27T10:29:19.272169Z","caller":"traceutil/trace.go:171","msg":"trace[967919639] transaction","detail":"{read_only:false; response_revision:17627; number_of_response:1; }","duration":"156.752191ms","start":"2026-05-27T10:29:19.115408Z","end":"2026-05-27T10:29:19.27216Z","steps":["trace[967919639] 'process raft request' (duration: 156.454525ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:29:19.27288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.91145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-05-27T10:29:19.272937Z","caller":"traceutil/trace.go:171","msg":"trace[1275618384] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:17627; }","duration":"136.006012ms","start":"2026-05-27T10:29:19.1369Z","end":"2026-05-27T10:29:19.272906Z","steps":["trace[1275618384] 'agreement among raft nodes before linearized reading' (duration: 135.91042ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:29:19.273603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.682056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:94 size:946927"} {"level":"info","ts":"2026-05-27T10:29:19.273636Z","caller":"traceutil/trace.go:171","msg":"trace[1749706644] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:94; response_revision:17627; }","duration":"154.735306ms","start":"2026-05-27T10:29:19.118894Z","end":"2026-05-27T10:29:19.273629Z","steps":["trace[1749706644] 'agreement among raft nodes before linearized reading' (duration: 153.370077ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:29:25.794992Z","caller":"traceutil/trace.go:171","msg":"trace[572495185] linearizableReadLoop","detail":"{readStateIndex:18266; appliedIndex:18265; }","duration":"240.3933ms","start":"2026-05-27T10:29:25.554569Z","end":"2026-05-27T10:29:25.794963Z","steps":["trace[572495185] 'read index received' (duration: 240.166596ms)","trace[572495185] 'applied index is now lower than readState.Index' (duration: 225.174µs)"],"step_count":2} {"level":"info","ts":"2026-05-27T10:29:25.795382Z","caller":"traceutil/trace.go:171","msg":"trace[1142744459] transaction","detail":"{read_only:false; response_revision:17664; number_of_response:1; }","duration":"396.582518ms","start":"2026-05-27T10:29:25.398787Z","end":"2026-05-27T10:29:25.795369Z","steps":["trace[1142744459] 'process raft request' (duration: 396.001056ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:29:25.795504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T10:29:25.398763Z","time spent":"396.657789ms","remote":"127.0.0.1:35380","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":20988,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T10:29:25.795779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.219908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-27T10:29:25.795824Z","caller":"traceutil/trace.go:171","msg":"trace[1059677767] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:17664; }","duration":"241.263219ms","start":"2026-05-27T10:29:25.554549Z","end":"2026-05-27T10:29:25.795812Z","steps":["trace[1059677767] 'agreement among raft nodes before linearized reading' (duration: 241.190787ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:29:25.796046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.258248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-05-27T10:29:25.796145Z","caller":"traceutil/trace.go:171","msg":"trace[409135138] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:17664; }","duration":"106.422852ms","start":"2026-05-27T10:29:25.689698Z","end":"2026-05-27T10:29:25.796121Z","steps":["trace[409135138] 'agreement among raft nodes before linearized reading' (duration: 106.118616ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:29:26.168547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.494787ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-27T10:29:26.168685Z","caller":"traceutil/trace.go:171","msg":"trace[828780422] linearizableReadLoop","detail":"{readStateIndex:18267; appliedIndex:18266; }","duration":"330.946569ms","start":"2026-05-27T10:29:25.837708Z","end":"2026-05-27T10:29:26.168654Z","steps":["trace[828780422] 'read index received' (duration: 39.227508ms)","trace[828780422] 'applied index is now lower than readState.Index' (duration: 291.717651ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T10:29:26.168752Z","caller":"traceutil/trace.go:171","msg":"trace[2105227751] transaction","detail":"{read_only:false; response_revision:17665; number_of_response:1; }","duration":"359.906396ms","start":"2026-05-27T10:29:25.808806Z","end":"2026-05-27T10:29:26.168713Z","steps":["trace[2105227751] 'process raft request' (duration: 68.149024ms)","trace[2105227751] 'compare' (duration: 291.347222ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T10:29:26.168906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.201684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-05-27T10:29:26.168948Z","caller":"traceutil/trace.go:171","msg":"trace[743924892] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:17665; }","duration":"331.287106ms","start":"2026-05-27T10:29:25.83765Z","end":"2026-05-27T10:29:26.168937Z","steps":["trace[743924892] 'agreement among raft nodes before linearized reading' (duration: 331.129933ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:29:26.168937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T10:29:25.808783Z","time spent":"360.059099ms","remote":"127.0.0.1:35460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T10:29:26.168992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T10:29:25.837627Z","time spent":"331.348708ms","remote":"127.0.0.1:35460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":556,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"warn","ts":"2026-05-27T10:29:26.169187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.798322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-05-27T10:29:26.169224Z","caller":"traceutil/trace.go:171","msg":"trace[1359016085] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:17665; }","duration":"322.900794ms","start":"2026-05-27T10:29:25.846315Z","end":"2026-05-27T10:29:26.169216Z","steps":["trace[1359016085] 'agreement among raft nodes before linearized reading' (duration: 322.805782ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:29:26.16925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T10:29:25.846292Z","time spent":"322.953086ms","remote":"127.0.0.1:35460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":506,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-27T10:29:26.169794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.646073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-05-27T10:29:26.169874Z","caller":"traceutil/trace.go:171","msg":"trace[826772254] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:17665; }","duration":"165.772106ms","start":"2026-05-27T10:29:26.004086Z","end":"2026-05-27T10:29:26.169858Z","steps":["trace[826772254] 'agreement among raft nodes before linearized reading' (duration: 165.585252ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:29:26.651807Z","caller":"traceutil/trace.go:171","msg":"trace[1635378550] transaction","detail":"{read_only:false; response_revision:17668; number_of_response:1; }","duration":"475.659569ms","start":"2026-05-27T10:29:26.176107Z","end":"2026-05-27T10:29:26.651766Z","steps":["trace[1635378550] 'process raft request' (duration: 475.525336ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:29:26.651882Z","caller":"traceutil/trace.go:171","msg":"trace[812409466] transaction","detail":"{read_only:false; response_revision:17667; number_of_response:1; }","duration":"476.62176ms","start":"2026-05-27T10:29:26.175223Z","end":"2026-05-27T10:29:26.651845Z","steps":["trace[812409466] 'process raft request' (duration: 476.219631ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:29:26.652052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T10:29:26.175192Z","time spent":"476.767613ms","remote":"127.0.0.1:35460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T10:29:26.652068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T10:29:26.176089Z","time spent":"475.809372ms","remote":"127.0.0.1:35460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":514,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T10:32:52.198664Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"a727d7e7ce5fe36a","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-27T10:32:52.203639Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-05-27T10:32:52.203765Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-05-27T10:34:05.93196Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17524} {"level":"info","ts":"2026-05-27T10:34:06.042663Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17524,"took":"105.78724ms","hash":85485895,"current-db-size-bytes":56066048,"current-db-size":"56 MB","current-db-size-in-use-bytes":34258944,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-05-27T10:34:06.042748Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":85485895,"revision":17524,"compact-revision":15254} {"level":"info","ts":"2026-05-27T10:35:02.17439Z","caller":"traceutil/trace.go:171","msg":"trace[71479545] transaction","detail":"{read_only:false; response_revision:20271; number_of_response:1; }","duration":"125.066068ms","start":"2026-05-27T10:35:02.049291Z","end":"2026-05-27T10:35:02.174333Z","steps":["trace[71479545] 'process raft request' (duration: 109.534521ms)","trace[71479545] 'compare' (duration: 15.380854ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T10:35:03.359234Z","caller":"traceutil/trace.go:171","msg":"trace[1315102878] transaction","detail":"{read_only:false; response_revision:20277; number_of_response:1; }","duration":"142.76028ms","start":"2026-05-27T10:35:03.216445Z","end":"2026-05-27T10:35:03.359206Z","steps":["trace[1315102878] 'process raft request' (duration: 142.569336ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:35:03.671595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.49161ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-27T10:35:03.671783Z","caller":"traceutil/trace.go:171","msg":"trace[1426189427] linearizableReadLoop","detail":"{readStateIndex:20968; appliedIndex:20966; }","duration":"116.767947ms","start":"2026-05-27T10:35:03.554999Z","end":"2026-05-27T10:35:03.671767Z","steps":["trace[1426189427] 'read index received' (duration: 60.621µs)","trace[1426189427] 'applied index is now lower than readState.Index' (duration: 116.706516ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T10:35:03.67189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.903781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-27T10:35:03.671917Z","caller":"traceutil/trace.go:171","msg":"trace[736523876] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20279; }","duration":"116.931111ms","start":"2026-05-27T10:35:03.554978Z","end":"2026-05-27T10:35:03.671909Z","steps":["trace[736523876] 'agreement among raft nodes before linearized reading' (duration: 116.844539ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:35:03.672034Z","caller":"traceutil/trace.go:171","msg":"trace[739121464] transaction","detail":"{read_only:false; response_revision:20279; number_of_response:1; }","duration":"454.258785ms","start":"2026-05-27T10:35:03.217671Z","end":"2026-05-27T10:35:03.67193Z","steps":["trace[739121464] 'process raft request' (duration: 454.040909ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:35:03.672173Z","caller":"traceutil/trace.go:171","msg":"trace[364590014] transaction","detail":"{read_only:false; response_revision:20278; number_of_response:1; }","duration":"455.613004ms","start":"2026-05-27T10:35:03.216523Z","end":"2026-05-27T10:35:03.672136Z","steps":["trace[364590014] 'process raft request' (duration: 272.483339ms)","trace[364590014] 'compare' (duration: 182.356668ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T10:35:03.672274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T10:35:03.217658Z","time spent":"454.499851ms","remote":"127.0.0.1:35460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T10:35:03.67252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T10:35:03.216502Z","time spent":"455.929591ms","remote":"127.0.0.1:35460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T10:35:04.709086Z","caller":"traceutil/trace.go:171","msg":"trace[1393775070] linearizableReadLoop","detail":"{readStateIndex:20974; appliedIndex:20972; }","duration":"118.455615ms","start":"2026-05-27T10:35:04.590605Z","end":"2026-05-27T10:35:04.709061Z","steps":["trace[1393775070] 'read index received' (duration: 33.177798ms)","trace[1393775070] 'applied index is now lower than readState.Index' (duration: 85.276717ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T10:35:04.709402Z","caller":"traceutil/trace.go:171","msg":"trace[112379229] transaction","detail":"{read_only:false; response_revision:20284; number_of_response:1; }","duration":"327.876648ms","start":"2026-05-27T10:35:04.381493Z","end":"2026-05-27T10:35:04.70937Z","steps":["trace[112379229] 'process raft request' (duration: 327.517581ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:35:04.709415Z","caller":"traceutil/trace.go:171","msg":"trace[67736340] transaction","detail":"{read_only:false; response_revision:20283; number_of_response:1; }","duration":"329.32326ms","start":"2026-05-27T10:35:04.380039Z","end":"2026-05-27T10:35:04.709363Z","steps":["trace[67736340] 'process raft request' (duration: 243.721967ms)","trace[67736340] 'compare' (duration: 85.078992ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T10:35:04.709492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.865153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-05-27T10:35:04.709548Z","caller":"traceutil/trace.go:171","msg":"trace[821548854] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:20284; }","duration":"118.973016ms","start":"2026-05-27T10:35:04.590557Z","end":"2026-05-27T10:35:04.70953Z","steps":["trace[821548854] 'agreement among raft nodes before linearized reading' (duration: 118.620598ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:35:04.70955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T10:35:04.381483Z","time spent":"327.99614ms","remote":"127.0.0.1:35460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T10:35:04.709594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T10:35:04.380019Z","time spent":"329.480143ms","remote":"127.0.0.1:35460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T10:39:05.938027Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19946} {"level":"info","ts":"2026-05-27T10:39:06.053938Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19946,"took":"111.590366ms","hash":3672416012,"current-db-size-bytes":56066048,"current-db-size":"56 MB","current-db-size-in-use-bytes":40321024,"current-db-size-in-use":"40 MB"} {"level":"info","ts":"2026-05-27T10:39:06.053998Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3672416012,"revision":19946,"compact-revision":17524} {"level":"info","ts":"2026-05-27T10:39:06.306669Z","caller":"traceutil/trace.go:171","msg":"trace[1130318744] transaction","detail":"{read_only:false; response_revision:22738; number_of_response:1; }","duration":"103.409717ms","start":"2026-05-27T10:39:06.203238Z","end":"2026-05-27T10:39:06.306647Z","steps":["trace[1130318744] 'process raft request' (duration: 103.231084ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:42:46.459681Z","caller":"traceutil/trace.go:171","msg":"trace[839776935] linearizableReadLoop","detail":"{readStateIndex:25765; appliedIndex:25764; }","duration":"164.712393ms","start":"2026-05-27T10:42:46.294941Z","end":"2026-05-27T10:42:46.459653Z","steps":["trace[839776935] 'read index received' (duration: 93.715918ms)","trace[839776935] 'applied index is now lower than readState.Index' (duration: 70.994975ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T10:42:46.459876Z","caller":"traceutil/trace.go:171","msg":"trace[87178578] transaction","detail":"{read_only:false; response_revision:24942; number_of_response:1; }","duration":"206.409176ms","start":"2026-05-27T10:42:46.25345Z","end":"2026-05-27T10:42:46.459859Z","steps":["trace[87178578] 'process raft request' (duration: 135.233256ms)","trace[87178578] 'compare' (duration: 70.72909ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T10:42:46.460091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.134712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"warn","ts":"2026-05-27T10:42:46.460253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.633006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-27T10:42:46.460394Z","caller":"traceutil/trace.go:171","msg":"trace[100580572] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24942; }","duration":"112.84435ms","start":"2026-05-27T10:42:46.347528Z","end":"2026-05-27T10:42:46.460373Z","steps":["trace[100580572] 'agreement among raft nodes before linearized reading' (duration: 112.455972ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:42:46.460314Z","caller":"traceutil/trace.go:171","msg":"trace[1939507305] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24942; }","duration":"165.362457ms","start":"2026-05-27T10:42:46.294892Z","end":"2026-05-27T10:42:46.460254Z","steps":["trace[1939507305] 'agreement among raft nodes before linearized reading' (duration: 165.05211ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:42:46.776791Z","caller":"traceutil/trace.go:171","msg":"trace[1419453369] linearizableReadLoop","detail":"{readStateIndex:25768; appliedIndex:25767; }","duration":"224.789563ms","start":"2026-05-27T10:42:46.551972Z","end":"2026-05-27T10:42:46.776762Z","steps":["trace[1419453369] 'read index received' (duration: 190.032601ms)","trace[1419453369] 'applied index is now lower than readState.Index' (duration: 34.755652ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T10:42:46.776825Z","caller":"traceutil/trace.go:171","msg":"trace[1178333536] transaction","detail":"{read_only:false; response_revision:24945; number_of_response:1; }","duration":"234.040752ms","start":"2026-05-27T10:42:46.542746Z","end":"2026-05-27T10:42:46.776787Z","steps":["trace[1178333536] 'process raft request' (duration: 199.332932ms)","trace[1178333536] 'compare' (duration: 34.345752ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T10:42:46.777023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.039107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-27T10:42:46.777076Z","caller":"traceutil/trace.go:171","msg":"trace[1854939831] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24945; }","duration":"225.15779ms","start":"2026-05-27T10:42:46.551906Z","end":"2026-05-27T10:42:46.777064Z","steps":["trace[1854939831] 'agreement among raft nodes before linearized reading' (duration: 224.985076ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:42:46.853829Z","caller":"traceutil/trace.go:171","msg":"trace[444557446] transaction","detail":"{read_only:false; response_revision:24947; number_of_response:1; }","duration":"157.279612ms","start":"2026-05-27T10:42:46.696511Z","end":"2026-05-27T10:42:46.853791Z","steps":["trace[444557446] 'process raft request' (duration: 157.1911ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:42:46.853904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.849121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-05-27T10:42:46.853946Z","caller":"traceutil/trace.go:171","msg":"trace[331478675] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24947; }","duration":"155.963614ms","start":"2026-05-27T10:42:46.697974Z","end":"2026-05-27T10:42:46.853938Z","steps":["trace[331478675] 'agreement among raft nodes before linearized reading' (duration: 155.802361ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:42:46.854126Z","caller":"traceutil/trace.go:171","msg":"trace[1876136175] transaction","detail":"{read_only:false; response_revision:24946; number_of_response:1; }","duration":"158.658982ms","start":"2026-05-27T10:42:46.69541Z","end":"2026-05-27T10:42:46.854069Z","steps":["trace[1876136175] 'process raft request' (duration: 158.133521ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:42:47.063877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.100917ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-27T10:42:47.063989Z","caller":"traceutil/trace.go:171","msg":"trace[1616002456] linearizableReadLoop","detail":"{readStateIndex:25771; appliedIndex:25770; }","duration":"179.339549ms","start":"2026-05-27T10:42:46.884629Z","end":"2026-05-27T10:42:47.063968Z","steps":["trace[1616002456] 'read index received' (duration: 30.026658ms)","trace[1616002456] 'applied index is now lower than readState.Index' (duration: 149.311491ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T10:42:47.064114Z","caller":"traceutil/trace.go:171","msg":"trace[107716908] transaction","detail":"{read_only:false; response_revision:24948; number_of_response:1; }","duration":"206.239461ms","start":"2026-05-27T10:42:46.857838Z","end":"2026-05-27T10:42:47.064077Z","steps":["trace[107716908] 'process raft request' (duration: 56.834038ms)","trace[107716908] 'compare' (duration: 148.556295ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T10:42:47.064164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.540354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-05-27T10:42:47.064355Z","caller":"traceutil/trace.go:171","msg":"trace[1982659750] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24948; }","duration":"179.755689ms","start":"2026-05-27T10:42:46.884576Z","end":"2026-05-27T10:42:47.064332Z","steps":["trace[1982659750] 'agreement among raft nodes before linearized reading' (duration: 179.445752ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:42:47.080307Z","caller":"traceutil/trace.go:171","msg":"trace[12624910] transaction","detail":"{read_only:false; response_revision:24949; number_of_response:1; }","duration":"105.992313ms","start":"2026-05-27T10:42:46.974289Z","end":"2026-05-27T10:42:47.080281Z","steps":["trace[12624910] 'process raft request' (duration: 105.84753ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:42:47.363465Z","caller":"traceutil/trace.go:171","msg":"trace[2132122490] transaction","detail":"{read_only:false; response_revision:24951; number_of_response:1; }","duration":"306.075362ms","start":"2026-05-27T10:42:47.057374Z","end":"2026-05-27T10:42:47.36345Z","steps":["trace[2132122490] 'process raft request' (duration: 306.021281ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:42:47.363604Z","caller":"traceutil/trace.go:171","msg":"trace[22896144] transaction","detail":"{read_only:false; response_revision:24952; number_of_response:1; }","duration":"294.392678ms","start":"2026-05-27T10:42:47.069202Z","end":"2026-05-27T10:42:47.363594Z","steps":["trace[22896144] 'process raft request' (duration: 294.218465ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:42:47.363493Z","caller":"traceutil/trace.go:171","msg":"trace[615440176] transaction","detail":"{read_only:false; response_revision:24950; number_of_response:1; }","duration":"371.506786ms","start":"2026-05-27T10:42:46.991953Z","end":"2026-05-27T10:42:47.36346Z","steps":["trace[615440176] 'process raft request' (duration: 371.329553ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:42:47.363731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T10:42:47.057353Z","time spent":"306.292736ms","remote":"127.0.0.1:35460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T10:42:47.363833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T10:42:46.991932Z","time spent":"371.827784ms","remote":"127.0.0.1:35460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T10:42:47.392285Z","caller":"traceutil/trace.go:171","msg":"trace[1958949034] transaction","detail":"{read_only:false; response_revision:24953; number_of_response:1; }","duration":"156.871613ms","start":"2026-05-27T10:42:47.235394Z","end":"2026-05-27T10:42:47.392265Z","steps":["trace[1958949034] 'process raft request' (duration: 156.522846ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:42:47.565312Z","caller":"traceutil/trace.go:171","msg":"trace[243170598] transaction","detail":"{read_only:false; response_revision:24954; number_of_response:1; }","duration":"168.875283ms","start":"2026-05-27T10:42:47.39641Z","end":"2026-05-27T10:42:47.565285Z","steps":["trace[243170598] 'process raft request' (duration: 143.79076ms)","trace[243170598] 'compare' (duration: 24.693145ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T10:42:48.234Z","caller":"traceutil/trace.go:171","msg":"trace[12170924] linearizableReadLoop","detail":"{readStateIndex:25778; appliedIndex:25777; }","duration":"271.360209ms","start":"2026-05-27T10:42:47.962616Z","end":"2026-05-27T10:42:48.233976Z","steps":["trace[12170924] 'read index received' (duration: 271.099314ms)","trace[12170924] 'applied index is now lower than readState.Index' (duration: 259.945µs)"],"step_count":2} {"level":"info","ts":"2026-05-27T10:42:48.234133Z","caller":"traceutil/trace.go:171","msg":"trace[2054814682] transaction","detail":"{read_only:false; response_revision:24955; number_of_response:1; }","duration":"308.185106ms","start":"2026-05-27T10:42:47.925933Z","end":"2026-05-27T10:42:48.234118Z","steps":["trace[2054814682] 'process raft request' (duration: 307.87025ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:42:48.234242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T10:42:47.925908Z","time spent":"308.253448ms","remote":"127.0.0.1:35460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T10:42:48.234447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.82837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinedeployments/\" range_end:\"/registry/cluster.x-k8s.io/machinedeployments0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-27T10:42:48.234489Z","caller":"traceutil/trace.go:171","msg":"trace[482581205] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinedeployments/; range_end:/registry/cluster.x-k8s.io/machinedeployments0; response_count:0; response_revision:24955; }","duration":"271.927692ms","start":"2026-05-27T10:42:47.962549Z","end":"2026-05-27T10:42:48.234477Z","steps":["trace[482581205] 'agreement among raft nodes before linearized reading' (duration: 271.855481ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:42:48.542412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.026616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-05-27T10:42:48.542476Z","caller":"traceutil/trace.go:171","msg":"trace[1811010237] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24955; }","duration":"291.125777ms","start":"2026-05-27T10:42:48.251336Z","end":"2026-05-27T10:42:48.542462Z","steps":["trace[1811010237] 'range keys from in-memory index tree' (duration: 290.881771ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:42:48.542804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.98458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-05-27T10:42:48.542877Z","caller":"traceutil/trace.go:171","msg":"trace[963064730] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:24955; }","duration":"141.089523ms","start":"2026-05-27T10:42:48.401772Z","end":"2026-05-27T10:42:48.542862Z","steps":["trace[963064730] 'count revisions from in-memory index tree' (duration: 140.909599ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:42:48.734996Z","caller":"traceutil/trace.go:171","msg":"trace[1353838704] linearizableReadLoop","detail":"{readStateIndex:25780; appliedIndex:25779; }","duration":"176.963458ms","start":"2026-05-27T10:42:48.558002Z","end":"2026-05-27T10:42:48.734965Z","steps":["trace[1353838704] 'read index received' (duration: 122.96626ms)","trace[1353838704] 'applied index is now lower than readState.Index' (duration: 53.996468ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T10:42:48.735034Z","caller":"traceutil/trace.go:171","msg":"trace[172296888] transaction","detail":"{read_only:false; response_revision:24957; number_of_response:1; }","duration":"185.920682ms","start":"2026-05-27T10:42:48.549077Z","end":"2026-05-27T10:42:48.734997Z","steps":["trace[172296888] 'process raft request' (duration: 131.778651ms)","trace[172296888] 'compare' (duration: 53.953467ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T10:42:48.735284Z","caller":"traceutil/trace.go:171","msg":"trace[176256206] transaction","detail":"{read_only:false; response_revision:24958; number_of_response:1; }","duration":"166.203805ms","start":"2026-05-27T10:42:48.56906Z","end":"2026-05-27T10:42:48.735264Z","steps":["trace[176256206] 'process raft request' (duration: 165.872468ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:42:48.735265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.523365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-27T10:42:48.735464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.396779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-05-27T10:42:48.735518Z","caller":"traceutil/trace.go:171","msg":"trace[2126874688] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24958; }","duration":"190.50204ms","start":"2026-05-27T10:42:48.545002Z","end":"2026-05-27T10:42:48.735504Z","steps":["trace[2126874688] 'agreement among raft nodes before linearized reading' (duration: 190.055711ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:42:48.735379Z","caller":"traceutil/trace.go:171","msg":"trace[1643430147] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24958; }","duration":"180.662467ms","start":"2026-05-27T10:42:48.554688Z","end":"2026-05-27T10:42:48.735351Z","steps":["trace[1643430147] 'agreement among raft nodes before linearized reading' (duration: 180.30398ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:42:56.312112Z","caller":"traceutil/trace.go:171","msg":"trace[1817653692] transaction","detail":"{read_only:false; response_revision:25015; number_of_response:1; }","duration":"321.29501ms","start":"2026-05-27T10:42:55.990787Z","end":"2026-05-27T10:42:56.312082Z","steps":["trace[1817653692] 'process raft request' (duration: 320.788559ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:42:56.312488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T10:42:55.990766Z","time spent":"321.438664ms","remote":"127.0.0.1:35460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":544,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T10:42:59.202603Z","caller":"traceutil/trace.go:171","msg":"trace[16667162] transaction","detail":"{read_only:false; response_revision:25037; number_of_response:1; }","duration":"152.630511ms","start":"2026-05-27T10:42:59.049944Z","end":"2026-05-27T10:42:59.202575Z","steps":["trace[16667162] 'process raft request' (duration: 152.431587ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:42:59.272286Z","caller":"traceutil/trace.go:171","msg":"trace[1798278151] transaction","detail":"{read_only:false; response_revision:25038; number_of_response:1; }","duration":"156.589387ms","start":"2026-05-27T10:42:59.115663Z","end":"2026-05-27T10:42:59.272253Z","steps":["trace[1798278151] 'process raft request' (duration: 156.381613ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:42:59.272425Z","caller":"traceutil/trace.go:171","msg":"trace[130395128] linearizableReadLoop","detail":"{readStateIndex:25863; appliedIndex:25861; }","duration":"156.039955ms","start":"2026-05-27T10:42:59.116135Z","end":"2026-05-27T10:42:59.272175Z","steps":["trace[130395128] 'read index received' (duration: 86.236795ms)","trace[130395128] 'applied index is now lower than readState.Index' (duration: 69.80226ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T10:42:59.272563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.408334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-05-27T10:42:59.272648Z","caller":"traceutil/trace.go:171","msg":"trace[508550580] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25038; }","duration":"156.537156ms","start":"2026-05-27T10:42:59.116094Z","end":"2026-05-27T10:42:59.272631Z","steps":["trace[508550580] 'agreement among raft nodes before linearized reading' (duration: 156.298241ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:42:59.659242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.173346ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-27T10:42:59.659439Z","caller":"traceutil/trace.go:171","msg":"trace[1805868182] transaction","detail":"{read_only:false; response_revision:25041; number_of_response:1; }","duration":"251.691355ms","start":"2026-05-27T10:42:59.407724Z","end":"2026-05-27T10:42:59.659415Z","steps":["trace[1805868182] 'process raft request' (duration: 109.259793ms)","trace[1805868182] 'compare' (duration: 142.059884ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T10:42:59.973838Z","caller":"traceutil/trace.go:171","msg":"trace[934750635] transaction","detail":"{read_only:false; response_revision:25042; number_of_response:1; }","duration":"563.415697ms","start":"2026-05-27T10:42:59.410394Z","end":"2026-05-27T10:42:59.97381Z","steps":["trace[934750635] 'process raft request' (duration: 563.035999ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:42:59.974158Z","caller":"traceutil/trace.go:171","msg":"trace[649588505] transaction","detail":"{read_only:false; response_revision:25043; number_of_response:1; }","duration":"538.521369ms","start":"2026-05-27T10:42:59.4356Z","end":"2026-05-27T10:42:59.974121Z","steps":["trace[649588505] 'process raft request' (duration: 538.151861ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:42:59.974092Z","caller":"traceutil/trace.go:171","msg":"trace[2092270144] linearizableReadLoop","detail":"{readStateIndex:25868; appliedIndex:25866; }","duration":"539.620652ms","start":"2026-05-27T10:42:59.434431Z","end":"2026-05-27T10:42:59.974051Z","steps":["trace[2092270144] 'read index received' (duration: 82.464913ms)","trace[2092270144] 'applied index is now lower than readState.Index' (duration: 457.152479ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T10:42:59.974319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T10:42:59.435571Z","time spent":"538.667002ms","remote":"127.0.0.1:35460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T10:42:59.974313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T10:42:59.410371Z","time spent":"563.848046ms","remote":"127.0.0.1:35460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T10:42:59.97436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"539.898478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-05-27T10:42:59.974417Z","caller":"traceutil/trace.go:171","msg":"trace[1165375798] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25043; }","duration":"540.046471ms","start":"2026-05-27T10:42:59.434353Z","end":"2026-05-27T10:42:59.9744Z","steps":["trace[1165375798] 'agreement among raft nodes before linearized reading' (duration: 539.813316ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:42:59.974474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T10:42:59.434305Z","time spent":"540.146813ms","remote":"127.0.0.1:35460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":519,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-27T10:43:00.005433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.477028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1989"} {"level":"info","ts":"2026-05-27T10:43:00.005527Z","caller":"traceutil/trace.go:171","msg":"trace[572840113] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:25044; }","duration":"385.613021ms","start":"2026-05-27T10:42:59.619892Z","end":"2026-05-27T10:43:00.005505Z","steps":["trace[572840113] 'agreement among raft nodes before linearized reading' (duration: 385.270074ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:43:00.00557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T10:42:59.61987Z","time spent":"385.690973ms","remote":"127.0.0.1:35396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":1,"response size":2013,"request content":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" "} {"level":"info","ts":"2026-05-27T10:43:00.005972Z","caller":"traceutil/trace.go:171","msg":"trace[324176259] transaction","detail":"{read_only:false; response_revision:25044; number_of_response:1; }","duration":"139.053749ms","start":"2026-05-27T10:42:59.866905Z","end":"2026-05-27T10:43:00.005959Z","steps":["trace[324176259] 'process raft request' (duration: 138.087797ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:43:00.006195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.289571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-octavia-server-0\" ","response":"range_response_count:1 size:1864"} {"level":"info","ts":"2026-05-27T10:43:00.006247Z","caller":"traceutil/trace.go:171","msg":"trace[294191268] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-octavia-server-0; range_end:; response_count:1; response_revision:25044; }","duration":"254.340222ms","start":"2026-05-27T10:42:59.751892Z","end":"2026-05-27T10:43:00.006232Z","steps":["trace[294191268] 'agreement among raft nodes before linearized reading' (duration: 254.21837ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:43:00.006499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.634911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/subnets/\" range_end:\"/registry/openstack.k-orc.cloud/subnets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-27T10:43:00.006542Z","caller":"traceutil/trace.go:171","msg":"trace[1301060900] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/subnets/; range_end:/registry/openstack.k-orc.cloud/subnets0; response_count:0; response_revision:25044; }","duration":"359.708302ms","start":"2026-05-27T10:42:59.646823Z","end":"2026-05-27T10:43:00.006531Z","steps":["trace[1301060900] 'agreement among raft nodes before linearized reading' (duration: 359.63967ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:43:00.006573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T10:42:59.646806Z","time spent":"359.758483ms","remote":"127.0.0.1:38280","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":30,"request content":"key:\"/registry/openstack.k-orc.cloud/subnets/\" range_end:\"/registry/openstack.k-orc.cloud/subnets0\" count_only:true "} {"level":"warn","ts":"2026-05-27T10:43:00.006793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.919215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-05-27T10:43:00.006827Z","caller":"traceutil/trace.go:171","msg":"trace[454958061] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25044; }","duration":"383.954776ms","start":"2026-05-27T10:42:59.622863Z","end":"2026-05-27T10:43:00.006818Z","steps":["trace[454958061] 'agreement among raft nodes before linearized reading' (duration: 383.865114ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:43:00.006876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T10:42:59.622801Z","time spent":"384.067898ms","remote":"127.0.0.1:35460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":506,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-27T10:43:00.286265Z","caller":"traceutil/trace.go:171","msg":"trace[901924452] transaction","detail":"{read_only:false; response_revision:25046; number_of_response:1; }","duration":"274.833196ms","start":"2026-05-27T10:43:00.011358Z","end":"2026-05-27T10:43:00.286191Z","steps":["trace[901924452] 'process raft request' (duration: 274.677872ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:43:00.286472Z","caller":"traceutil/trace.go:171","msg":"trace[154020589] transaction","detail":"{read_only:false; response_revision:25045; number_of_response:1; }","duration":"307.108544ms","start":"2026-05-27T10:42:59.979327Z","end":"2026-05-27T10:43:00.286435Z","steps":["trace[154020589] 'process raft request' (duration: 263.148452ms)","trace[154020589] 'compare' (duration: 43.42261ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T10:43:00.286625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T10:42:59.979307Z","time spent":"307.239217ms","remote":"127.0.0.1:35460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T10:43:00.286672Z","caller":"traceutil/trace.go:171","msg":"trace[1120609295] linearizableReadLoop","detail":"{readStateIndex:25871; appliedIndex:25870; }","duration":"276.171285ms","start":"2026-05-27T10:43:00.010467Z","end":"2026-05-27T10:43:00.286638Z","steps":["trace[1120609295] 'read index received' (duration: 232.020499ms)","trace[1120609295] 'applied index is now lower than readState.Index' (duration: 44.146996ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T10:43:00.286954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.894209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/openstack/container-infra-registry\" ","response":"range_response_count:1 size:784"} {"level":"info","ts":"2026-05-27T10:43:00.286999Z","caller":"traceutil/trace.go:171","msg":"trace[653549825] range","detail":"{range_begin:/registry/ingress/openstack/container-infra-registry; range_end:; response_count:1; response_revision:25046; }","duration":"275.96068ms","start":"2026-05-27T10:43:00.011021Z","end":"2026-05-27T10:43:00.286981Z","steps":["trace[653549825] 'agreement among raft nodes before linearized reading' (duration: 275.778686ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:43:00.287102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.6298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/policies/\" range_end:\"/registry/rabbitmq.com/policies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-27T10:43:00.287144Z","caller":"traceutil/trace.go:171","msg":"trace[1810263551] range","detail":"{range_begin:/registry/rabbitmq.com/policies/; range_end:/registry/rabbitmq.com/policies0; response_count:0; response_revision:25046; }","duration":"163.747262ms","start":"2026-05-27T10:43:00.123385Z","end":"2026-05-27T10:43:00.287132Z","steps":["trace[1810263551] 'agreement among raft nodes before linearized reading' (duration: 163.67004ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:43:00.28717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.707496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-95d6fda1-9830-43d1-94f0-b4233c568500\" ","response":"range_response_count:1 size:1324"} {"level":"info","ts":"2026-05-27T10:43:00.287213Z","caller":"traceutil/trace.go:171","msg":"trace[812732482] range","detail":"{range_begin:/registry/persistentvolumes/pvc-95d6fda1-9830-43d1-94f0-b4233c568500; range_end:; response_count:1; response_revision:25046; }","duration":"276.788408ms","start":"2026-05-27T10:43:00.01041Z","end":"2026-05-27T10:43:00.287199Z","steps":["trace[812732482] 'agreement among raft nodes before linearized reading' (duration: 276.351479ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:43:00.287215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.805506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/openstack/container-infra\" ","response":"range_response_count:1 size:729"} {"level":"info","ts":"2026-05-27T10:43:00.287281Z","caller":"traceutil/trace.go:171","msg":"trace[2083109954] range","detail":"{range_begin:/registry/ingress/openstack/container-infra; range_end:; response_count:1; response_revision:25046; }","duration":"275.909488ms","start":"2026-05-27T10:43:00.011353Z","end":"2026-05-27T10:43:00.287262Z","steps":["trace[2083109954] 'agreement among raft nodes before linearized reading' (duration: 275.712844ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:43:00.393167Z","caller":"traceutil/trace.go:171","msg":"trace[605793574] transaction","detail":"{read_only:false; response_revision:25048; number_of_response:1; }","duration":"100.115126ms","start":"2026-05-27T10:43:00.293024Z","end":"2026-05-27T10:43:00.393139Z","steps":["trace[605793574] 'process raft request' (duration: 94.370191ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:43:03.51944Z","caller":"traceutil/trace.go:171","msg":"trace[1274359407] transaction","detail":"{read_only:false; response_revision:25070; number_of_response:1; }","duration":"231.750124ms","start":"2026-05-27T10:43:03.287672Z","end":"2026-05-27T10:43:03.519422Z","steps":["trace[1274359407] 'process raft request' (duration: 231.621421ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:43:04.015716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.056508ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-27T10:43:04.016012Z","caller":"traceutil/trace.go:171","msg":"trace[1046622147] transaction","detail":"{read_only:false; response_revision:25072; number_of_response:1; }","duration":"342.56359ms","start":"2026-05-27T10:43:03.673424Z","end":"2026-05-27T10:43:04.015988Z","steps":["trace[1046622147] 'process raft request' (duration: 342.437127ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:43:04.016049Z","caller":"traceutil/trace.go:171","msg":"trace[2089483719] linearizableReadLoop","detail":"{readStateIndex:25897; appliedIndex:25895; }","duration":"636.049458ms","start":"2026-05-27T10:43:03.37997Z","end":"2026-05-27T10:43:04.01602Z","steps":["trace[2089483719] 'read index received' (duration: 139.354625ms)","trace[2089483719] 'applied index is now lower than readState.Index' (duration: 496.693123ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T10:43:04.016121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T10:43:03.673399Z","time spent":"342.664702ms","remote":"127.0.0.1:35460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T10:43:04.016256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"636.292164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-05-27T10:43:04.016279Z","caller":"traceutil/trace.go:171","msg":"trace[1870906954] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25072; }","duration":"636.376596ms","start":"2026-05-27T10:43:03.379898Z","end":"2026-05-27T10:43:04.016275Z","steps":["trace[1870906954] 'agreement among raft nodes before linearized reading' (duration: 636.259283ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:43:04.016296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T10:43:03.379872Z","time spent":"636.419566ms","remote":"127.0.0.1:35460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":533,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-27T10:43:04.016149Z","caller":"traceutil/trace.go:171","msg":"trace[738091886] transaction","detail":"{read_only:false; response_revision:25071; number_of_response:1; }","duration":"674.885929ms","start":"2026-05-27T10:43:03.341255Z","end":"2026-05-27T10:43:04.016141Z","steps":["trace[738091886] 'process raft request' (duration: 462.2661ms)","trace[738091886] 'compare' (duration: 211.917874ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T10:43:04.016543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T10:43:03.341223Z","time spent":"675.281348ms","remote":"127.0.0.1:35460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T10:43:04.420393Z","caller":"traceutil/trace.go:171","msg":"trace[1881594439] transaction","detail":"{read_only:false; response_revision:25073; number_of_response:1; }","duration":"427.389785ms","start":"2026-05-27T10:43:03.992982Z","end":"2026-05-27T10:43:04.420372Z","steps":["trace[1881594439] 'process raft request' (duration: 427.271102ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:43:04.420509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T10:43:03.992956Z","time spent":"427.496997ms","remote":"127.0.0.1:35460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T10:43:04.435165Z","caller":"traceutil/trace.go:171","msg":"trace[1649690778] linearizableReadLoop","detail":"{readStateIndex:25900; appliedIndex:25898; }","duration":"419.026735ms","start":"2026-05-27T10:43:04.016114Z","end":"2026-05-27T10:43:04.435141Z","steps":["trace[1649690778] 'read index received' (duration: 404.088241ms)","trace[1649690778] 'applied index is now lower than readState.Index' (duration: 14.937444ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T10:43:04.435457Z","caller":"traceutil/trace.go:171","msg":"trace[1253962900] transaction","detail":"{read_only:false; response_revision:25074; number_of_response:1; }","duration":"435.317078ms","start":"2026-05-27T10:43:04.000127Z","end":"2026-05-27T10:43:04.435444Z","steps":["trace[1253962900] 'process raft request' (duration: 434.797466ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:43:04.435566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T10:43:04.000107Z","time spent":"435.390119ms","remote":"127.0.0.1:35460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T10:43:04.435746Z","caller":"traceutil/trace.go:171","msg":"trace[1185663859] transaction","detail":"{read_only:false; response_revision:25075; number_of_response:1; }","duration":"415.240772ms","start":"2026-05-27T10:43:04.020495Z","end":"2026-05-27T10:43:04.435736Z","steps":["trace[1185663859] 'process raft request' (duration: 414.562267ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:43:04.435811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T10:43:04.020474Z","time spent":"415.306074ms","remote":"127.0.0.1:35460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T10:43:04.435944Z","caller":"traceutil/trace.go:171","msg":"trace[504352872] transaction","detail":"{read_only:false; response_revision:25076; number_of_response:1; }","duration":"229.485524ms","start":"2026-05-27T10:43:04.206448Z","end":"2026-05-27T10:43:04.435933Z","steps":["trace[504352872] 'process raft request' (duration: 228.652496ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:43:04.436161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"697.612521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium-operator\" ","response":"range_response_count:1 size:539"} {"level":"info","ts":"2026-05-27T10:43:04.436202Z","caller":"traceutil/trace.go:171","msg":"trace[707452493] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium-operator; range_end:; response_count:1; response_revision:25076; }","duration":"697.688132ms","start":"2026-05-27T10:43:03.738505Z","end":"2026-05-27T10:43:04.436193Z","steps":["trace[707452493] 'agreement among raft nodes before linearized reading' (duration: 697.55766ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:43:04.436232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T10:43:03.738478Z","time spent":"697.746643ms","remote":"127.0.0.1:35404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":563,"request content":"key:\"/registry/serviceaccounts/kube-system/cilium-operator\" "} {"level":"warn","ts":"2026-05-27T10:43:04.436403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"740.939498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-27T10:43:04.436446Z","caller":"traceutil/trace.go:171","msg":"trace[579684891] range","detail":"{range_begin:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/; range_end:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0; response_count:0; response_revision:25076; }","duration":"741.007109ms","start":"2026-05-27T10:43:03.695428Z","end":"2026-05-27T10:43:04.436435Z","steps":["trace[579684891] 'agreement among raft nodes before linearized reading' (duration: 740.942197ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:43:04.436477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T10:43:03.695417Z","time spent":"741.050649ms","remote":"127.0.0.1:46252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":128,"response count":0,"response size":30,"request content":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0\" count_only:true "} {"level":"warn","ts":"2026-05-27T10:43:04.436638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"749.042413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/users/\" range_end:\"/registry/rabbitmq.com/users0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-27T10:43:04.436675Z","caller":"traceutil/trace.go:171","msg":"trace[423699925] range","detail":"{range_begin:/registry/rabbitmq.com/users/; range_end:/registry/rabbitmq.com/users0; response_count:0; response_revision:25076; }","duration":"749.120474ms","start":"2026-05-27T10:43:03.687546Z","end":"2026-05-27T10:43:04.436666Z","steps":["trace[423699925] 'agreement among raft nodes before linearized reading' (duration: 749.063103ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:43:04.436728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T10:43:03.687524Z","time spent":"749.173115ms","remote":"127.0.0.1:58568","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":0,"response size":30,"request content":"key:\"/registry/rabbitmq.com/users/\" range_end:\"/registry/rabbitmq.com/users0\" count_only:true "} {"level":"warn","ts":"2026-05-27T10:43:04.436908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.332512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/clusterclasses/\" range_end:\"/registry/cluster.x-k8s.io/clusterclasses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-27T10:43:04.436947Z","caller":"traceutil/trace.go:171","msg":"trace[2137049910] range","detail":"{range_begin:/registry/cluster.x-k8s.io/clusterclasses/; range_end:/registry/cluster.x-k8s.io/clusterclasses0; response_count:0; response_revision:25076; }","duration":"322.415544ms","start":"2026-05-27T10:43:04.114522Z","end":"2026-05-27T10:43:04.436938Z","steps":["trace[2137049910] 'agreement among raft nodes before linearized reading' (duration: 322.353043ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:43:04.436977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T10:43:04.11446Z","time spent":"322.509526ms","remote":"127.0.0.1:45932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":30,"request content":"key:\"/registry/cluster.x-k8s.io/clusterclasses/\" range_end:\"/registry/cluster.x-k8s.io/clusterclasses0\" count_only:true "} {"level":"warn","ts":"2026-05-27T10:43:04.437422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.9937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-05-27T10:43:04.437466Z","caller":"traceutil/trace.go:171","msg":"trace[2000023602] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25076; }","duration":"135.049562ms","start":"2026-05-27T10:43:04.302407Z","end":"2026-05-27T10:43:04.437457Z","steps":["trace[2000023602] 'agreement among raft nodes before linearized reading' (duration: 134.94046ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:43:04.437637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.90976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-05-27T10:43:04.437675Z","caller":"traceutil/trace.go:171","msg":"trace[408089941] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25076; }","duration":"135.974572ms","start":"2026-05-27T10:43:04.301692Z","end":"2026-05-27T10:43:04.437667Z","steps":["trace[408089941] 'agreement among raft nodes before linearized reading' (duration: 135.88843ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:43:04.902678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.988837ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:30"} {"level":"info","ts":"2026-05-27T10:43:04.902984Z","caller":"traceutil/trace.go:171","msg":"trace[356669880] linearizableReadLoop","detail":"{readStateIndex:25903; appliedIndex:25902; }","duration":"459.52887ms","start":"2026-05-27T10:43:04.443413Z","end":"2026-05-27T10:43:04.902942Z","steps":["trace[356669880] 'read index received' (duration: 148.266597ms)","trace[356669880] 'applied index is now lower than readState.Index' (duration: 311.259263ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T10:43:04.903051Z","caller":"traceutil/trace.go:171","msg":"trace[229963838] transaction","detail":"{read_only:false; response_revision:25078; number_of_response:1; }","duration":"456.575436ms","start":"2026-05-27T10:43:04.446456Z","end":"2026-05-27T10:43:04.903032Z","steps":["trace[229963838] 'process raft request' (duration: 456.476744ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:43:04.903067Z","caller":"traceutil/trace.go:171","msg":"trace[406290832] transaction","detail":"{read_only:false; response_revision:25077; number_of_response:1; }","duration":"456.614028ms","start":"2026-05-27T10:43:04.446399Z","end":"2026-05-27T10:43:04.903013Z","steps":["trace[406290832] 'process raft request' (duration: 456.399483ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:43:04.903164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T10:43:04.446452Z","time spent":"456.641738ms","remote":"127.0.0.1:35460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T10:43:04.903263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T10:43:04.446382Z","time spent":"456.791181ms","remote":"127.0.0.1:35460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T10:43:04.903716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.296687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-operator-869df985b8-7zssd\" ","response":"range_response_count:1 size:4695"} {"level":"warn","ts":"2026-05-27T10:43:04.903731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.629724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-27T10:43:04.903786Z","caller":"traceutil/trace.go:171","msg":"trace[1541604467] range","detail":"{range_begin:/registry/pods/kube-system/cilium-operator-869df985b8-7zssd; range_end:; response_count:1; response_revision:25079; }","duration":"460.371579ms","start":"2026-05-27T10:43:04.44338Z","end":"2026-05-27T10:43:04.903752Z","steps":["trace[1541604467] 'agreement among raft nodes before linearized reading' (duration: 459.724685ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:43:04.903821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T10:43:04.443361Z","time spent":"460.45245ms","remote":"127.0.0.1:35380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":4719,"request content":"key:\"/registry/pods/kube-system/cilium-operator-869df985b8-7zssd\" "} {"level":"info","ts":"2026-05-27T10:43:04.903814Z","caller":"traceutil/trace.go:171","msg":"trace[1646884360] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25079; }","duration":"349.749726ms","start":"2026-05-27T10:43:04.554043Z","end":"2026-05-27T10:43:04.903792Z","steps":["trace[1646884360] 'agreement among raft nodes before linearized reading' (duration: 349.599622ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:43:04.903982Z","caller":"traceutil/trace.go:171","msg":"trace[1258777975] transaction","detail":"{read_only:false; response_revision:25079; number_of_response:1; }","duration":"260.491355ms","start":"2026-05-27T10:43:04.643466Z","end":"2026-05-27T10:43:04.903957Z","steps":["trace[1258777975] 'process raft request' (duration: 259.521184ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:43:04.903995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T10:43:04.554027Z","time spent":"349.92609ms","remote":"127.0.0.1:35186","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-27T10:43:04.904092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.835218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-glance-server-0\" ","response":"range_response_count:1 size:1858"} {"level":"info","ts":"2026-05-27T10:43:04.904142Z","caller":"traceutil/trace.go:171","msg":"trace[994920182] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-glance-server-0; range_end:; response_count:1; response_revision:25079; }","duration":"196.883309ms","start":"2026-05-27T10:43:04.707245Z","end":"2026-05-27T10:43:04.904128Z","steps":["trace[994920182] 'agreement among raft nodes before linearized reading' (duration: 196.595213ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:43:05.147644Z","caller":"traceutil/trace.go:171","msg":"trace[576616908] transaction","detail":"{read_only:false; response_revision:25080; number_of_response:1; }","duration":"237.913797ms","start":"2026-05-27T10:43:04.909697Z","end":"2026-05-27T10:43:05.147611Z","steps":["trace[576616908] 'process raft request' (duration: 222.728779ms)","trace[576616908] 'compare' (duration: 14.741849ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T10:43:05.224097Z","caller":"traceutil/trace.go:171","msg":"trace[1365946906] linearizableReadLoop","detail":"{readStateIndex:25911; appliedIndex:25906; }","duration":"168.460283ms","start":"2026-05-27T10:43:05.055611Z","end":"2026-05-27T10:43:05.224071Z","steps":["trace[1365946906] 'read index received' (duration: 76.881483ms)","trace[1365946906] 'applied index is now lower than readState.Index' (duration: 91.57758ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T10:43:05.224281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.643598ms","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:6"} {"level":"info","ts":"2026-05-27T10:43:05.224326Z","caller":"traceutil/trace.go:171","msg":"trace[430046491] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:25084; }","duration":"168.75144ms","start":"2026-05-27T10:43:05.055562Z","end":"2026-05-27T10:43:05.224313Z","steps":["trace[430046491] 'agreement among raft nodes before linearized reading' (duration: 168.614888ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:43:05.224716Z","caller":"traceutil/trace.go:171","msg":"trace[1204729776] transaction","detail":"{read_only:false; response_revision:25081; number_of_response:1; }","duration":"314.545465ms","start":"2026-05-27T10:43:04.910159Z","end":"2026-05-27T10:43:05.224705Z","steps":["trace[1204729776] 'process raft request' (duration: 313.669935ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:43:05.224808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T10:43:04.910129Z","time spent":"314.621377ms","remote":"127.0.0.1:35460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T10:43:05.224997Z","caller":"traceutil/trace.go:171","msg":"trace[1478512500] transaction","detail":"{read_only:false; response_revision:25084; number_of_response:1; }","duration":"296.509365ms","start":"2026-05-27T10:43:04.928462Z","end":"2026-05-27T10:43:05.224971Z","steps":["trace[1478512500] 'process raft request' (duration: 295.575114ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:43:05.225214Z","caller":"traceutil/trace.go:171","msg":"trace[943868902] transaction","detail":"{read_only:false; response_revision:25082; number_of_response:1; }","duration":"314.364899ms","start":"2026-05-27T10:43:04.910838Z","end":"2026-05-27T10:43:05.225203Z","steps":["trace[943868902] 'process raft request' (duration: 313.126572ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:43:05.225336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T10:43:04.910825Z","time spent":"314.419051ms","remote":"127.0.0.1:35460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T10:43:05.225376Z","caller":"traceutil/trace.go:171","msg":"trace[1871915234] transaction","detail":"{read_only:false; response_revision:25083; number_of_response:1; }","duration":"297.107497ms","start":"2026-05-27T10:43:04.928256Z","end":"2026-05-27T10:43:05.225364Z","steps":["trace[1871915234] 'process raft request' (duration: 295.749727ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:43:05.548045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.79754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-27T10:43:05.548156Z","caller":"traceutil/trace.go:171","msg":"trace[2077650660] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:25084; }","duration":"161.959324ms","start":"2026-05-27T10:43:05.386172Z","end":"2026-05-27T10:43:05.548131Z","steps":["trace[2077650660] 'count revisions from in-memory index tree' (duration: 161.666348ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:43:05.548163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.594071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-27T10:43:05.548644Z","caller":"traceutil/trace.go:171","msg":"trace[1324099468] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:0; response_revision:25084; }","duration":"282.107502ms","start":"2026-05-27T10:43:05.266518Z","end":"2026-05-27T10:43:05.548625Z","steps":["trace[1324099468] 'range keys from in-memory index tree' (duration: 281.53401ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:43:05.754881Z","caller":"traceutil/trace.go:171","msg":"trace[1028912571] linearizableReadLoop","detail":"{readStateIndex:25912; appliedIndex:25911; }","duration":"198.964284ms","start":"2026-05-27T10:43:05.555883Z","end":"2026-05-27T10:43:05.754847Z","steps":["trace[1028912571] 'read index received' (duration: 198.651157ms)","trace[1028912571] 'applied index is now lower than readState.Index' (duration: 311.937µs)"],"step_count":2} {"level":"info","ts":"2026-05-27T10:43:05.754969Z","caller":"traceutil/trace.go:171","msg":"trace[1742917690] transaction","detail":"{read_only:false; response_revision:25085; number_of_response:1; }","duration":"230.266691ms","start":"2026-05-27T10:43:05.52466Z","end":"2026-05-27T10:43:05.754927Z","steps":["trace[1742917690] 'process raft request' (duration: 229.984095ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:43:05.755071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.170469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-27T10:43:05.755121Z","caller":"traceutil/trace.go:171","msg":"trace[666570975] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25085; }","duration":"199.26637ms","start":"2026-05-27T10:43:05.55584Z","end":"2026-05-27T10:43:05.755106Z","steps":["trace[666570975] 'agreement among raft nodes before linearized reading' (duration: 199.171188ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:43:05.755327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.019894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-05-27T10:43:05.755421Z","caller":"traceutil/trace.go:171","msg":"trace[1886489048] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25085; }","duration":"198.192957ms","start":"2026-05-27T10:43:05.557207Z","end":"2026-05-27T10:43:05.7554Z","steps":["trace[1886489048] 'agreement among raft nodes before linearized reading' (duration: 197.896951ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:43:06.152627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.29976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5652"} {"level":"info","ts":"2026-05-27T10:43:06.152702Z","caller":"traceutil/trace.go:171","msg":"trace[1574616274] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:25085; }","duration":"296.405241ms","start":"2026-05-27T10:43:05.856278Z","end":"2026-05-27T10:43:06.152683Z","steps":["trace[1574616274] 'range keys from in-memory index tree' (duration: 296.058505ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:43:06.155488Z","caller":"traceutil/trace.go:171","msg":"trace[1228229043] transaction","detail":"{read_only:false; response_revision:25086; number_of_response:1; }","duration":"133.656101ms","start":"2026-05-27T10:43:06.021799Z","end":"2026-05-27T10:43:06.155455Z","steps":["trace[1228229043] 'process raft request' (duration: 133.161651ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:43:06.656227Z","caller":"traceutil/trace.go:171","msg":"trace[937817828] transaction","detail":"{read_only:false; response_revision:25087; number_of_response:1; }","duration":"230.570658ms","start":"2026-05-27T10:43:06.425624Z","end":"2026-05-27T10:43:06.656194Z","steps":["trace[937817828] 'process raft request' (duration: 230.366953ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:43:06.65636Z","caller":"traceutil/trace.go:171","msg":"trace[453681894] linearizableReadLoop","detail":"{readStateIndex:25914; appliedIndex:25914; }","duration":"208.865278ms","start":"2026-05-27T10:43:06.447323Z","end":"2026-05-27T10:43:06.656188Z","steps":["trace[453681894] 'read index received' (duration: 208.846528ms)","trace[453681894] 'applied index is now lower than readState.Index' (duration: 15.78µs)"],"step_count":2} {"level":"warn","ts":"2026-05-27T10:43:06.656656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.301227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-05-27T10:43:06.656716Z","caller":"traceutil/trace.go:171","msg":"trace[1391422157] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25087; }","duration":"209.42451ms","start":"2026-05-27T10:43:06.447278Z","end":"2026-05-27T10:43:06.656702Z","steps":["trace[1391422157] 'agreement among raft nodes before linearized reading' (duration: 208.920689ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:43:07.162301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.030932ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-27T10:43:07.162473Z","caller":"traceutil/trace.go:171","msg":"trace[14633224] transaction","detail":"{read_only:false; response_revision:25088; number_of_response:1; }","duration":"713.111646ms","start":"2026-05-27T10:43:06.449327Z","end":"2026-05-27T10:43:07.162439Z","steps":["trace[14633224] 'process raft request' (duration: 349.841818ms)","trace[14633224] 'compare' (duration: 361.812367ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T10:43:07.162604Z","caller":"traceutil/trace.go:171","msg":"trace[469763846] transaction","detail":"{read_only:false; response_revision:25089; number_of_response:1; }","duration":"502.060721ms","start":"2026-05-27T10:43:06.660521Z","end":"2026-05-27T10:43:07.162581Z","steps":["trace[469763846] 'process raft request' (duration: 501.938278ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:43:07.162681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T10:43:06.449303Z","time spent":"713.280269ms","remote":"127.0.0.1:35460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T10:43:07.162711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T10:43:06.660481Z","time spent":"502.181662ms","remote":"127.0.0.1:35460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T10:43:07.162739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"608.132834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-27T10:43:07.162654Z","caller":"traceutil/trace.go:171","msg":"trace[56722967] linearizableReadLoop","detail":"{readStateIndex:25915; appliedIndex:25914; }","duration":"506.168978ms","start":"2026-05-27T10:43:06.656438Z","end":"2026-05-27T10:43:07.162607Z","steps":["trace[56722967] 'read index received' (duration: 142.728587ms)","trace[56722967] 'applied index is now lower than readState.Index' (duration: 363.434151ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T10:43:07.16285Z","caller":"traceutil/trace.go:171","msg":"trace[775422947] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25089; }","duration":"608.292978ms","start":"2026-05-27T10:43:06.554537Z","end":"2026-05-27T10:43:07.16283Z","steps":["trace[775422947] 'agreement among raft nodes before linearized reading' (duration: 608.070003ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:43:07.162831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"648.920386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachines/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachines0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-27T10:43:07.162895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T10:43:06.554516Z","time spent":"608.36846ms","remote":"127.0.0.1:35192","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-27T10:43:07.16292Z","caller":"traceutil/trace.go:171","msg":"trace[379482108] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackmachines/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackmachines0; response_count:0; response_revision:25089; }","duration":"649.064639ms","start":"2026-05-27T10:43:06.513834Z","end":"2026-05-27T10:43:07.162898Z","steps":["trace[379482108] 'agreement among raft nodes before linearized reading' (duration: 648.887746ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:43:07.162971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T10:43:06.513814Z","time spent":"649.143362ms","remote":"127.0.0.1:46196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":126,"response count":0,"response size":30,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachines/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachines0\" count_only:true "} {"level":"warn","ts":"2026-05-27T10:43:07.163626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"641.927196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinepools/\" range_end:\"/registry/cluster.x-k8s.io/machinepools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-27T10:43:07.163798Z","caller":"traceutil/trace.go:171","msg":"trace[339217853] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinepools/; range_end:/registry/cluster.x-k8s.io/machinepools0; response_count:0; response_revision:25089; }","duration":"642.13518ms","start":"2026-05-27T10:43:06.521636Z","end":"2026-05-27T10:43:07.163771Z","steps":["trace[339217853] 'agreement among raft nodes before linearized reading' (duration: 640.973474ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:43:07.163893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T10:43:06.521617Z","time spent":"642.259733ms","remote":"127.0.0.1:45858","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":30,"request content":"key:\"/registry/cluster.x-k8s.io/machinepools/\" range_end:\"/registry/cluster.x-k8s.io/machinepools0\" count_only:true "} {"level":"warn","ts":"2026-05-27T10:43:07.18583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.810641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-27T10:43:07.185892Z","caller":"traceutil/trace.go:171","msg":"trace[481961189] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:0; response_revision:25090; }","duration":"351.871372ms","start":"2026-05-27T10:43:06.834004Z","end":"2026-05-27T10:43:07.185875Z","steps":["trace[481961189] 'agreement among raft nodes before linearized reading' (duration: 351.75907ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:43:07.185955Z","caller":"traceutil/trace.go:171","msg":"trace[692315219] transaction","detail":"{read_only:false; response_revision:25090; number_of_response:1; }","duration":"275.795967ms","start":"2026-05-27T10:43:06.910116Z","end":"2026-05-27T10:43:07.185912Z","steps":["trace[692315219] 'process raft request' (duration: 275.507231ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:43:07.18608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T10:43:06.833947Z","time spent":"352.028005ms","remote":"127.0.0.1:58548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":0,"response size":30,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" "} {"level":"warn","ts":"2026-05-27T10:43:07.186276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.789872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-05-27T10:43:07.186393Z","caller":"traceutil/trace.go:171","msg":"trace[328035308] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25090; }","duration":"272.951375ms","start":"2026-05-27T10:43:06.913423Z","end":"2026-05-27T10:43:07.186374Z","steps":["trace[328035308] 'agreement among raft nodes before linearized reading' (duration: 272.73501ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:43:07.186613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.085824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-05-27T10:43:07.186661Z","caller":"traceutil/trace.go:171","msg":"trace[1328697341] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25090; }","duration":"277.200317ms","start":"2026-05-27T10:43:06.909448Z","end":"2026-05-27T10:43:07.186649Z","steps":["trace[1328697341] 'agreement among raft nodes before linearized reading' (duration: 277.016333ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:43:07.524668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.734901ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-27T10:43:07.524901Z","caller":"traceutil/trace.go:171","msg":"trace[1821357746] linearizableReadLoop","detail":"{readStateIndex:25921; appliedIndex:25919; }","duration":"329.931808ms","start":"2026-05-27T10:43:07.194948Z","end":"2026-05-27T10:43:07.52488Z","steps":["trace[1821357746] 'read index received' (duration: 28.905605ms)","trace[1821357746] 'applied index is now lower than readState.Index' (duration: 301.024852ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T10:43:07.524941Z","caller":"traceutil/trace.go:171","msg":"trace[1652656116] transaction","detail":"{read_only:false; response_revision:25093; number_of_response:1; }","duration":"333.511454ms","start":"2026-05-27T10:43:07.191399Z","end":"2026-05-27T10:43:07.524911Z","steps":["trace[1652656116] 'process raft request' (duration: 97.430857ms)","trace[1652656116] 'compare' (duration: 235.576165ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T10:43:07.524981Z","caller":"traceutil/trace.go:171","msg":"trace[169307114] transaction","detail":"{read_only:false; response_revision:25094; number_of_response:1; }","duration":"333.482374ms","start":"2026-05-27T10:43:07.191468Z","end":"2026-05-27T10:43:07.52495Z","steps":["trace[169307114] 'process raft request' (duration: 333.321511ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:43:07.525099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T10:43:07.191387Z","time spent":"333.646238ms","remote":"127.0.0.1:35460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T10:43:07.525075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.103771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/openstack/\" range_end:\"/registry/resourcequotas/openstack0\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-27T10:43:07.525137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T10:43:07.191222Z","time spent":"333.846772ms","remote":"127.0.0.1:35460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T10:43:07.525164Z","caller":"traceutil/trace.go:171","msg":"trace[1667233898] range","detail":"{range_begin:/registry/resourcequotas/openstack/; range_end:/registry/resourcequotas/openstack0; response_count:0; response_revision:25094; }","duration":"330.262354ms","start":"2026-05-27T10:43:07.194878Z","end":"2026-05-27T10:43:07.52514Z","steps":["trace[1667233898] 'agreement among raft nodes before linearized reading' (duration: 330.099751ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:43:07.525212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T10:43:07.194846Z","time spent":"330.356696ms","remote":"127.0.0.1:35254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":0,"response size":30,"request content":"key:\"/registry/resourcequotas/openstack/\" range_end:\"/registry/resourcequotas/openstack0\" "} {"level":"info","ts":"2026-05-27T10:43:07.71479Z","caller":"traceutil/trace.go:171","msg":"trace[287465754] transaction","detail":"{read_only:false; response_revision:25095; number_of_response:1; }","duration":"484.05267ms","start":"2026-05-27T10:43:07.2307Z","end":"2026-05-27T10:43:07.714753Z","steps":["trace[287465754] 'process raft request' (duration: 437.888542ms)","trace[287465754] 'compare' (duration: 45.210628ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T10:43:07.714985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T10:43:07.230684Z","time spent":"484.202613ms","remote":"127.0.0.1:35460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T10:43:07.71523Z","caller":"traceutil/trace.go:171","msg":"trace[1168805576] transaction","detail":"{read_only:false; response_revision:25096; number_of_response:1; }","duration":"482.826125ms","start":"2026-05-27T10:43:07.232363Z","end":"2026-05-27T10:43:07.71519Z","steps":["trace[1168805576] 'process raft request' (duration: 482.410816ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:43:07.715342Z","caller":"traceutil/trace.go:171","msg":"trace[273665484] linearizableReadLoop","detail":"{readStateIndex:25923; appliedIndex:25921; }","duration":"190.370158ms","start":"2026-05-27T10:43:07.524949Z","end":"2026-05-27T10:43:07.715319Z","steps":["trace[273665484] 'read index received' (duration: 143.714539ms)","trace[273665484] 'applied index is now lower than readState.Index' (duration: 46.653169ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T10:43:07.715402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T10:43:07.232351Z","time spent":"482.960078ms","remote":"127.0.0.1:35460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T10:43:07.715505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"485.415681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-05-27T10:43:07.71556Z","caller":"traceutil/trace.go:171","msg":"trace[1930523871] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25096; }","duration":"485.508643ms","start":"2026-05-27T10:43:07.230039Z","end":"2026-05-27T10:43:07.715548Z","steps":["trace[1930523871] 'agreement among raft nodes before linearized reading' (duration: 485.35752ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:43:07.715597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T10:43:07.230019Z","time spent":"485.569335ms","remote":"127.0.0.1:35460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":465,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-27T10:43:07.715645Z","caller":"traceutil/trace.go:171","msg":"trace[500876281] transaction","detail":"{read_only:false; response_revision:25097; number_of_response:1; }","duration":"188.092639ms","start":"2026-05-27T10:43:07.52754Z","end":"2026-05-27T10:43:07.715633Z","steps":["trace[500876281] 'process raft request' (duration: 187.66547ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:43:07.715846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"484.554561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-05-27T10:43:07.716645Z","caller":"traceutil/trace.go:171","msg":"trace[1612743929] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25096; }","duration":"484.689855ms","start":"2026-05-27T10:43:07.231227Z","end":"2026-05-27T10:43:07.715917Z","steps":["trace[1612743929] 'agreement among raft nodes before linearized reading' (duration: 484.139883ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:43:07.716789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T10:43:07.231218Z","time spent":"485.516302ms","remote":"127.0.0.1:35460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":582,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-05-27T10:43:07.717606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.481584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-27T10:43:07.717675Z","caller":"traceutil/trace.go:171","msg":"trace[1270876128] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25097; }","duration":"163.025397ms","start":"2026-05-27T10:43:07.554633Z","end":"2026-05-27T10:43:07.717659Z","steps":["trace[1270876128] 'agreement among raft nodes before linearized reading' (duration: 161.077765ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:43:07.894643Z","caller":"traceutil/trace.go:171","msg":"trace[2070614958] linearizableReadLoop","detail":"{readStateIndex:25926; appliedIndex:25925; }","duration":"153.125002ms","start":"2026-05-27T10:43:07.741486Z","end":"2026-05-27T10:43:07.894611Z","steps":["trace[2070614958] 'read index received' (duration: 152.641621ms)","trace[2070614958] 'applied index is now lower than readState.Index' (duration: 481.731µs)"],"step_count":2} {"level":"info","ts":"2026-05-27T10:43:07.894845Z","caller":"traceutil/trace.go:171","msg":"trace[1066297999] transaction","detail":"{read_only:false; response_revision:25099; number_of_response:1; }","duration":"173.078674ms","start":"2026-05-27T10:43:07.721703Z","end":"2026-05-27T10:43:07.894782Z","steps":["trace[1066297999] 'process raft request' (duration: 172.294066ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:43:07.895186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.083164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:1578"} {"level":"info","ts":"2026-05-27T10:43:07.895237Z","caller":"traceutil/trace.go:171","msg":"trace[1499208587] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:25099; }","duration":"173.234877ms","start":"2026-05-27T10:43:07.721988Z","end":"2026-05-27T10:43:07.895223Z","steps":["trace[1499208587] 'agreement among raft nodes before linearized reading' (duration: 172.799158ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:43:07.895759Z","caller":"traceutil/trace.go:171","msg":"trace[776185603] transaction","detail":"{read_only:false; response_revision:25100; number_of_response:1; }","duration":"134.168982ms","start":"2026-05-27T10:43:07.761574Z","end":"2026-05-27T10:43:07.895743Z","steps":["trace[776185603] 'process raft request' (duration: 133.943868ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:43:07.895776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.052166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstoreusers/\" range_end:\"/registry/ceph.rook.io/cephobjectstoreusers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-27T10:43:07.895866Z","caller":"traceutil/trace.go:171","msg":"trace[708887925] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstoreusers/; range_end:/registry/ceph.rook.io/cephobjectstoreusers0; response_count:0; response_revision:25100; }","duration":"138.2011ms","start":"2026-05-27T10:43:07.757644Z","end":"2026-05-27T10:43:07.895845Z","steps":["trace[708887925] 'agreement among raft nodes before linearized reading' (duration: 138.013896ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:43:09.293001Z","caller":"traceutil/trace.go:171","msg":"trace[1612763851] transaction","detail":"{read_only:false; response_revision:25159; number_of_response:1; }","duration":"123.571913ms","start":"2026-05-27T10:43:09.169396Z","end":"2026-05-27T10:43:09.292968Z","steps":["trace[1612763851] 'process raft request' (duration: 123.343558ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:43:09.372114Z","caller":"traceutil/trace.go:171","msg":"trace[295270473] linearizableReadLoop","detail":"{readStateIndex:25988; appliedIndex:25985; }","duration":"143.687958ms","start":"2026-05-27T10:43:09.228394Z","end":"2026-05-27T10:43:09.372082Z","steps":["trace[295270473] 'read index received' (duration: 64.478975ms)","trace[295270473] 'applied index is now lower than readState.Index' (duration: 79.207853ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T10:43:09.372179Z","caller":"traceutil/trace.go:171","msg":"trace[1758531562] transaction","detail":"{read_only:false; response_revision:25161; number_of_response:1; }","duration":"179.698837ms","start":"2026-05-27T10:43:09.192438Z","end":"2026-05-27T10:43:09.372137Z","steps":["trace[1758531562] 'process raft request' (duration: 179.566555ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:43:09.372197Z","caller":"traceutil/trace.go:171","msg":"trace[1430823216] transaction","detail":"{read_only:false; response_revision:25160; number_of_response:1; }","duration":"199.500806ms","start":"2026-05-27T10:43:09.172657Z","end":"2026-05-27T10:43:09.372158Z","steps":["trace[1430823216] 'process raft request' (duration: 175.128728ms)","trace[1430823216] 'compare' (duration: 24.077592ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T10:43:09.372341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.928203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-05-27T10:43:09.372475Z","caller":"traceutil/trace.go:171","msg":"trace[586353054] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25161; }","duration":"144.057666ms","start":"2026-05-27T10:43:09.228341Z","end":"2026-05-27T10:43:09.372399Z","steps":["trace[586353054] 'agreement among raft nodes before linearized reading' (duration: 143.891602ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:43:09.372643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.917293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.130\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-05-27T10:43:09.372735Z","caller":"traceutil/trace.go:171","msg":"trace[1100116734] range","detail":"{range_begin:/registry/masterleases/199.19.213.130; range_end:; response_count:1; response_revision:25161; }","duration":"144.24946ms","start":"2026-05-27T10:43:09.228468Z","end":"2026-05-27T10:43:09.372718Z","steps":["trace[1100116734] 'agreement among raft nodes before linearized reading' (duration: 143.79497ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:43:14.84907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.161972ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:30"} {"level":"info","ts":"2026-05-27T10:43:14.850155Z","caller":"traceutil/trace.go:171","msg":"trace[2064585059] linearizableReadLoop","detail":"{readStateIndex:26054; appliedIndex:26052; }","duration":"305.257523ms","start":"2026-05-27T10:43:14.544864Z","end":"2026-05-27T10:43:14.850122Z","steps":["trace[2064585059] 'read index received' (duration: 29.213641ms)","trace[2064585059] 'applied index is now lower than readState.Index' (duration: 276.041602ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T10:43:14.850291Z","caller":"traceutil/trace.go:171","msg":"trace[1255106521] transaction","detail":"{read_only:false; response_revision:25224; number_of_response:1; }","duration":"392.872609ms","start":"2026-05-27T10:43:14.457406Z","end":"2026-05-27T10:43:14.850279Z","steps":["trace[1255106521] 'process raft request' (duration: 391.788745ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:43:14.850457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T10:43:14.45737Z","time spent":"392.951931ms","remote":"127.0.0.1:35460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":627,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T10:43:15.010068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.187833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-27T10:43:15.010167Z","caller":"traceutil/trace.go:171","msg":"trace[1309992106] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:25224; }","duration":"465.348816ms","start":"2026-05-27T10:43:14.544794Z","end":"2026-05-27T10:43:15.010142Z","steps":["trace[1309992106] 'agreement among raft nodes before linearized reading' (duration: 305.648882ms)","trace[1309992106] 'count revisions from in-memory index tree' (duration: 159.5457ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T10:43:15.010211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T10:43:14.544766Z","time spent":"465.434307ms","remote":"127.0.0.1:51912","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true "} {"level":"info","ts":"2026-05-27T10:43:15.011132Z","caller":"traceutil/trace.go:171","msg":"trace[1038430680] linearizableReadLoop","detail":"{readStateIndex:26055; appliedIndex:26054; }","duration":"160.884189ms","start":"2026-05-27T10:43:14.850236Z","end":"2026-05-27T10:43:15.01112Z","steps":["trace[1038430680] 'read index received' (duration: 87.992762ms)","trace[1038430680] 'applied index is now lower than readState.Index' (duration: 72.890667ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T10:43:15.011331Z","caller":"traceutil/trace.go:171","msg":"trace[652109495] transaction","detail":"{read_only:false; response_revision:25225; number_of_response:1; }","duration":"323.132159ms","start":"2026-05-27T10:43:14.688185Z","end":"2026-05-27T10:43:15.011317Z","steps":["trace[652109495] 'process raft request' (duration: 250.003258ms)","trace[652109495] 'compare' (duration: 72.829495ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T10:43:15.011424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T10:43:14.688138Z","time spent":"323.229441ms","remote":"127.0.0.1:35460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T10:43:15.011676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.798675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-27T10:43:15.01178Z","caller":"traceutil/trace.go:171","msg":"trace[1412060356] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25225; }","duration":"458.919108ms","start":"2026-05-27T10:43:14.552837Z","end":"2026-05-27T10:43:15.011756Z","steps":["trace[1412060356] 'agreement among raft nodes before linearized reading' (duration: 458.736284ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:43:15.011843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T10:43:14.552803Z","time spent":"459.030909ms","remote":"127.0.0.1:35186","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-27T10:43:15.01204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.703744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/clusterclasses/\" range_end:\"/registry/cluster.x-k8s.io/clusterclasses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-27T10:43:15.012091Z","caller":"traceutil/trace.go:171","msg":"trace[292907351] range","detail":"{range_begin:/registry/cluster.x-k8s.io/clusterclasses/; range_end:/registry/cluster.x-k8s.io/clusterclasses0; response_count:0; response_revision:25225; }","duration":"104.794627ms","start":"2026-05-27T10:43:14.907286Z","end":"2026-05-27T10:43:15.01208Z","steps":["trace[292907351] 'agreement among raft nodes before linearized reading' (duration: 104.726735ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:43:15.011784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.258737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:10359"} {"level":"info","ts":"2026-05-27T10:43:15.012334Z","caller":"traceutil/trace.go:171","msg":"trace[1007374005] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:25225; }","duration":"154.830589ms","start":"2026-05-27T10:43:14.857486Z","end":"2026-05-27T10:43:15.012316Z","steps":["trace[1007374005] 'agreement among raft nodes before linearized reading' (duration: 154.197025ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:43:15.01236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.84493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:1860"} {"level":"info","ts":"2026-05-27T10:43:15.012398Z","caller":"traceutil/trace.go:171","msg":"trace[408671373] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:25225; }","duration":"142.887421ms","start":"2026-05-27T10:43:14.869501Z","end":"2026-05-27T10:43:15.012388Z","steps":["trace[408671373] 'agreement among raft nodes before linearized reading' (duration: 142.780299ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:43:24.991147Z","caller":"traceutil/trace.go:171","msg":"trace[1504164797] transaction","detail":"{read_only:false; response_revision:25306; number_of_response:1; }","duration":"124.840271ms","start":"2026-05-27T10:43:24.86628Z","end":"2026-05-27T10:43:24.99112Z","steps":["trace[1504164797] 'process raft request' (duration: 124.653326ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:43:25.295995Z","caller":"traceutil/trace.go:171","msg":"trace[1958747039] linearizableReadLoop","detail":"{readStateIndex:26139; appliedIndex:26138; }","duration":"178.163953ms","start":"2026-05-27T10:43:25.117802Z","end":"2026-05-27T10:43:25.295966Z","steps":["trace[1958747039] 'read index received' (duration: 125.866463ms)","trace[1958747039] 'applied index is now lower than readState.Index' (duration: 52.29615ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T10:43:25.296299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.4789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-27T10:43:25.296353Z","caller":"traceutil/trace.go:171","msg":"trace[203480187] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:25307; }","duration":"178.599753ms","start":"2026-05-27T10:43:25.117745Z","end":"2026-05-27T10:43:25.296344Z","steps":["trace[203480187] 'agreement among raft nodes before linearized reading' (duration: 178.49496ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:43:25.296173Z","caller":"traceutil/trace.go:171","msg":"trace[1288518043] transaction","detail":"{read_only:false; response_revision:25307; number_of_response:1; }","duration":"245.368867ms","start":"2026-05-27T10:43:25.050774Z","end":"2026-05-27T10:43:25.296142Z","steps":["trace[1288518043] 'process raft request' (duration: 192.965684ms)","trace[1288518043] 'compare' (duration: 52.094176ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T10:43:25.296788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.675074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3236"} {"level":"info","ts":"2026-05-27T10:43:25.296834Z","caller":"traceutil/trace.go:171","msg":"trace[1572349739] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:25307; }","duration":"147.761926ms","start":"2026-05-27T10:43:25.149058Z","end":"2026-05-27T10:43:25.29682Z","steps":["trace[1572349739] 'agreement among raft nodes before linearized reading' (duration: 147.628054ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:43:25.538695Z","caller":"traceutil/trace.go:171","msg":"trace[1118111397] linearizableReadLoop","detail":"{readStateIndex:26140; appliedIndex:26139; }","duration":"224.475286ms","start":"2026-05-27T10:43:25.314186Z","end":"2026-05-27T10:43:25.538661Z","steps":["trace[1118111397] 'read index received' (duration: 224.315592ms)","trace[1118111397] 'applied index is now lower than readState.Index' (duration: 157.463µs)"],"step_count":2} {"level":"info","ts":"2026-05-27T10:43:25.538839Z","caller":"traceutil/trace.go:171","msg":"trace[1817703754] transaction","detail":"{read_only:false; response_revision:25308; number_of_response:1; }","duration":"225.59842ms","start":"2026-05-27T10:43:25.313164Z","end":"2026-05-27T10:43:25.538762Z","steps":["trace[1817703754] 'process raft request' (duration: 225.312044ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:43:25.538976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.768102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-manila-server\" ","response":"range_response_count:1 size:9602"} {"level":"info","ts":"2026-05-27T10:43:25.539031Z","caller":"traceutil/trace.go:171","msg":"trace[1715364223] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-manila-server; range_end:; response_count:1; response_revision:25308; }","duration":"224.862804ms","start":"2026-05-27T10:43:25.314155Z","end":"2026-05-27T10:43:25.539018Z","steps":["trace[1715364223] 'agreement among raft nodes before linearized reading' (duration: 224.642419ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:43:25.821099Z","caller":"traceutil/trace.go:171","msg":"trace[107691465] linearizableReadLoop","detail":"{readStateIndex:26142; appliedIndex:26140; }","duration":"282.293537ms","start":"2026-05-27T10:43:25.538773Z","end":"2026-05-27T10:43:25.821067Z","steps":["trace[107691465] 'read index received' (duration: 260.119157ms)","trace[107691465] 'applied index is now lower than readState.Index' (duration: 22.172739ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T10:43:25.821113Z","caller":"traceutil/trace.go:171","msg":"trace[1808105777] transaction","detail":"{read_only:false; response_revision:25310; number_of_response:1; }","duration":"389.875794ms","start":"2026-05-27T10:43:25.431213Z","end":"2026-05-27T10:43:25.821088Z","steps":["trace[1808105777] 'process raft request' (duration: 389.783002ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:43:25.821309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.928089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-05-27T10:43:25.821355Z","caller":"traceutil/trace.go:171","msg":"trace[1914123505] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25310; }","duration":"374.00446ms","start":"2026-05-27T10:43:25.447343Z","end":"2026-05-27T10:43:25.821347Z","steps":["trace[1914123505] 'agreement among raft nodes before linearized reading' (duration: 373.833867ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:43:25.821381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T10:43:25.447336Z","time spent":"374.039221ms","remote":"127.0.0.1:35460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":506,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-27T10:43:25.821353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T10:43:25.431191Z","time spent":"390.055827ms","remote":"127.0.0.1:35460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T10:43:25.821425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.496779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-05-27T10:43:25.821476Z","caller":"traceutil/trace.go:171","msg":"trace[612485922] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25310; }","duration":"379.603962ms","start":"2026-05-27T10:43:25.441857Z","end":"2026-05-27T10:43:25.821461Z","steps":["trace[612485922] 'agreement among raft nodes before linearized reading' (duration: 379.360607ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:43:25.821524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T10:43:25.441842Z","time spent":"379.668673ms","remote":"127.0.0.1:35460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":533,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-27T10:43:25.821876Z","caller":"traceutil/trace.go:171","msg":"trace[566636315] transaction","detail":"{read_only:false; response_revision:25309; number_of_response:1; }","duration":"472.794807ms","start":"2026-05-27T10:43:25.348244Z","end":"2026-05-27T10:43:25.821038Z","steps":["trace[566636315] 'process raft request' (duration: 450.499805ms)","trace[566636315] 'compare' (duration: 21.707269ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T10:43:25.822033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T10:43:25.348197Z","time spent":"473.721437ms","remote":"127.0.0.1:35460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T10:43:25.823389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.065719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-27T10:43:25.823499Z","caller":"traceutil/trace.go:171","msg":"trace[887006869] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25312; }","duration":"213.215272ms","start":"2026-05-27T10:43:25.610261Z","end":"2026-05-27T10:43:25.823477Z","steps":["trace[887006869] 'agreement among raft nodes before linearized reading' (duration: 212.971417ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:43:25.823583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.969441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/rabbitmq-manila.18b366254c96bcf0\" ","response":"range_response_count:1 size:736"} {"level":"info","ts":"2026-05-27T10:43:25.823658Z","caller":"traceutil/trace.go:171","msg":"trace[109450614] range","detail":"{range_begin:/registry/events/openstack/rabbitmq-manila.18b366254c96bcf0; range_end:; response_count:1; response_revision:25312; }","duration":"277.074763ms","start":"2026-05-27T10:43:25.546569Z","end":"2026-05-27T10:43:25.823644Z","steps":["trace[109450614] 'agreement among raft nodes before linearized reading' (duration: 276.835278ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:43:25.823888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.735918ms","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:8"} {"level":"warn","ts":"2026-05-27T10:43:25.823924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.385397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-27T10:43:25.823932Z","caller":"traceutil/trace.go:171","msg":"trace[1249105697] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:25312; }","duration":"265.80201ms","start":"2026-05-27T10:43:25.558119Z","end":"2026-05-27T10:43:25.823921Z","steps":["trace[1249105697] 'agreement among raft nodes before linearized reading' (duration: 265.662307ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:43:25.823946Z","caller":"traceutil/trace.go:171","msg":"trace[1190016971] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25312; }","duration":"269.420328ms","start":"2026-05-27T10:43:25.554519Z","end":"2026-05-27T10:43:25.823939Z","steps":["trace[1190016971] 'agreement among raft nodes before linearized reading' (duration: 269.381517ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:43:25.823974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.704487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:1860"} {"level":"info","ts":"2026-05-27T10:43:25.824038Z","caller":"traceutil/trace.go:171","msg":"trace[1721300998] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:25312; }","duration":"277.786398ms","start":"2026-05-27T10:43:25.546236Z","end":"2026-05-27T10:43:25.824022Z","steps":["trace[1721300998] 'agreement among raft nodes before linearized reading' (duration: 277.527843ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:43:25.823432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.040289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-05-27T10:43:25.824252Z","caller":"traceutil/trace.go:171","msg":"trace[1666142616] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25312; }","duration":"213.880456ms","start":"2026-05-27T10:43:25.610361Z","end":"2026-05-27T10:43:25.824241Z","steps":["trace[1666142616] 'agreement among raft nodes before linearized reading' (duration: 212.859404ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:43:26.127927Z","caller":"traceutil/trace.go:171","msg":"trace[1455663966] transaction","detail":"{read_only:false; response_revision:25314; number_of_response:1; }","duration":"297.883353ms","start":"2026-05-27T10:43:25.830009Z","end":"2026-05-27T10:43:26.127892Z","steps":["trace[1455663966] 'process raft request' (duration: 297.529906ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:43:26.128378Z","caller":"traceutil/trace.go:171","msg":"trace[765998143] transaction","detail":"{read_only:false; response_revision:25315; number_of_response:1; }","duration":"298.21504ms","start":"2026-05-27T10:43:25.830103Z","end":"2026-05-27T10:43:26.128318Z","steps":["trace[765998143] 'process raft request' (duration: 297.72011ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:43:26.128547Z","caller":"traceutil/trace.go:171","msg":"trace[1831365695] transaction","detail":"{read_only:false; response_revision:25316; number_of_response:1; }","duration":"298.348025ms","start":"2026-05-27T10:43:25.830174Z","end":"2026-05-27T10:43:26.128522Z","steps":["trace[1831365695] 'process raft request' (duration: 297.979146ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:43:26.128669Z","caller":"traceutil/trace.go:171","msg":"trace[1800739978] linearizableReadLoop","detail":"{readStateIndex:26149; appliedIndex:26145; }","duration":"271.80093ms","start":"2026-05-27T10:43:25.856853Z","end":"2026-05-27T10:43:26.128654Z","steps":["trace[1800739978] 'read index received' (duration: 270.645114ms)","trace[1800739978] 'applied index is now lower than readState.Index' (duration: 1.154466ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T10:43:26.12875Z","caller":"traceutil/trace.go:171","msg":"trace[1488802025] transaction","detail":"{read_only:false; response_revision:25317; number_of_response:1; }","duration":"297.781462ms","start":"2026-05-27T10:43:25.830943Z","end":"2026-05-27T10:43:26.128725Z","steps":["trace[1488802025] 'process raft request' (duration: 297.535257ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:43:26.128966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.495242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-manila-server\" ","response":"range_response_count:1 size:9602"} {"level":"warn","ts":"2026-05-27T10:43:26.128978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.574028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-05-27T10:43:26.129023Z","caller":"traceutil/trace.go:171","msg":"trace[1917355258] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-manila-server; range_end:; response_count:1; response_revision:25317; }","duration":"295.616135ms","start":"2026-05-27T10:43:25.833395Z","end":"2026-05-27T10:43:26.129011Z","steps":["trace[1917355258] 'agreement among raft nodes before linearized reading' (duration: 295.347069ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:43:26.129049Z","caller":"traceutil/trace.go:171","msg":"trace[507276528] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25317; }","duration":"297.685201ms","start":"2026-05-27T10:43:25.831348Z","end":"2026-05-27T10:43:26.129033Z","steps":["trace[507276528] 'agreement among raft nodes before linearized reading' (duration: 297.421985ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:43:26.149315Z","caller":"traceutil/trace.go:171","msg":"trace[1358593691] transaction","detail":"{read_only:false; response_revision:25318; number_of_response:1; }","duration":"188.032907ms","start":"2026-05-27T10:43:25.961227Z","end":"2026-05-27T10:43:26.14926Z","steps":["trace[1358593691] 'process raft request' (duration: 187.70629ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:43:26.149474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.211368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-05-27T10:43:26.149657Z","caller":"traceutil/trace.go:171","msg":"trace[953317752] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25318; }","duration":"168.412323ms","start":"2026-05-27T10:43:25.981206Z","end":"2026-05-27T10:43:26.149619Z","steps":["trace[953317752] 'agreement among raft nodes before linearized reading' (duration: 168.004334ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:43:26.33535Z","caller":"traceutil/trace.go:171","msg":"trace[1221548899] transaction","detail":"{read_only:false; response_revision:25320; number_of_response:1; }","duration":"182.690902ms","start":"2026-05-27T10:43:26.152629Z","end":"2026-05-27T10:43:26.33532Z","steps":["trace[1221548899] 'process raft request' (duration: 181.816093ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:43:26.335653Z","caller":"traceutil/trace.go:171","msg":"trace[625068598] transaction","detail":"{read_only:false; response_revision:25322; number_of_response:1; }","duration":"102.223171ms","start":"2026-05-27T10:43:26.233212Z","end":"2026-05-27T10:43:26.335435Z","steps":["trace[625068598] 'process raft request' (duration: 102.070087ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:43:26.335745Z","caller":"traceutil/trace.go:171","msg":"trace[1498664411] transaction","detail":"{read_only:false; response_revision:25321; number_of_response:1; }","duration":"178.93034ms","start":"2026-05-27T10:43:26.156777Z","end":"2026-05-27T10:43:26.335707Z","steps":["trace[1498664411] 'process raft request' (duration: 178.396598ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:43:32.090697Z","caller":"traceutil/trace.go:171","msg":"trace[2110547762] transaction","detail":"{read_only:false; response_revision:25358; number_of_response:1; }","duration":"245.518552ms","start":"2026-05-27T10:43:31.845149Z","end":"2026-05-27T10:43:32.090667Z","steps":["trace[2110547762] 'process raft request' (duration: 243.407506ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:43:32.090979Z","caller":"traceutil/trace.go:171","msg":"trace[752428981] transaction","detail":"{read_only:false; response_revision:25359; number_of_response:1; }","duration":"244.365306ms","start":"2026-05-27T10:43:31.846603Z","end":"2026-05-27T10:43:32.090968Z","steps":["trace[752428981] 'process raft request' (duration: 244.004309ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:43:32.091007Z","caller":"traceutil/trace.go:171","msg":"trace[1000901810] transaction","detail":"{read_only:false; response_revision:25360; number_of_response:1; }","duration":"244.143282ms","start":"2026-05-27T10:43:31.846841Z","end":"2026-05-27T10:43:32.090985Z","steps":["trace[1000901810] 'process raft request' (duration: 244.022119ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:43:32.321678Z","caller":"traceutil/trace.go:171","msg":"trace[105960673] transaction","detail":"{read_only:false; response_revision:25362; number_of_response:1; }","duration":"158.352685ms","start":"2026-05-27T10:43:32.163297Z","end":"2026-05-27T10:43:32.32165Z","steps":["trace[105960673] 'process raft request' (duration: 158.14905ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:43:32.321983Z","caller":"traceutil/trace.go:171","msg":"trace[1315981096] transaction","detail":"{read_only:false; response_revision:25363; number_of_response:1; }","duration":"158.435827ms","start":"2026-05-27T10:43:32.163532Z","end":"2026-05-27T10:43:32.321968Z","steps":["trace[1315981096] 'process raft request' (duration: 158.067769ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:43:32.322251Z","caller":"traceutil/trace.go:171","msg":"trace[1193550878] linearizableReadLoop","detail":"{readStateIndex:26198; appliedIndex:26193; }","duration":"149.398942ms","start":"2026-05-27T10:43:32.172832Z","end":"2026-05-27T10:43:32.322231Z","steps":["trace[1193550878] 'read index received' (duration: 15.137568ms)","trace[1193550878] 'applied index is now lower than readState.Index' (duration: 134.260284ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T10:43:32.322369Z","caller":"traceutil/trace.go:171","msg":"trace[729346677] transaction","detail":"{read_only:false; response_revision:25364; number_of_response:1; }","duration":"157.196202ms","start":"2026-05-27T10:43:32.165114Z","end":"2026-05-27T10:43:32.322311Z","steps":["trace[729346677] 'process raft request' (duration: 156.780362ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:43:32.322389Z","caller":"traceutil/trace.go:171","msg":"trace[8917905] transaction","detail":"{read_only:false; response_revision:25365; number_of_response:1; }","duration":"151.132689ms","start":"2026-05-27T10:43:32.171238Z","end":"2026-05-27T10:43:32.32237Z","steps":["trace[8917905] 'process raft request' (duration: 150.899343ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:43:32.32267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.783821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-05-27T10:43:32.322782Z","caller":"traceutil/trace.go:171","msg":"trace[197959020] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25365; }","duration":"149.966954ms","start":"2026-05-27T10:43:32.17279Z","end":"2026-05-27T10:43:32.322757Z","steps":["trace[197959020] 'agreement among raft nodes before linearized reading' (duration: 149.592436ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:43:32.470545Z","caller":"traceutil/trace.go:171","msg":"trace[602106986] linearizableReadLoop","detail":"{readStateIndex:26200; appliedIndex:26198; }","duration":"109.5375ms","start":"2026-05-27T10:43:32.360984Z","end":"2026-05-27T10:43:32.470521Z","steps":["trace[602106986] 'read index received' (duration: 33.187939ms)","trace[602106986] 'applied index is now lower than readState.Index' (duration: 76.348561ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T10:43:32.470611Z","caller":"traceutil/trace.go:171","msg":"trace[1606818055] transaction","detail":"{read_only:false; response_revision:25367; number_of_response:1; }","duration":"114.297851ms","start":"2026-05-27T10:43:32.356288Z","end":"2026-05-27T10:43:32.470586Z","steps":["trace[1606818055] 'process raft request' (duration: 114.176999ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:43:32.470594Z","caller":"traceutil/trace.go:171","msg":"trace[72596922] transaction","detail":"{read_only:false; response_revision:25366; number_of_response:1; }","duration":"143.7591ms","start":"2026-05-27T10:43:32.326794Z","end":"2026-05-27T10:43:32.470553Z","steps":["trace[72596922] 'process raft request' (duration: 67.359727ms)","trace[72596922] 'compare' (duration: 76.128857ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T10:43:32.470739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.744935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-05-27T10:43:32.470786Z","caller":"traceutil/trace.go:171","msg":"trace[478934423] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25367; }","duration":"109.855237ms","start":"2026-05-27T10:43:32.360918Z","end":"2026-05-27T10:43:32.470773Z","steps":["trace[478934423] 'agreement among raft nodes before linearized reading' (duration: 109.702373ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:43:33.547634Z","caller":"traceutil/trace.go:171","msg":"trace[619530063] transaction","detail":"{read_only:false; response_revision:25372; number_of_response:1; }","duration":"110.652664ms","start":"2026-05-27T10:43:33.436937Z","end":"2026-05-27T10:43:33.54759Z","steps":["trace[619530063] 'process raft request' (duration: 110.347058ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:43:33.54779Z","caller":"traceutil/trace.go:171","msg":"trace[1262068681] transaction","detail":"{read_only:false; response_revision:25374; number_of_response:1; }","duration":"102.037087ms","start":"2026-05-27T10:43:33.445728Z","end":"2026-05-27T10:43:33.547765Z","steps":["trace[1262068681] 'process raft request' (duration: 101.779982ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:43:33.547785Z","caller":"traceutil/trace.go:171","msg":"trace[1145919405] transaction","detail":"{read_only:false; response_revision:25373; number_of_response:1; }","duration":"110.203024ms","start":"2026-05-27T10:43:33.437546Z","end":"2026-05-27T10:43:33.547749Z","steps":["trace[1145919405] 'process raft request' (duration: 109.915637ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:43:36.701873Z","caller":"traceutil/trace.go:171","msg":"trace[342841520] linearizableReadLoop","detail":"{readStateIndex:26241; appliedIndex:26240; }","duration":"197.415111ms","start":"2026-05-27T10:43:36.504431Z","end":"2026-05-27T10:43:36.701846Z","steps":["trace[342841520] 'read index received' (duration: 128.535301ms)","trace[342841520] 'applied index is now lower than readState.Index' (duration: 68.87823ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T10:43:36.701982Z","caller":"traceutil/trace.go:171","msg":"trace[1784444539] transaction","detail":"{read_only:false; response_revision:25406; number_of_response:1; }","duration":"202.349487ms","start":"2026-05-27T10:43:36.499594Z","end":"2026-05-27T10:43:36.701944Z","steps":["trace[1784444539] 'process raft request' (duration: 133.353584ms)","trace[1784444539] 'compare' (duration: 68.707527ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T10:43:36.702195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.729907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-05-27T10:43:36.702284Z","caller":"traceutil/trace.go:171","msg":"trace[846958550] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25406; }","duration":"197.870281ms","start":"2026-05-27T10:43:36.504394Z","end":"2026-05-27T10:43:36.702264Z","steps":["trace[846958550] 'agreement among raft nodes before linearized reading' (duration: 197.604685ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:43:36.702352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.94502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-27T10:43:36.702411Z","caller":"traceutil/trace.go:171","msg":"trace[1183732770] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25406; }","duration":"148.089544ms","start":"2026-05-27T10:43:36.554309Z","end":"2026-05-27T10:43:36.702398Z","steps":["trace[1183732770] 'agreement among raft nodes before linearized reading' (duration: 147.952901ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:43:37.798451Z","caller":"traceutil/trace.go:171","msg":"trace[173134280] transaction","detail":"{read_only:false; response_revision:25409; number_of_response:1; }","duration":"127.421106ms","start":"2026-05-27T10:43:37.671005Z","end":"2026-05-27T10:43:37.798427Z","steps":["trace[173134280] 'process raft request' (duration: 127.214281ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:43:40.131971Z","caller":"traceutil/trace.go:171","msg":"trace[645908223] transaction","detail":"{read_only:false; response_revision:25425; number_of_response:1; }","duration":"118.495742ms","start":"2026-05-27T10:43:40.013443Z","end":"2026-05-27T10:43:40.131938Z","steps":["trace[645908223] 'process raft request' (duration: 88.119936ms)","trace[645908223] 'compare' (duration: 30.255604ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T10:43:40.287139Z","caller":"traceutil/trace.go:171","msg":"trace[1381088747] transaction","detail":"{read_only:false; response_revision:25427; number_of_response:1; }","duration":"168.238509ms","start":"2026-05-27T10:43:40.118876Z","end":"2026-05-27T10:43:40.287114Z","steps":["trace[1381088747] 'process raft request' (duration: 168.037035ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:43:40.287381Z","caller":"traceutil/trace.go:171","msg":"trace[1801672685] transaction","detail":"{read_only:false; response_revision:25428; number_of_response:1; }","duration":"166.597854ms","start":"2026-05-27T10:43:40.120749Z","end":"2026-05-27T10:43:40.287347Z","steps":["trace[1801672685] 'process raft request' (duration: 166.309508ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:43:40.495687Z","caller":"traceutil/trace.go:171","msg":"trace[1340753049] transaction","detail":"{read_only:false; response_revision:25429; number_of_response:1; }","duration":"203.697546ms","start":"2026-05-27T10:43:40.291949Z","end":"2026-05-27T10:43:40.495646Z","steps":["trace[1340753049] 'process raft request' (duration: 147.00731ms)","trace[1340753049] 'compare' (duration: 56.150675ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T10:43:40.495452Z","caller":"traceutil/trace.go:171","msg":"trace[900579244] linearizableReadLoop","detail":"{readStateIndex:26265; appliedIndex:26264; }","duration":"199.72594ms","start":"2026-05-27T10:43:40.295685Z","end":"2026-05-27T10:43:40.495411Z","steps":["trace[900579244] 'read index received' (duration: 143.325491ms)","trace[900579244] 'applied index is now lower than readState.Index' (duration: 56.398879ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T10:43:40.495853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.144949ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagerconfigs/\" range_end:\"/registry/monitoring.coreos.com/alertmanagerconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-27T10:43:40.495944Z","caller":"traceutil/trace.go:171","msg":"trace[94708216] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagerconfigs/; range_end:/registry/monitoring.coreos.com/alertmanagerconfigs0; response_count:0; response_revision:25429; }","duration":"200.293602ms","start":"2026-05-27T10:43:40.295635Z","end":"2026-05-27T10:43:40.495928Z","steps":["trace[94708216] 'agreement among raft nodes before linearized reading' (duration: 200.120539ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:43:40.498972Z","caller":"traceutil/trace.go:171","msg":"trace[198734835] transaction","detail":"{read_only:false; response_revision:25430; number_of_response:1; }","duration":"147.338317ms","start":"2026-05-27T10:43:40.351614Z","end":"2026-05-27T10:43:40.498952Z","steps":["trace[198734835] 'process raft request' (duration: 147.02059ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:43:40.498967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.635435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-05-27T10:43:40.499043Z","caller":"traceutil/trace.go:171","msg":"trace[535221972] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25430; }","duration":"142.758758ms","start":"2026-05-27T10:43:40.356265Z","end":"2026-05-27T10:43:40.499024Z","steps":["trace[535221972] 'agreement among raft nodes before linearized reading' (duration: 142.547814ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:43:40.499049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.374484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-05-27T10:43:40.499148Z","caller":"traceutil/trace.go:171","msg":"trace[1894438963] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25430; }","duration":"139.503287ms","start":"2026-05-27T10:43:40.359624Z","end":"2026-05-27T10:43:40.499127Z","steps":["trace[1894438963] 'agreement among raft nodes before linearized reading' (duration: 139.258282ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:43:40.499156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.98347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-05-27T10:43:40.499141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.880896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-05-27T10:43:40.499372Z","caller":"traceutil/trace.go:171","msg":"trace[1295541181] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25430; }","duration":"140.10447ms","start":"2026-05-27T10:43:40.359211Z","end":"2026-05-27T10:43:40.499316Z","steps":["trace[1295541181] 'agreement among raft nodes before linearized reading' (duration: 139.711102ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:43:40.499254Z","caller":"traceutil/trace.go:171","msg":"trace[1207563726] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:25430; }","duration":"129.139774ms","start":"2026-05-27T10:43:40.370092Z","end":"2026-05-27T10:43:40.499232Z","steps":["trace[1207563726] 'agreement among raft nodes before linearized reading' (duration: 128.939049ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:43:53.574612Z","caller":"traceutil/trace.go:171","msg":"trace[522633283] transaction","detail":"{read_only:false; response_revision:25526; number_of_response:1; }","duration":"106.613056ms","start":"2026-05-27T10:43:53.467973Z","end":"2026-05-27T10:43:53.574586Z","steps":["trace[522633283] 'process raft request' (duration: 106.34371ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:44:03.739138Z","caller":"traceutil/trace.go:171","msg":"trace[1540430728] transaction","detail":"{read_only:false; response_revision:25611; number_of_response:1; }","duration":"130.120184ms","start":"2026-05-27T10:44:03.608978Z","end":"2026-05-27T10:44:03.739098Z","steps":["trace[1540430728] 'process raft request' (duration: 129.759757ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:44:03.923129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.443729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddresses/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddresses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-27T10:44:03.923277Z","caller":"traceutil/trace.go:171","msg":"trace[348677325] range","detail":"{range_begin:/registry/ipam.cluster.x-k8s.io/ipaddresses/; range_end:/registry/ipam.cluster.x-k8s.io/ipaddresses0; response_count:0; response_revision:25611; }","duration":"122.644763ms","start":"2026-05-27T10:44:03.800601Z","end":"2026-05-27T10:44:03.923245Z","steps":["trace[348677325] 'count revisions from in-memory index tree' (duration: 122.347577ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:44:04.191951Z","caller":"traceutil/trace.go:171","msg":"trace[2118899040] transaction","detail":"{read_only:false; response_revision:25612; number_of_response:1; }","duration":"124.600575ms","start":"2026-05-27T10:44:04.067321Z","end":"2026-05-27T10:44:04.191922Z","steps":["trace[2118899040] 'process raft request' (duration: 124.395561ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:44:04.344856Z","caller":"traceutil/trace.go:171","msg":"trace[1395862988] transaction","detail":"{read_only:false; response_revision:25614; number_of_response:1; }","duration":"108.220431ms","start":"2026-05-27T10:44:04.236609Z","end":"2026-05-27T10:44:04.34483Z","steps":["trace[1395862988] 'process raft request' (duration: 105.421791ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:44:04.660129Z","caller":"traceutil/trace.go:171","msg":"trace[1132450666] transaction","detail":"{read_only:false; response_revision:25615; number_of_response:1; }","duration":"269.846477ms","start":"2026-05-27T10:44:04.390263Z","end":"2026-05-27T10:44:04.66011Z","steps":["trace[1132450666] 'process raft request' (duration: 209.744397ms)","trace[1132450666] 'compare' (duration: 60.020898ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T10:44:04.907652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.429869ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-27T10:44:04.907889Z","caller":"traceutil/trace.go:171","msg":"trace[353277645] transaction","detail":"{read_only:false; response_revision:25616; number_of_response:1; }","duration":"504.627166ms","start":"2026-05-27T10:44:04.403228Z","end":"2026-05-27T10:44:04.907855Z","steps":["trace[353277645] 'process raft request' (duration: 368.90552ms)","trace[353277645] 'compare' (duration: 135.311017ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T10:44:04.90805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T10:44:04.403205Z","time spent":"504.76197ms","remote":"127.0.0.1:35460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T10:44:04.921553Z","caller":"traceutil/trace.go:171","msg":"trace[407938911] transaction","detail":"{read_only:false; response_revision:25618; number_of_response:1; }","duration":"290.011463ms","start":"2026-05-27T10:44:04.631518Z","end":"2026-05-27T10:44:04.92153Z","steps":["trace[407938911] 'process raft request' (duration: 289.964992ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:44:04.921585Z","caller":"traceutil/trace.go:171","msg":"trace[44964355] transaction","detail":"{read_only:false; response_revision:25617; number_of_response:1; }","duration":"333.477144ms","start":"2026-05-27T10:44:04.588083Z","end":"2026-05-27T10:44:04.92156Z","steps":["trace[44964355] 'process raft request' (duration: 333.29467ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:44:04.921594Z","caller":"traceutil/trace.go:171","msg":"trace[2103344608] linearizableReadLoop","detail":"{readStateIndex:26458; appliedIndex:26455; }","duration":"302.36816ms","start":"2026-05-27T10:44:04.619188Z","end":"2026-05-27T10:44:04.921556Z","steps":["trace[2103344608] 'read index received' (duration: 152.937468ms)","trace[2103344608] 'applied index is now lower than readState.Index' (duration: 149.425452ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T10:44:04.921731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T10:44:04.588043Z","time spent":"333.601446ms","remote":"127.0.0.1:35460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T10:44:04.922071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.872031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-05-27T10:44:04.922134Z","caller":"traceutil/trace.go:171","msg":"trace[1842099077] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25618; }","duration":"302.966844ms","start":"2026-05-27T10:44:04.61915Z","end":"2026-05-27T10:44:04.922116Z","steps":["trace[1842099077] 'agreement among raft nodes before linearized reading' (duration: 302.615816ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:44:04.922173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T10:44:04.619135Z","time spent":"303.028756ms","remote":"127.0.0.1:35460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":506,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-27T10:44:04.922405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.754253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-27T10:44:04.922528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.882646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-27T10:44:04.922585Z","caller":"traceutil/trace.go:171","msg":"trace[1139011148] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:25618; }","duration":"237.962327ms","start":"2026-05-27T10:44:04.684609Z","end":"2026-05-27T10:44:04.922571Z","steps":["trace[1139011148] 'agreement among raft nodes before linearized reading' (duration: 237.827745ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:44:04.922683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.892947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-05-27T10:44:04.922761Z","caller":"traceutil/trace.go:171","msg":"trace[287373164] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25618; }","duration":"256.977439ms","start":"2026-05-27T10:44:04.665765Z","end":"2026-05-27T10:44:04.922743Z","steps":["trace[287373164] 'agreement among raft nodes before linearized reading' (duration: 256.836396ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:44:04.922527Z","caller":"traceutil/trace.go:171","msg":"trace[2082241420] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25618; }","duration":"298.891386ms","start":"2026-05-27T10:44:04.623615Z","end":"2026-05-27T10:44:04.922507Z","steps":["trace[2082241420] 'agreement among raft nodes before linearized reading' (duration: 298.571129ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:44:04.922408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.399118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-05-27T10:44:04.923011Z","caller":"traceutil/trace.go:171","msg":"trace[1546420532] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25618; }","duration":"251.01587ms","start":"2026-05-27T10:44:04.671976Z","end":"2026-05-27T10:44:04.922992Z","steps":["trace[1546420532] 'agreement among raft nodes before linearized reading' (duration: 250.289685ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:44:05.281245Z","caller":"traceutil/trace.go:171","msg":"trace[1740481526] transaction","detail":"{read_only:false; response_revision:25620; number_of_response:1; }","duration":"351.446192ms","start":"2026-05-27T10:44:04.929769Z","end":"2026-05-27T10:44:05.281215Z","steps":["trace[1740481526] 'process raft request' (duration: 333.966395ms)","trace[1740481526] 'compare' (duration: 16.64431ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T10:44:05.281635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T10:44:04.929759Z","time spent":"351.792841ms","remote":"127.0.0.1:35460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T10:44:05.28184Z","caller":"traceutil/trace.go:171","msg":"trace[556422476] transaction","detail":"{read_only:false; response_revision:25621; number_of_response:1; }","duration":"351.765829ms","start":"2026-05-27T10:44:04.929815Z","end":"2026-05-27T10:44:05.281581Z","steps":["trace[556422476] 'process raft request' (duration: 351.30731ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:44:05.282129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T10:44:04.929808Z","time spent":"352.164859ms","remote":"127.0.0.1:35460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T10:44:05.282174Z","caller":"traceutil/trace.go:171","msg":"trace[71783605] transaction","detail":"{read_only:false; response_revision:25625; number_of_response:1; }","duration":"115.62096ms","start":"2026-05-27T10:44:05.166536Z","end":"2026-05-27T10:44:05.282157Z","steps":["trace[71783605] 'process raft request' (duration: 115.572979ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:44:05.282189Z","caller":"traceutil/trace.go:171","msg":"trace[24905187] transaction","detail":"{read_only:false; response_revision:25624; number_of_response:1; }","duration":"352.107137ms","start":"2026-05-27T10:44:04.93006Z","end":"2026-05-27T10:44:05.282167Z","steps":["trace[24905187] 'process raft request' (duration: 351.999484ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:44:05.28219Z","caller":"traceutil/trace.go:171","msg":"trace[1588613721] transaction","detail":"{read_only:false; response_revision:25623; number_of_response:1; }","duration":"352.133097ms","start":"2026-05-27T10:44:04.930023Z","end":"2026-05-27T10:44:05.282156Z","steps":["trace[1588613721] 'process raft request' (duration: 351.962964ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:44:05.282375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T10:44:04.930013Z","time spent":"352.25353ms","remote":"127.0.0.1:35460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T10:44:05.282512Z","caller":"traceutil/trace.go:171","msg":"trace[1501438698] transaction","detail":"{read_only:false; response_revision:25622; number_of_response:1; }","duration":"352.224509ms","start":"2026-05-27T10:44:04.929981Z","end":"2026-05-27T10:44:05.282206Z","steps":["trace[1501438698] 'process raft request' (duration: 351.460693ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:44:05.282672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T10:44:04.929963Z","time spent":"352.630258ms","remote":"127.0.0.1:35460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T10:44:05.282759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T10:44:04.930054Z","time spent":"352.196438ms","remote":"127.0.0.1:35460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T10:44:05.573462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.876714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machines/\" range_end:\"/registry/cluster.x-k8s.io/machines0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-27T10:44:05.57354Z","caller":"traceutil/trace.go:171","msg":"trace[1345069489] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machines/; range_end:/registry/cluster.x-k8s.io/machines0; response_count:0; response_revision:25625; }","duration":"101.998306ms","start":"2026-05-27T10:44:05.471523Z","end":"2026-05-27T10:44:05.573522Z","steps":["trace[1345069489] 'count revisions from in-memory index tree' (duration: 101.781011ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:44:05.948545Z","caller":"traceutil/trace.go:171","msg":"trace[1233915993] transaction","detail":"{read_only:false; response_revision:25626; number_of_response:1; }","duration":"204.218257ms","start":"2026-05-27T10:44:05.744296Z","end":"2026-05-27T10:44:05.948515Z","steps":["trace[1233915993] 'process raft request' (duration: 204.032623ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:44:06.065214Z","caller":"traceutil/trace.go:171","msg":"trace[461858508] linearizableReadLoop","detail":"{readStateIndex:26468; appliedIndex:26466; }","duration":"117.105883ms","start":"2026-05-27T10:44:05.948065Z","end":"2026-05-27T10:44:06.065171Z","steps":["trace[461858508] 'read index received' (duration: 325.797µs)","trace[461858508] 'applied index is now lower than readState.Index' (duration: 116.778216ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T10:44:06.065245Z","caller":"traceutil/trace.go:171","msg":"trace[665720217] transaction","detail":"{read_only:false; response_revision:25627; number_of_response:1; }","duration":"125.186368ms","start":"2026-05-27T10:44:05.940007Z","end":"2026-05-27T10:44:06.065193Z","steps":["trace[665720217] 'process raft request' (duration: 56.511622ms)","trace[665720217] 'compare' (duration: 68.479292ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T10:44:06.065333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.238936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-27T10:44:06.065424Z","caller":"traceutil/trace.go:171","msg":"trace[1950908163] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:25627; }","duration":"117.370519ms","start":"2026-05-27T10:44:05.948033Z","end":"2026-05-27T10:44:06.065403Z","steps":["trace[1950908163] 'agreement among raft nodes before linearized reading' (duration: 117.169005ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:44:06.378442Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22719} {"level":"warn","ts":"2026-05-27T10:44:06.3795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.786241ms","expected-duration":"100ms","prefix":"","request":"header: compaction: ","response":"size:6"} {"level":"info","ts":"2026-05-27T10:44:06.379696Z","caller":"traceutil/trace.go:171","msg":"trace[2121445803] linearizableReadLoop","detail":"{readStateIndex:26469; appliedIndex:26468; }","duration":"302.78468ms","start":"2026-05-27T10:44:06.076886Z","end":"2026-05-27T10:44:06.379671Z","steps":["trace[2121445803] 'read index received' (duration: 89.133059ms)","trace[2121445803] 'applied index is now lower than readState.Index' (duration: 213.649791ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T10:44:06.379853Z","caller":"traceutil/trace.go:171","msg":"trace[1512631465] compact","detail":"{revision:22719; response_revision:25627; }","duration":"313.177465ms","start":"2026-05-27T10:44:06.066575Z","end":"2026-05-27T10:44:06.379752Z","steps":["trace[1512631465] 'process raft request' (duration: 99.38707ms)","trace[1512631465] 'check and update compact revision' (duration: 211.607757ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T10:44:06.379982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T10:44:06.066499Z","time spent":"313.41812ms","remote":"127.0.0.1:35204","response type":"/etcdserverpb.KV/Compact","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-05-27T10:44:06.380284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.367182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces/\" range_end:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-27T10:44:06.380386Z","caller":"traceutil/trace.go:171","msg":"trace[772517115] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpoolradosnamespaces/; range_end:/registry/ceph.rook.io/cephblockpoolradosnamespaces0; response_count:0; response_revision:25627; }","duration":"303.520276ms","start":"2026-05-27T10:44:06.076844Z","end":"2026-05-27T10:44:06.380364Z","steps":["trace[772517115] 'agreement among raft nodes before linearized reading' (duration: 302.911653ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:44:06.380444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T10:44:06.07683Z","time spent":"303.601397ms","remote":"127.0.0.1:55088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":30,"request content":"key:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces/\" range_end:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces0\" count_only:true "} {"level":"info","ts":"2026-05-27T10:44:06.381553Z","caller":"traceutil/trace.go:171","msg":"trace[1191418461] transaction","detail":"{read_only:false; response_revision:25628; number_of_response:1; }","duration":"176.274233ms","start":"2026-05-27T10:44:06.205259Z","end":"2026-05-27T10:44:06.381533Z","steps":["trace[1191418461] 'process raft request' (duration: 174.395743ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T10:44:06.381984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.198876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/subnets/\" range_end:\"/registry/openstack.k-orc.cloud/subnets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-27T10:44:06.38204Z","caller":"traceutil/trace.go:171","msg":"trace[1098304285] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/subnets/; range_end:/registry/openstack.k-orc.cloud/subnets0; response_count:0; response_revision:25629; }","duration":"185.270608ms","start":"2026-05-27T10:44:06.196753Z","end":"2026-05-27T10:44:06.382024Z","steps":["trace[1098304285] 'agreement among raft nodes before linearized reading' (duration: 185.131585ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:44:06.522215Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22719,"took":"136.101634ms","hash":3801076879,"current-db-size-bytes":56066048,"current-db-size":"56 MB","current-db-size-in-use-bytes":34914304,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-05-27T10:44:06.522292Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3801076879,"revision":22719,"compact-revision":19946} {"level":"info","ts":"2026-05-27T10:45:32.90443Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006afd.wal"} {"level":"info","ts":"2026-05-27T10:45:53.471835Z","caller":"traceutil/trace.go:171","msg":"trace[539227662] transaction","detail":"{read_only:false; response_revision:26718; number_of_response:1; }","duration":"136.434901ms","start":"2026-05-27T10:45:53.335365Z","end":"2026-05-27T10:45:53.4718Z","steps":["trace[539227662] 'process raft request' (duration: 87.514483ms)","trace[539227662] 'compare' (duration: 48.158912ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T10:45:53.471969Z","caller":"traceutil/trace.go:171","msg":"trace[1697005560] transaction","detail":"{read_only:false; response_revision:26719; number_of_response:1; }","duration":"135.815958ms","start":"2026-05-27T10:45:53.336109Z","end":"2026-05-27T10:45:53.471925Z","steps":["trace[1697005560] 'process raft request' (duration: 135.551142ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:45:53.475068Z","caller":"traceutil/trace.go:171","msg":"trace[372424645] transaction","detail":"{read_only:false; response_revision:26720; number_of_response:1; }","duration":"137.953385ms","start":"2026-05-27T10:45:53.337075Z","end":"2026-05-27T10:45:53.475028Z","steps":["trace[372424645] 'process raft request' (duration: 135.476431ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T10:49:06.388254Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25627} {"level":"info","ts":"2026-05-27T10:49:06.521942Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25627,"took":"128.765425ms","hash":1049794320,"current-db-size-bytes":56066048,"current-db-size":"56 MB","current-db-size-in-use-bytes":37650432,"current-db-size-in-use":"38 MB"} {"level":"info","ts":"2026-05-27T10:49:06.522021Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1049794320,"revision":25627,"compact-revision":22719} {"level":"info","ts":"2026-05-27T10:50:19.605327Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"a727d7e7ce5fe36a","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-27T10:50:19.610568Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-05-27T10:50:19.610656Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-05-27T10:54:06.394555Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28494} {"level":"info","ts":"2026-05-27T10:54:06.533753Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28494,"took":"134.639713ms","hash":1363877211,"current-db-size-bytes":56066048,"current-db-size":"56 MB","current-db-size-in-use-bytes":30527488,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-27T10:54:06.533822Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1363877211,"revision":28494,"compact-revision":25627} {"level":"info","ts":"2026-05-27T10:59:06.402209Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30714} {"level":"info","ts":"2026-05-27T10:59:06.519337Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30714,"took":"111.799459ms","hash":3189188854,"current-db-size-bytes":56066048,"current-db-size":"56 MB","current-db-size-in-use-bytes":30900224,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-27T10:59:06.519439Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3189188854,"revision":30714,"compact-revision":28494} {"level":"info","ts":"2026-05-27T11:04:06.408892Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32980} {"level":"info","ts":"2026-05-27T11:04:06.566732Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32980,"took":"153.543282ms","hash":912595131,"current-db-size-bytes":57110528,"current-db-size":"57 MB","current-db-size-in-use-bytes":31518720,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-27T11:04:06.567038Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":912595131,"revision":32980,"compact-revision":30714} {"level":"info","ts":"2026-05-27T11:09:06.416145Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35337} {"level":"info","ts":"2026-05-27T11:09:06.585501Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35337,"took":"160.907372ms","hash":3499233875,"current-db-size-bytes":58425344,"current-db-size":"58 MB","current-db-size-in-use-bytes":31633408,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-27T11:09:06.585582Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3499233875,"revision":35337,"compact-revision":32980} {"level":"info","ts":"2026-05-27T11:11:38.369687Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"a727d7e7ce5fe36a","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-27T11:11:38.376662Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-05-27T11:11:38.376785Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-05-27T11:14:06.422756Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37597} {"level":"info","ts":"2026-05-27T11:14:06.544822Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37597,"took":"117.251717ms","hash":1005423033,"current-db-size-bytes":58425344,"current-db-size":"58 MB","current-db-size-in-use-bytes":31412224,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-27T11:14:06.544908Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1005423033,"revision":37597,"compact-revision":35337}