{"level":"warn","ts":"2026-06-09T17:11:00.95522Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-09T17:11:00.955344Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.210:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.210:2380","--initial-cluster=instance=https://199.204.45.210:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.210:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.210: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-06-09T17:11:00.955462Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-09T17:11:00.955482Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.210:2380"]} {"level":"info","ts":"2026-06-09T17:11:00.95553Z","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-06-09T17:11:00.956407Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.210:2379"]} {"level":"info","ts":"2026-06-09T17:11:00.956515Z","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.204.45.210:2380"],"listen-peer-urls":["https://199.204.45.210:2380"],"advertise-client-urls":["https://199.204.45.210:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.210:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.210: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-06-09T17:11:00.960385Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.580139ms"} {"level":"info","ts":"2026-06-09T17:11:00.966907Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"1cac722af8b2b8a6","cluster-id":"ba0a336ca61ee41d"} {"level":"info","ts":"2026-06-09T17:11:00.967037Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 switched to configuration voters=()"} {"level":"info","ts":"2026-06-09T17:11:00.96707Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became follower at term 0"} {"level":"info","ts":"2026-06-09T17:11:00.967094Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 1cac722af8b2b8a6 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-09T17:11:00.967119Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became follower at term 1"} {"level":"info","ts":"2026-06-09T17:11:00.967174Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 switched to configuration voters=(2066151857942870182)"} {"level":"warn","ts":"2026-06-09T17:11:00.975124Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-09T17:11:00.976716Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-09T17:11:00.977968Z","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-06-09T17:11:00.982149Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"1cac722af8b2b8a6","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-09T17:11:00.982473Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"1cac722af8b2b8a6","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-09T17:11:00.982943Z","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-06-09T17:11:00.983109Z","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-06-09T17:11:00.983126Z","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-06-09T17:11:00.986173Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 switched to configuration voters=(2066151857942870182)"} {"level":"info","ts":"2026-06-09T17:11:00.986463Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ba0a336ca61ee41d","local-member-id":"1cac722af8b2b8a6","added-peer-id":"1cac722af8b2b8a6","added-peer-peer-urls":["https://199.204.45.210:2380"]} {"level":"info","ts":"2026-06-09T17:11:00.999109Z","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-06-09T17:11:00.999194Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.210:2380"} {"level":"info","ts":"2026-06-09T17:11:00.999227Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.210:2380"} {"level":"info","ts":"2026-06-09T17:11:00.999699Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"1cac722af8b2b8a6","initial-advertise-peer-urls":["https://199.204.45.210:2380"],"listen-peer-urls":["https://199.204.45.210:2380"],"advertise-client-urls":["https://199.204.45.210:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.210:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-09T17:11:00.999786Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-09T17:11:01.467364Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 is starting a new election at term 1"} {"level":"info","ts":"2026-06-09T17:11:01.467431Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became pre-candidate at term 1"} {"level":"info","ts":"2026-06-09T17:11:01.467467Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 received MsgPreVoteResp from 1cac722af8b2b8a6 at term 1"} {"level":"info","ts":"2026-06-09T17:11:01.467485Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became candidate at term 2"} {"level":"info","ts":"2026-06-09T17:11:01.467496Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 received MsgVoteResp from 1cac722af8b2b8a6 at term 2"} {"level":"info","ts":"2026-06-09T17:11:01.467511Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became leader at term 2"} {"level":"info","ts":"2026-06-09T17:11:01.467529Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 1cac722af8b2b8a6 elected leader 1cac722af8b2b8a6 at term 2"} {"level":"info","ts":"2026-06-09T17:11:01.468395Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-09T17:11:01.468639Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"1cac722af8b2b8a6","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.210:2379]}","request-path":"/0/members/1cac722af8b2b8a6/attributes","cluster-id":"ba0a336ca61ee41d","publish-timeout":"7s"} {"level":"info","ts":"2026-06-09T17:11:01.468685Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-09T17:11:01.468657Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-09T17:11:01.469034Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-09T17:11:01.469088Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-09T17:11:01.47013Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ba0a336ca61ee41d","local-member-id":"1cac722af8b2b8a6","cluster-version":"3.5"} {"level":"info","ts":"2026-06-09T17:11:01.470236Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-09T17:11:01.470267Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-09T17:11:01.472434Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.210:2379"} {"level":"info","ts":"2026-06-09T17:11:01.472506Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-09T17:11:14.194542Z","caller":"traceutil/trace.go:171","msg":"trace[400399014] transaction","detail":"{read_only:false; response_revision:360; number_of_response:1; }","duration":"222.395377ms","start":"2026-06-09T17:11:13.972122Z","end":"2026-06-09T17:11:14.194518Z","steps":["trace[400399014] 'process raft request' (duration: 222.229573ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:11:45.867672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.781853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-06-09T17:11:45.867807Z","caller":"traceutil/trace.go:171","msg":"trace[1929904523] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:597; }","duration":"119.982348ms","start":"2026-06-09T17:11:45.747801Z","end":"2026-06-09T17:11:45.867783Z","steps":["trace[1929904523] 'range keys from in-memory index tree' (duration: 119.607079ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:11:46.183411Z","caller":"traceutil/trace.go:171","msg":"trace[781417368] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"123.477253ms","start":"2026-06-09T17:11:46.05991Z","end":"2026-06-09T17:11:46.183387Z","steps":["trace[781417368] 'process raft request' (duration: 86.723847ms)","trace[781417368] 'compare' (duration: 36.635033ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:11:46.585486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.086027ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-09T17:11:46.585702Z","caller":"traceutil/trace.go:171","msg":"trace[1760898612] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"386.140848ms","start":"2026-06-09T17:11:46.199533Z","end":"2026-06-09T17:11:46.585673Z","steps":["trace[1760898612] 'process raft request' (duration: 188.075107ms)","trace[1760898612] 'compare' (duration: 196.844262ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:11:46.585822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:11:46.199511Z","time spent":"386.271461ms","remote":"127.0.0.1:57078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2735,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-09T17:11:46.904987Z","caller":"traceutil/trace.go:171","msg":"trace[43571162] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"314.645955ms","start":"2026-06-09T17:11:46.590313Z","end":"2026-06-09T17:11:46.904959Z","steps":["trace[43571162] 'process raft request' (duration: 314.568083ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:11:46.905013Z","caller":"traceutil/trace.go:171","msg":"trace[90633216] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"314.736927ms","start":"2026-06-09T17:11:46.590224Z","end":"2026-06-09T17:11:46.904961Z","steps":["trace[90633216] 'process raft request' (duration: 314.503332ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:11:46.90505Z","caller":"traceutil/trace.go:171","msg":"trace[1915995481] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"314.724267ms","start":"2026-06-09T17:11:46.590262Z","end":"2026-06-09T17:11:46.904986Z","steps":["trace[1915995481] 'process raft request' (duration: 314.577533ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:11:46.905213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:11:46.590197Z","time spent":"314.931462ms","remote":"127.0.0.1:56992","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":775,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-09T17:11:46.905275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:11:46.590244Z","time spent":"314.939793ms","remote":"127.0.0.1:56992","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":905,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-09T17:11:46.905371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:11:46.590294Z","time spent":"314.779778ms","remote":"127.0.0.1:57078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3209,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T17:13:15.12864Z","caller":"traceutil/trace.go:171","msg":"trace[1506569063] transaction","detail":"{read_only:false; response_revision:1806; number_of_response:1; }","duration":"112.659781ms","start":"2026-06-09T17:13:15.015945Z","end":"2026-06-09T17:13:15.128605Z","steps":["trace[1506569063] 'process raft request' (duration: 112.478357ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:13:28.561747Z","caller":"traceutil/trace.go:171","msg":"trace[857253570] transaction","detail":"{read_only:false; response_revision:1920; number_of_response:1; }","duration":"122.586744ms","start":"2026-06-09T17:13:28.43913Z","end":"2026-06-09T17:13:28.561717Z","steps":["trace[857253570] 'process raft request' (duration: 122.405019ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:13:59.888181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.569784ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-06-09T17:13:59.888436Z","caller":"traceutil/trace.go:171","msg":"trace[1184474122] transaction","detail":"{read_only:false; response_revision:2106; number_of_response:1; }","duration":"147.191422ms","start":"2026-06-09T17:13:59.741227Z","end":"2026-06-09T17:13:59.888419Z","steps":["trace[1184474122] 'process raft request' (duration: 147.056429ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:14:06.334211Z","caller":"traceutil/trace.go:171","msg":"trace[631320542] linearizableReadLoop","detail":"{readStateIndex:2220; appliedIndex:2219; }","duration":"362.977177ms","start":"2026-06-09T17:14:05.971206Z","end":"2026-06-09T17:14:06.334183Z","steps":["trace[631320542] 'read index received' (duration: 362.941567ms)","trace[631320542] 'applied index is now lower than readState.Index' (duration: 34.2µs)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:14:06.334299Z","caller":"traceutil/trace.go:171","msg":"trace[1974257352] transaction","detail":"{read_only:false; response_revision:2137; number_of_response:1; }","duration":"424.815515ms","start":"2026-06-09T17:14:05.909424Z","end":"2026-06-09T17:14:06.334239Z","steps":["trace[1974257352] 'process raft request' (duration: 424.591619ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:14:06.334449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.222883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-06-09T17:14:06.334499Z","caller":"traceutil/trace.go:171","msg":"trace[1503864151] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2137; }","duration":"363.360927ms","start":"2026-06-09T17:14:05.971127Z","end":"2026-06-09T17:14:06.334488Z","steps":["trace[1503864151] 'agreement among raft nodes before linearized reading' (duration: 363.192673ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:14:06.334534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:14:05.971092Z","time spent":"363.433539ms","remote":"127.0.0.1:57168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-09T17:14:06.334635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:14:05.909387Z","time spent":"425.107322ms","remote":"127.0.0.1:57168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T17:14:06.45316Z","caller":"traceutil/trace.go:171","msg":"trace[589826513] linearizableReadLoop","detail":"{readStateIndex:2221; appliedIndex:2220; }","duration":"118.828566ms","start":"2026-06-09T17:14:06.334291Z","end":"2026-06-09T17:14:06.453119Z","steps":["trace[589826513] 'read index received' (duration: 109.140938ms)","trace[589826513] 'applied index is now lower than readState.Index' (duration: 9.685618ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:14:06.453298Z","caller":"traceutil/trace.go:171","msg":"trace[1822214273] transaction","detail":"{read_only:false; response_revision:2138; number_of_response:1; }","duration":"289.983846ms","start":"2026-06-09T17:14:06.16323Z","end":"2026-06-09T17:14:06.453214Z","steps":["trace[1822214273] 'process raft request' (duration: 280.184836ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:14:06.453466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.1952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T17:14:06.453602Z","caller":"traceutil/trace.go:171","msg":"trace[1076142349] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2138; }","duration":"336.400416ms","start":"2026-06-09T17:14:06.11717Z","end":"2026-06-09T17:14:06.453571Z","steps":["trace[1076142349] 'agreement among raft nodes before linearized reading' (duration: 336.138558ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:14:06.453659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:14:06.116573Z","time spent":"337.075872ms","remote":"127.0.0.1:56886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-09T17:14:06.458043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.125298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-09T17:14:06.458119Z","caller":"traceutil/trace.go:171","msg":"trace[1550146059] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2139; }","duration":"120.254791ms","start":"2026-06-09T17:14:06.33785Z","end":"2026-06-09T17:14:06.458105Z","steps":["trace[1550146059] 'agreement among raft nodes before linearized reading' (duration: 120.074427ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:14:06.458146Z","caller":"traceutil/trace.go:171","msg":"trace[346803584] transaction","detail":"{read_only:false; response_revision:2139; number_of_response:1; }","duration":"118.836425ms","start":"2026-06-09T17:14:06.339277Z","end":"2026-06-09T17:14:06.458113Z","steps":["trace[346803584] 'process raft request' (duration: 118.489667ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:14:55.329983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.880047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-06-09T17:14:55.330123Z","caller":"traceutil/trace.go:171","msg":"trace[453559464] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2576; }","duration":"133.096523ms","start":"2026-06-09T17:14:55.197001Z","end":"2026-06-09T17:14:55.330098Z","steps":["trace[453559464] 'range keys from in-memory index tree' (duration: 132.548489ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:15:00.66483Z","caller":"traceutil/trace.go:171","msg":"trace[504802524] transaction","detail":"{read_only:false; response_revision:2605; number_of_response:1; }","duration":"103.971188ms","start":"2026-06-09T17:15:00.560802Z","end":"2026-06-09T17:15:00.664773Z","steps":["trace[504802524] 'process raft request' (duration: 48.774255ms)","trace[504802524] 'compare' (duration: 54.878455ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:15:00.665093Z","caller":"traceutil/trace.go:171","msg":"trace[22116664] transaction","detail":"{read_only:false; response_revision:2606; number_of_response:1; }","duration":"101.814204ms","start":"2026-06-09T17:15:00.563244Z","end":"2026-06-09T17:15:00.665058Z","steps":["trace[22116664] 'process raft request' (duration: 101.457496ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:15:00.665265Z","caller":"traceutil/trace.go:171","msg":"trace[1342852406] transaction","detail":"{read_only:false; response_revision:2607; number_of_response:1; }","duration":"101.631511ms","start":"2026-06-09T17:15:00.563603Z","end":"2026-06-09T17:15:00.665235Z","steps":["trace[1342852406] 'process raft request' (duration: 101.351474ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:15:00.665322Z","caller":"traceutil/trace.go:171","msg":"trace[136542006] transaction","detail":"{read_only:false; response_revision:2608; number_of_response:1; }","duration":"101.113158ms","start":"2026-06-09T17:15:00.56419Z","end":"2026-06-09T17:15:00.665303Z","steps":["trace[136542006] 'process raft request' (duration: 100.885423ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:15:00.665348Z","caller":"traceutil/trace.go:171","msg":"trace[1473268348] transaction","detail":"{read_only:false; response_revision:2609; number_of_response:1; }","duration":"101.096898ms","start":"2026-06-09T17:15:00.564225Z","end":"2026-06-09T17:15:00.665322Z","steps":["trace[1473268348] 'process raft request' (duration: 100.971975ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:15:00.665614Z","caller":"traceutil/trace.go:171","msg":"trace[1397496225] transaction","detail":"{read_only:false; response_revision:2610; number_of_response:1; }","duration":"100.838791ms","start":"2026-06-09T17:15:00.56476Z","end":"2026-06-09T17:15:00.665598Z","steps":["trace[1397496225] 'process raft request' (duration: 100.504162ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:15:28.267575Z","caller":"traceutil/trace.go:171","msg":"trace[927049157] linearizableReadLoop","detail":"{readStateIndex:2883; appliedIndex:2882; }","duration":"103.940877ms","start":"2026-06-09T17:15:28.163611Z","end":"2026-06-09T17:15:28.267552Z","steps":["trace[927049157] 'read index received' (duration: 103.794604ms)","trace[927049157] 'applied index is now lower than readState.Index' (duration: 144.863µs)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:15:28.267656Z","caller":"traceutil/trace.go:171","msg":"trace[1745389795] transaction","detail":"{read_only:false; response_revision:2775; number_of_response:1; }","duration":"129.476964ms","start":"2026-06-09T17:15:28.138145Z","end":"2026-06-09T17:15:28.267622Z","steps":["trace[1745389795] 'process raft request' (duration: 129.249817ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:15:28.268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.382069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-06-09T17:15:28.26805Z","caller":"traceutil/trace.go:171","msg":"trace[268707557] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2775; }","duration":"104.485342ms","start":"2026-06-09T17:15:28.163553Z","end":"2026-06-09T17:15:28.268038Z","steps":["trace[268707557] 'agreement among raft nodes before linearized reading' (duration: 104.111343ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:17:29.252495Z","caller":"traceutil/trace.go:171","msg":"trace[1936636799] transaction","detail":"{read_only:false; response_revision:3549; number_of_response:1; }","duration":"108.798793ms","start":"2026-06-09T17:17:29.143664Z","end":"2026-06-09T17:17:29.252463Z","steps":["trace[1936636799] 'process raft request' (duration: 108.590918ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:17:29.252499Z","caller":"traceutil/trace.go:171","msg":"trace[146871861] transaction","detail":"{read_only:false; response_revision:3548; number_of_response:1; }","duration":"111.203582ms","start":"2026-06-09T17:17:29.141255Z","end":"2026-06-09T17:17:29.252458Z","steps":["trace[146871861] 'process raft request' (duration: 100.196524ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:18:05.124031Z","caller":"traceutil/trace.go:171","msg":"trace[855614842] transaction","detail":"{read_only:false; response_revision:3802; number_of_response:1; }","duration":"107.437136ms","start":"2026-06-09T17:18:05.016558Z","end":"2026-06-09T17:18:05.123995Z","steps":["trace[855614842] 'process raft request' (duration: 107.18754ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:18:05.232355Z","caller":"traceutil/trace.go:171","msg":"trace[227165662] linearizableReadLoop","detail":"{readStateIndex:3950; appliedIndex:3949; }","duration":"102.369793ms","start":"2026-06-09T17:18:05.129951Z","end":"2026-06-09T17:18:05.232321Z","steps":["trace[227165662] 'read index received' (duration: 102.079546ms)","trace[227165662] 'applied index is now lower than readState.Index' (duration: 288.947µs)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:18:05.232492Z","caller":"traceutil/trace.go:171","msg":"trace[212323707] transaction","detail":"{read_only:false; response_revision:3803; number_of_response:1; }","duration":"104.540996ms","start":"2026-06-09T17:18:05.127915Z","end":"2026-06-09T17:18:05.232456Z","steps":["trace[212323707] 'process raft request' (duration: 104.150227ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:18:05.23272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.731202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-06-09T17:18:05.23279Z","caller":"traceutil/trace.go:171","msg":"trace[2055264799] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:3803; }","duration":"102.861365ms","start":"2026-06-09T17:18:05.129912Z","end":"2026-06-09T17:18:05.232773Z","steps":["trace[2055264799] 'agreement among raft nodes before linearized reading' (duration: 102.539827ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:19:29.135403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.098363ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-09T17:19:29.135472Z","caller":"traceutil/trace.go:171","msg":"trace[1288829226] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:4982; }","duration":"144.222086ms","start":"2026-06-09T17:19:28.991237Z","end":"2026-06-09T17:19:29.135459Z","steps":["trace[1288829226] 'count revisions from in-memory index tree' (duration: 144.023992ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:19:42.789632Z","caller":"traceutil/trace.go:171","msg":"trace[1178096090] transaction","detail":"{read_only:false; response_revision:5246; number_of_response:1; }","duration":"133.60629ms","start":"2026-06-09T17:19:42.656006Z","end":"2026-06-09T17:19:42.789612Z","steps":["trace[1178096090] 'process raft request' (duration: 133.430694ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:19:42.82556Z","caller":"traceutil/trace.go:171","msg":"trace[596053186] transaction","detail":"{read_only:false; response_revision:5247; number_of_response:1; }","duration":"119.117637ms","start":"2026-06-09T17:19:42.706423Z","end":"2026-06-09T17:19:42.825541Z","steps":["trace[596053186] 'process raft request' (duration: 119.015404ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:19:51.125175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.010651ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-09T17:19:51.125271Z","caller":"traceutil/trace.go:171","msg":"trace[1971799925] linearizableReadLoop","detail":"{readStateIndex:5614; appliedIndex:5613; }","duration":"106.638843ms","start":"2026-06-09T17:19:51.01862Z","end":"2026-06-09T17:19:51.125259Z","steps":["trace[1971799925] 'read index received' (duration: 33.881µs)","trace[1971799925] 'applied index is now lower than readState.Index' (duration: 106.604322ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:19:51.125324Z","caller":"traceutil/trace.go:171","msg":"trace[781932098] transaction","detail":"{read_only:false; response_revision:5411; number_of_response:1; }","duration":"203.245563ms","start":"2026-06-09T17:19:50.922042Z","end":"2026-06-09T17:19:51.125287Z","steps":["trace[781932098] 'process raft request' (duration: 64.045957ms)","trace[781932098] 'compare' (duration: 138.915999ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:19:51.125559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.92944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:529"} {"level":"info","ts":"2026-06-09T17:19:51.125763Z","caller":"traceutil/trace.go:171","msg":"trace[606224643] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:5411; }","duration":"107.163036ms","start":"2026-06-09T17:19:51.01858Z","end":"2026-06-09T17:19:51.125743Z","steps":["trace[606224643] 'agreement among raft nodes before linearized reading' (duration: 106.862968ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:19:51.376441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.804675ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-09T17:19:51.376649Z","caller":"traceutil/trace.go:171","msg":"trace[23360999] transaction","detail":"{read_only:false; response_revision:5413; number_of_response:1; }","duration":"243.676756ms","start":"2026-06-09T17:19:51.13295Z","end":"2026-06-09T17:19:51.376626Z","steps":["trace[23360999] 'process raft request' (duration: 125.601125ms)","trace[23360999] 'compare' (duration: 117.552099ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:20:02.348488Z","caller":"traceutil/trace.go:171","msg":"trace[1046639393] transaction","detail":"{read_only:false; response_revision:5580; number_of_response:1; }","duration":"115.641746ms","start":"2026-06-09T17:20:02.232824Z","end":"2026-06-09T17:20:02.348466Z","steps":["trace[1046639393] 'process raft request' (duration: 115.563124ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:20:02.34854Z","caller":"traceutil/trace.go:171","msg":"trace[766768763] transaction","detail":"{read_only:false; response_revision:5579; number_of_response:1; }","duration":"115.866541ms","start":"2026-06-09T17:20:02.232637Z","end":"2026-06-09T17:20:02.348504Z","steps":["trace[766768763] 'process raft request' (duration: 34.666944ms)","trace[766768763] 'compare' (duration: 80.950821ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:20:02.34886Z","caller":"traceutil/trace.go:171","msg":"trace[1988454234] transaction","detail":"{read_only:false; response_revision:5581; number_of_response:1; }","duration":"115.408681ms","start":"2026-06-09T17:20:02.23344Z","end":"2026-06-09T17:20:02.348849Z","steps":["trace[1988454234] 'process raft request' (duration: 114.993901ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:20:02.3492Z","caller":"traceutil/trace.go:171","msg":"trace[812940531] transaction","detail":"{read_only:false; response_revision:5582; number_of_response:1; }","duration":"115.038502ms","start":"2026-06-09T17:20:02.23415Z","end":"2026-06-09T17:20:02.349189Z","steps":["trace[812940531] 'process raft request' (duration: 114.605051ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:20:02.34918Z","caller":"traceutil/trace.go:171","msg":"trace[1467687929] linearizableReadLoop","detail":"{readStateIndex:5785; appliedIndex:5783; }","duration":"116.477688ms","start":"2026-06-09T17:20:02.232666Z","end":"2026-06-09T17:20:02.349143Z","steps":["trace[1467687929] 'read index received' (duration: 7.642677ms)","trace[1467687929] 'applied index is now lower than readState.Index' (duration: 108.83247ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:20:02.349412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.707983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-06-09T17:20:02.349459Z","caller":"traceutil/trace.go:171","msg":"trace[158914981] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5582; }","duration":"116.845696ms","start":"2026-06-09T17:20:02.232602Z","end":"2026-06-09T17:20:02.349448Z","steps":["trace[158914981] 'agreement among raft nodes before linearized reading' (duration: 116.627351ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:21:01.508791Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2959} {"level":"info","ts":"2026-06-09T17:21:01.577197Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2959,"took":"67.062533ms","hash":1498025762,"current-db-size-bytes":58462208,"current-db-size":"58 MB","current-db-size-in-use-bytes":34058240,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-06-09T17:21:01.577269Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1498025762,"revision":2959,"compact-revision":-1} {"level":"warn","ts":"2026-06-09T17:24:52.408199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.859591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T17:24:52.408317Z","caller":"traceutil/trace.go:171","msg":"trace[1205993199] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7675; }","duration":"239.032035ms","start":"2026-06-09T17:24:52.169262Z","end":"2026-06-09T17:24:52.408294Z","steps":["trace[1205993199] 'range keys from in-memory index tree' (duration: 238.766708ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:24:52.408225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.233649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-vxfvw\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-06-09T17:24:52.408383Z","caller":"traceutil/trace.go:171","msg":"trace[1732322786] range","detail":"{range_begin:/registry/pods/openstack/keepalived-vxfvw; range_end:; response_count:1; response_revision:7675; }","duration":"129.427874ms","start":"2026-06-09T17:24:52.27894Z","end":"2026-06-09T17:24:52.408368Z","steps":["trace[1732322786] 'range keys from in-memory index tree' (duration: 129.097085ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:25:48.313588Z","caller":"traceutil/trace.go:171","msg":"trace[2007617909] linearizableReadLoop","detail":"{readStateIndex:8485; appliedIndex:8484; }","duration":"197.592929ms","start":"2026-06-09T17:25:48.115977Z","end":"2026-06-09T17:25:48.31357Z","steps":["trace[2007617909] 'read index received' (duration: 197.518388ms)","trace[2007617909] 'applied index is now lower than readState.Index' (duration: 73.871µs)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:25:48.313661Z","caller":"traceutil/trace.go:171","msg":"trace[1197021530] transaction","detail":"{read_only:false; response_revision:8197; number_of_response:1; }","duration":"276.477225ms","start":"2026-06-09T17:25:48.037155Z","end":"2026-06-09T17:25:48.313632Z","steps":["trace[1197021530] 'process raft request' (duration: 276.29514ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:25:48.313713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.728952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T17:25:48.313733Z","caller":"traceutil/trace.go:171","msg":"trace[656372879] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8197; }","duration":"197.790274ms","start":"2026-06-09T17:25:48.115937Z","end":"2026-06-09T17:25:48.313727Z","steps":["trace[656372879] 'agreement among raft nodes before linearized reading' (duration: 197.741033ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:25:48.467302Z","caller":"traceutil/trace.go:171","msg":"trace[1270347319] linearizableReadLoop","detail":"{readStateIndex:8486; appliedIndex:8485; }","duration":"153.61945ms","start":"2026-06-09T17:25:48.313654Z","end":"2026-06-09T17:25:48.467274Z","steps":["trace[1270347319] 'read index received' (duration: 151.433828ms)","trace[1270347319] 'applied index is now lower than readState.Index' (duration: 2.183492ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:25:48.467311Z","caller":"traceutil/trace.go:171","msg":"trace[687571191] transaction","detail":"{read_only:false; response_revision:8198; number_of_response:1; }","duration":"267.080596ms","start":"2026-06-09T17:25:48.200204Z","end":"2026-06-09T17:25:48.467284Z","steps":["trace[687571191] 'process raft request' (duration: 265.006766ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:25:48.467457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.822382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T17:25:48.467525Z","caller":"traceutil/trace.go:171","msg":"trace[230916880] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8198; }","duration":"152.940005ms","start":"2026-06-09T17:25:48.314567Z","end":"2026-06-09T17:25:48.467507Z","steps":["trace[230916880] 'agreement among raft nodes before linearized reading' (duration: 152.835112ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:25:48.467605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.420911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-06-09T17:25:48.467679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.738844ms","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-06-09T17:25:48.467795Z","caller":"traceutil/trace.go:171","msg":"trace[1617251936] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8198; }","duration":"132.953269ms","start":"2026-06-09T17:25:48.334819Z","end":"2026-06-09T17:25:48.467772Z","steps":["trace[1617251936] 'agreement among raft nodes before linearized reading' (duration: 132.606581ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:25:48.467711Z","caller":"traceutil/trace.go:171","msg":"trace[1521708556] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8198; }","duration":"238.560104ms","start":"2026-06-09T17:25:48.229128Z","end":"2026-06-09T17:25:48.467688Z","steps":["trace[1521708556] 'agreement among raft nodes before linearized reading' (duration: 238.244987ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:26:01.551973Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6076} {"level":"info","ts":"2026-06-09T17:26:01.742277Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6076,"took":"188.846906ms","hash":2799327453,"current-db-size-bytes":58462208,"current-db-size":"58 MB","current-db-size-in-use-bytes":22904832,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-06-09T17:26:01.742339Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2799327453,"revision":6076,"compact-revision":2959} {"level":"info","ts":"2026-06-09T17:26:28.240196Z","caller":"traceutil/trace.go:171","msg":"trace[709359625] linearizableReadLoop","detail":"{readStateIndex:8765; appliedIndex:8764; }","duration":"280.387807ms","start":"2026-06-09T17:26:27.959772Z","end":"2026-06-09T17:26:28.24016Z","steps":["trace[709359625] 'read index received' (duration: 280.202482ms)","trace[709359625] 'applied index is now lower than readState.Index' (duration: 183.374µs)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:26:28.240553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.727025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T17:26:28.240626Z","caller":"traceutil/trace.go:171","msg":"trace[2052392697] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:8465; }","duration":"280.872348ms","start":"2026-06-09T17:26:27.959733Z","end":"2026-06-09T17:26:28.240605Z","steps":["trace[2052392697] 'agreement among raft nodes before linearized reading' (duration: 280.588461ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:26:28.241195Z","caller":"traceutil/trace.go:171","msg":"trace[761119947] transaction","detail":"{read_only:false; response_revision:8465; number_of_response:1; }","duration":"304.583234ms","start":"2026-06-09T17:26:27.936598Z","end":"2026-06-09T17:26:28.241181Z","steps":["trace[761119947] 'process raft request' (duration: 303.347884ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:26:28.241328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:26:27.936577Z","time spent":"304.659926ms","remote":"127.0.0.1:57168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T17:26:41.375416Z","caller":"traceutil/trace.go:171","msg":"trace[605480904] transaction","detail":"{read_only:false; response_revision:8574; number_of_response:1; }","duration":"130.408991ms","start":"2026-06-09T17:26:41.244983Z","end":"2026-06-09T17:26:41.375392Z","steps":["trace[605480904] 'process raft request' (duration: 124.531179ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:26:41.425963Z","caller":"traceutil/trace.go:171","msg":"trace[357752245] transaction","detail":"{read_only:false; response_revision:8575; number_of_response:1; }","duration":"179.692428ms","start":"2026-06-09T17:26:41.246257Z","end":"2026-06-09T17:26:41.425949Z","steps":["trace[357752245] 'process raft request' (duration: 179.33532ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:26:41.426013Z","caller":"traceutil/trace.go:171","msg":"trace[231074999] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8576; }","duration":"179.599207ms","start":"2026-06-09T17:26:41.246405Z","end":"2026-06-09T17:26:41.426004Z","steps":["trace[231074999] 'process raft request' (duration: 179.30756ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:26:41.42595Z","caller":"traceutil/trace.go:171","msg":"trace[1816173312] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8576; }","duration":"176.262266ms","start":"2026-06-09T17:26:41.249655Z","end":"2026-06-09T17:26:41.425917Z","steps":["trace[1816173312] 'process raft request' (duration: 176.104142ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:26:41.556679Z","caller":"traceutil/trace.go:171","msg":"trace[1348136022] linearizableReadLoop","detail":"{readStateIndex:8884; appliedIndex:8883; }","duration":"124.349434ms","start":"2026-06-09T17:26:41.432305Z","end":"2026-06-09T17:26:41.556655Z","steps":["trace[1348136022] 'read index received' (duration: 124.144259ms)","trace[1348136022] 'applied index is now lower than readState.Index' (duration: 204.045µs)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:26:41.556728Z","caller":"traceutil/trace.go:171","msg":"trace[213565495] transaction","detail":"{read_only:false; response_revision:8578; number_of_response:1; }","duration":"124.814185ms","start":"2026-06-09T17:26:41.431884Z","end":"2026-06-09T17:26:41.556698Z","steps":["trace[213565495] 'process raft request' (duration: 124.62222ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:26:41.5568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.473826ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-tvgww\" ","response":"range_response_count:1 size:4650"} {"level":"info","ts":"2026-06-09T17:26:41.556842Z","caller":"traceutil/trace.go:171","msg":"trace[1946186642] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-tvgww; range_end:; response_count:1; response_revision:8578; }","duration":"124.564619ms","start":"2026-06-09T17:26:41.432267Z","end":"2026-06-09T17:26:41.556832Z","steps":["trace[1946186642] 'agreement among raft nodes before linearized reading' (duration: 124.482237ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:26:42.467995Z","caller":"traceutil/trace.go:171","msg":"trace[889362783] linearizableReadLoop","detail":"{readStateIndex:8888; appliedIndex:8887; }","duration":"164.339166ms","start":"2026-06-09T17:26:42.303633Z","end":"2026-06-09T17:26:42.467973Z","steps":["trace[889362783] 'read index received' (duration: 164.240523ms)","trace[889362783] 'applied index is now lower than readState.Index' (duration: 97.732µs)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:26:42.468151Z","caller":"traceutil/trace.go:171","msg":"trace[1539080556] transaction","detail":"{read_only:false; response_revision:8582; number_of_response:1; }","duration":"249.640039ms","start":"2026-06-09T17:26:42.218478Z","end":"2026-06-09T17:26:42.468118Z","steps":["trace[1539080556] 'process raft request' (duration: 249.346602ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:26:42.468196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.631323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-06-09T17:26:42.468233Z","caller":"traceutil/trace.go:171","msg":"trace[1046648771] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8582; }","duration":"164.730585ms","start":"2026-06-09T17:26:42.30349Z","end":"2026-06-09T17:26:42.468221Z","steps":["trace[1046648771] 'agreement among raft nodes before linearized reading' (duration: 164.577102ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:27:31.562397Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000024be.wal"} {"level":"info","ts":"2026-06-09T17:27:54.692986Z","caller":"traceutil/trace.go:171","msg":"trace[2036164244] transaction","detail":"{read_only:false; response_revision:9308; number_of_response:1; }","duration":"133.380823ms","start":"2026-06-09T17:27:54.559553Z","end":"2026-06-09T17:27:54.692934Z","steps":["trace[2036164244] 'process raft request' (duration: 133.090066ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:28:43.537731Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"1cac722af8b2b8a6","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-09T17:28:43.543272Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-06-09T17:28:43.543414Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-06-09T17:30:17.560777Z","caller":"traceutil/trace.go:171","msg":"trace[1994487348] transaction","detail":"{read_only:false; response_revision:10378; number_of_response:1; }","duration":"190.738257ms","start":"2026-06-09T17:30:17.37Z","end":"2026-06-09T17:30:17.560738Z","steps":["trace[1994487348] 'process raft request' (duration: 159.961439ms)","trace[1994487348] 'compare' (duration: 30.593834ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:30:40.428091Z","caller":"traceutil/trace.go:171","msg":"trace[1381345830] linearizableReadLoop","detail":"{readStateIndex:10989; appliedIndex:10988; }","duration":"331.684373ms","start":"2026-06-09T17:30:40.096381Z","end":"2026-06-09T17:30:40.428065Z","steps":["trace[1381345830] 'read index received' (duration: 331.458247ms)","trace[1381345830] 'applied index is now lower than readState.Index' (duration: 224.876µs)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:30:40.428206Z","caller":"traceutil/trace.go:171","msg":"trace[1535997837] transaction","detail":"{read_only:false; response_revision:10624; number_of_response:1; }","duration":"341.077532ms","start":"2026-06-09T17:30:40.087119Z","end":"2026-06-09T17:30:40.428196Z","steps":["trace[1535997837] 'process raft request' (duration: 340.752324ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:30:40.428317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:30:40.087084Z","time spent":"341.150484ms","remote":"127.0.0.1:57168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T17:30:40.428397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.018321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-06-09T17:30:40.428464Z","caller":"traceutil/trace.go:171","msg":"trace[355446976] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:10624; }","duration":"332.151875ms","start":"2026-06-09T17:30:40.096298Z","end":"2026-06-09T17:30:40.42845Z","steps":["trace[355446976] 'agreement among raft nodes before linearized reading' (duration: 332.005221ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:30:40.428497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:30:40.096257Z","time spent":"332.231477ms","remote":"127.0.0.1:57168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-06-09T17:30:40.502256Z","caller":"traceutil/trace.go:171","msg":"trace[1676934835] transaction","detail":"{read_only:false; response_revision:10625; number_of_response:1; }","duration":"191.187587ms","start":"2026-06-09T17:30:40.311036Z","end":"2026-06-09T17:30:40.502224Z","steps":["trace[1676934835] 'process raft request' (duration: 179.272268ms)","trace[1676934835] 'compare' (duration: 11.510919ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:30:40.502597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.113182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T17:30:40.50268Z","caller":"traceutil/trace.go:171","msg":"trace[407298078] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:10627; }","duration":"385.238565ms","start":"2026-06-09T17:30:40.117406Z","end":"2026-06-09T17:30:40.502645Z","steps":["trace[407298078] 'agreement among raft nodes before linearized reading' (duration: 385.122272ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:30:40.502694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.621632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/openstack/rabbitmq-cinder-server\" ","response":"range_response_count:1 size:705"} {"level":"warn","ts":"2026-06-09T17:30:40.502737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:30:40.117377Z","time spent":"385.347698ms","remote":"127.0.0.1:56886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-09T17:30:40.502765Z","caller":"traceutil/trace.go:171","msg":"trace[138256357] range","detail":"{range_begin:/registry/serviceaccounts/openstack/rabbitmq-cinder-server; range_end:; response_count:1; response_revision:10627; }","duration":"361.705894ms","start":"2026-06-09T17:30:40.141027Z","end":"2026-06-09T17:30:40.502733Z","steps":["trace[138256357] 'agreement among raft nodes before linearized reading' (duration: 361.577341ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:30:40.502795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:30:40.141002Z","time spent":"361.786097ms","remote":"127.0.0.1:57108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":728,"request content":"key:\"/registry/serviceaccounts/openstack/rabbitmq-cinder-server\" "} {"level":"info","ts":"2026-06-09T17:30:40.850363Z","caller":"traceutil/trace.go:171","msg":"trace[181267476] linearizableReadLoop","detail":"{readStateIndex:10994; appliedIndex:10993; }","duration":"235.29136ms","start":"2026-06-09T17:30:40.615041Z","end":"2026-06-09T17:30:40.850333Z","steps":["trace[181267476] 'read index received' (duration: 231.20326ms)","trace[181267476] 'applied index is now lower than readState.Index' (duration: 4.08696ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:30:40.850656Z","caller":"traceutil/trace.go:171","msg":"trace[732539353] transaction","detail":"{read_only:false; response_revision:10629; number_of_response:1; }","duration":"313.209924ms","start":"2026-06-09T17:30:40.537433Z","end":"2026-06-09T17:30:40.850642Z","steps":["trace[732539353] 'process raft request' (duration: 308.801516ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:30:40.850776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:30:40.537407Z","time spent":"313.291146ms","remote":"127.0.0.1:57168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T17:30:40.850928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.863044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-09T17:30:40.851019Z","caller":"traceutil/trace.go:171","msg":"trace[1939314331] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:10629; }","duration":"235.996266ms","start":"2026-06-09T17:30:40.614997Z","end":"2026-06-09T17:30:40.850993Z","steps":["trace[1939314331] 'agreement among raft nodes before linearized reading' (duration: 235.836333ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:30:40.851485Z","caller":"traceutil/trace.go:171","msg":"trace[15195313] transaction","detail":"{read_only:false; response_revision:10630; number_of_response:1; }","duration":"110.767693ms","start":"2026-06-09T17:30:40.74069Z","end":"2026-06-09T17:30:40.851458Z","steps":["trace[15195313] 'process raft request' (duration: 110.539557ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:30:40.851498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.000269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-vxfvw\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-06-09T17:30:40.851565Z","caller":"traceutil/trace.go:171","msg":"trace[82887761] range","detail":"{range_begin:/registry/pods/openstack/keepalived-vxfvw; range_end:; response_count:1; response_revision:10630; }","duration":"183.118831ms","start":"2026-06-09T17:30:40.668431Z","end":"2026-06-09T17:30:40.851549Z","steps":["trace[82887761] 'agreement among raft nodes before linearized reading' (duration: 182.932076ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:31:01.557953Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8269} {"level":"info","ts":"2026-06-09T17:31:01.635113Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8269,"took":"75.346318ms","hash":1132608278,"current-db-size-bytes":58462208,"current-db-size":"58 MB","current-db-size-in-use-bytes":25366528,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-06-09T17:31:01.635194Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1132608278,"revision":8269,"compact-revision":6076} {"level":"info","ts":"2026-06-09T17:31:36.289729Z","caller":"traceutil/trace.go:171","msg":"trace[1241196072] linearizableReadLoop","detail":"{readStateIndex:11518; appliedIndex:11517; }","duration":"117.520421ms","start":"2026-06-09T17:31:36.172188Z","end":"2026-06-09T17:31:36.289708Z","steps":["trace[1241196072] 'read index received' (duration: 117.350577ms)","trace[1241196072] 'applied index is now lower than readState.Index' (duration: 169.224µs)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:31:36.289806Z","caller":"traceutil/trace.go:171","msg":"trace[478283760] transaction","detail":"{read_only:false; response_revision:11139; number_of_response:1; }","duration":"146.121086ms","start":"2026-06-09T17:31:36.143642Z","end":"2026-06-09T17:31:36.289763Z","steps":["trace[478283760] 'process raft request' (duration: 145.945071ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:31:36.289854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.668625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-06-09T17:31:36.289989Z","caller":"traceutil/trace.go:171","msg":"trace[1000313620] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:11139; }","duration":"117.85899ms","start":"2026-06-09T17:31:36.172122Z","end":"2026-06-09T17:31:36.289981Z","steps":["trace[1000313620] 'agreement among raft nodes before linearized reading' (duration: 117.691746ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:31:36.676265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.821808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/podmonitors/\" range_end:\"/registry/monitoring.coreos.com/podmonitors0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-09T17:31:36.676349Z","caller":"traceutil/trace.go:171","msg":"trace[1340335908] range","detail":"{range_begin:/registry/monitoring.coreos.com/podmonitors/; range_end:/registry/monitoring.coreos.com/podmonitors0; response_count:0; response_revision:11140; }","duration":"105.956351ms","start":"2026-06-09T17:31:36.570372Z","end":"2026-06-09T17:31:36.676328Z","steps":["trace[1340335908] 'count revisions from in-memory index tree' (duration: 105.675374ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:31:37.270891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.741008ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-09T17:31:37.271086Z","caller":"traceutil/trace.go:171","msg":"trace[1195282872] transaction","detail":"{read_only:false; response_revision:11142; number_of_response:1; }","duration":"564.314212ms","start":"2026-06-09T17:31:36.706751Z","end":"2026-06-09T17:31:37.271065Z","steps":["trace[1195282872] 'process raft request' (duration: 359.313138ms)","trace[1195282872] 'compare' (duration: 204.443181ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:31:37.271165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:31:36.706715Z","time spent":"564.414805ms","remote":"127.0.0.1:57168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T17:31:37.271245Z","caller":"traceutil/trace.go:171","msg":"trace[585856572] linearizableReadLoop","detail":"{readStateIndex:11521; appliedIndex:11519; }","duration":"559.187368ms","start":"2026-06-09T17:31:36.712033Z","end":"2026-06-09T17:31:37.27122Z","steps":["trace[585856572] 'read index received' (duration: 26.070893ms)","trace[585856572] 'applied index is now lower than readState.Index' (duration: 533.111885ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:31:37.271303Z","caller":"traceutil/trace.go:171","msg":"trace[165403017] transaction","detail":"{read_only:false; response_revision:11143; number_of_response:1; }","duration":"533.788761ms","start":"2026-06-09T17:31:36.737504Z","end":"2026-06-09T17:31:37.271293Z","steps":["trace[165403017] 'process raft request' (duration: 533.503904ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:31:37.271353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:31:36.737485Z","time spent":"533.842803ms","remote":"127.0.0.1:57168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T17:31:37.271425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"559.386973ms","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-06-09T17:31:37.271447Z","caller":"traceutil/trace.go:171","msg":"trace[324875903] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:11143; }","duration":"559.426044ms","start":"2026-06-09T17:31:36.712015Z","end":"2026-06-09T17:31:37.271441Z","steps":["trace[324875903] 'agreement among raft nodes before linearized reading' (duration: 559.312812ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:31:37.271468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:31:36.711959Z","time spent":"559.505006ms","remote":"127.0.0.1:57168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-09T17:31:37.271711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.557641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-06-09T17:31:37.271764Z","caller":"traceutil/trace.go:171","msg":"trace[416608828] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:11143; }","duration":"471.636083ms","start":"2026-06-09T17:31:36.800114Z","end":"2026-06-09T17:31:37.27175Z","steps":["trace[416608828] 'agreement among raft nodes before linearized reading' (duration: 471.344436ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:31:37.271773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.007843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-09T17:31:37.271814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:31:36.800087Z","time spent":"471.716325ms","remote":"127.0.0.1:57168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-06-09T17:31:37.271829Z","caller":"traceutil/trace.go:171","msg":"trace[1943052254] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:11143; }","duration":"111.095385ms","start":"2026-06-09T17:31:37.160717Z","end":"2026-06-09T17:31:37.271812Z","steps":["trace[1943052254] 'agreement among raft nodes before linearized reading' (duration: 110.997273ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:31:37.271829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.309636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-06-09T17:31:37.271892Z","caller":"traceutil/trace.go:171","msg":"trace[1356811099] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:11143; }","duration":"110.477871ms","start":"2026-06-09T17:31:37.161393Z","end":"2026-06-09T17:31:37.271871Z","steps":["trace[1356811099] 'agreement among raft nodes before linearized reading' (duration: 110.301936ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:31:37.272073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.762684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-06-09T17:31:37.272186Z","caller":"traceutil/trace.go:171","msg":"trace[1660779738] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:11143; }","duration":"121.913148ms","start":"2026-06-09T17:31:37.150259Z","end":"2026-06-09T17:31:37.272172Z","steps":["trace[1660779738] 'agreement among raft nodes before linearized reading' (duration: 121.474797ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:31:37.539621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.706797ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-09T17:31:37.539967Z","caller":"traceutil/trace.go:171","msg":"trace[680114205] transaction","detail":"{read_only:false; response_revision:11145; number_of_response:1; }","duration":"264.21535ms","start":"2026-06-09T17:31:37.275718Z","end":"2026-06-09T17:31:37.539933Z","steps":["trace[680114205] 'process raft request' (duration: 153.116314ms)","trace[680114205] 'compare' (duration: 110.529273ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:31:37.54001Z","caller":"traceutil/trace.go:171","msg":"trace[1660910352] linearizableReadLoop","detail":"{readStateIndex:11526; appliedIndex:11522; }","duration":"248.243783ms","start":"2026-06-09T17:31:37.291734Z","end":"2026-06-09T17:31:37.539978Z","steps":["trace[1660910352] 'read index received' (duration: 22.407013ms)","trace[1660910352] 'applied index is now lower than readState.Index' (duration: 225.83347ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:31:37.540037Z","caller":"traceutil/trace.go:171","msg":"trace[2103125501] transaction","detail":"{read_only:false; response_revision:11146; number_of_response:1; }","duration":"263.632396ms","start":"2026-06-09T17:31:37.276393Z","end":"2026-06-09T17:31:37.540025Z","steps":["trace[2103125501] 'process raft request' (duration: 263.314179ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:31:37.539984Z","caller":"traceutil/trace.go:171","msg":"trace[1647247663] transaction","detail":"{read_only:false; response_revision:11147; number_of_response:1; }","duration":"262.768806ms","start":"2026-06-09T17:31:37.277198Z","end":"2026-06-09T17:31:37.539967Z","steps":["trace[1647247663] 'process raft request' (duration: 262.565351ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:31:37.540239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.4845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-vxfvw\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-06-09T17:31:37.54042Z","caller":"traceutil/trace.go:171","msg":"trace[111531691] range","detail":"{range_begin:/registry/pods/openstack/keepalived-vxfvw; range_end:; response_count:1; response_revision:11148; }","duration":"248.722666ms","start":"2026-06-09T17:31:37.291686Z","end":"2026-06-09T17:31:37.540408Z","steps":["trace[111531691] 'agreement among raft nodes before linearized reading' (duration: 248.387098ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:31:37.539858Z","caller":"traceutil/trace.go:171","msg":"trace[1929849457] transaction","detail":"{read_only:false; response_revision:11148; number_of_response:1; }","duration":"138.162122ms","start":"2026-06-09T17:31:37.401679Z","end":"2026-06-09T17:31:37.539842Z","steps":["trace[1929849457] 'process raft request' (duration: 138.126081ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:31:38.067646Z","caller":"traceutil/trace.go:171","msg":"trace[1991381711] transaction","detail":"{read_only:false; response_revision:11149; number_of_response:1; }","duration":"121.916418ms","start":"2026-06-09T17:31:37.945713Z","end":"2026-06-09T17:31:38.067629Z","steps":["trace[1991381711] 'process raft request' (duration: 121.729523ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:36:01.563676Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10760} {"level":"info","ts":"2026-06-09T17:36:01.638938Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10760,"took":"72.666232ms","hash":14333641,"current-db-size-bytes":58462208,"current-db-size":"58 MB","current-db-size-in-use-bytes":27607040,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-06-09T17:36:01.638996Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":14333641,"revision":10760,"compact-revision":8269} {"level":"warn","ts":"2026-06-09T17:37:22.156721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.697953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-06-09T17:37:22.15682Z","caller":"traceutil/trace.go:171","msg":"trace[1616837676] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:13506; }","duration":"117.847566ms","start":"2026-06-09T17:37:22.038952Z","end":"2026-06-09T17:37:22.156799Z","steps":["trace[1616837676] 'range keys from in-memory index tree' (duration: 117.487807ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:37:49.702412Z","caller":"traceutil/trace.go:171","msg":"trace[1961746629] linearizableReadLoop","detail":"{readStateIndex:14120; appliedIndex:14118; }","duration":"225.338112ms","start":"2026-06-09T17:37:49.477027Z","end":"2026-06-09T17:37:49.702366Z","steps":["trace[1961746629] 'read index received' (duration: 5.952974ms)","trace[1961746629] 'applied index is now lower than readState.Index' (duration: 219.383248ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:37:49.702644Z","caller":"traceutil/trace.go:171","msg":"trace[514990037] transaction","detail":"{read_only:false; response_revision:13655; number_of_response:1; }","duration":"229.081083ms","start":"2026-06-09T17:37:49.473472Z","end":"2026-06-09T17:37:49.702553Z","steps":["trace[514990037] 'process raft request' (duration: 140.312031ms)","trace[514990037] 'compare' (duration: 88.408513ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:37:49.702882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.724111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-06-09T17:37:49.703001Z","caller":"traceutil/trace.go:171","msg":"trace[1536336064] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:13655; }","duration":"225.992468ms","start":"2026-06-09T17:37:49.476984Z","end":"2026-06-09T17:37:49.702977Z","steps":["trace[1536336064] 'agreement among raft nodes before linearized reading' (duration: 225.537037ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:38:07.168273Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305496616551641469,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-09T17:38:07.443328Z","caller":"traceutil/trace.go:171","msg":"trace[710167162] transaction","detail":"{read_only:false; response_revision:13763; number_of_response:1; }","duration":"792.276673ms","start":"2026-06-09T17:38:06.651018Z","end":"2026-06-09T17:38:07.443295Z","steps":["trace[710167162] 'process raft request' (duration: 792.093379ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:38:07.443507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:38:06.651Z","time spent":"792.406036ms","remote":"127.0.0.1:57168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T17:38:07.473982Z","caller":"traceutil/trace.go:171","msg":"trace[1793581793] transaction","detail":"{read_only:false; response_revision:13764; number_of_response:1; }","duration":"807.249895ms","start":"2026-06-09T17:38:06.666699Z","end":"2026-06-09T17:38:07.473949Z","steps":["trace[1793581793] 'process raft request' (duration: 807.073661ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:38:07.474158Z","caller":"traceutil/trace.go:171","msg":"trace[1098357758] linearizableReadLoop","detail":"{readStateIndex:14235; appliedIndex:14232; }","duration":"806.316144ms","start":"2026-06-09T17:38:06.667811Z","end":"2026-06-09T17:38:07.474128Z","steps":["trace[1098357758] 'read index received' (duration: 775.291132ms)","trace[1098357758] 'applied index is now lower than readState.Index' (duration: 31.022722ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:38:07.474232Z","caller":"traceutil/trace.go:171","msg":"trace[2057016444] transaction","detail":"{read_only:false; response_revision:13765; number_of_response:1; }","duration":"435.719811ms","start":"2026-06-09T17:38:07.038481Z","end":"2026-06-09T17:38:07.474201Z","steps":["trace[2057016444] 'process raft request' (duration: 435.418304ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:38:07.474379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:38:07.03846Z","time spent":"435.840034ms","remote":"127.0.0.1:57168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T17:38:07.474408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"792.5855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-06-09T17:38:07.474514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"478.55709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"warn","ts":"2026-06-09T17:38:07.474514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"806.671182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-vxfvw\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-06-09T17:38:07.47455Z","caller":"traceutil/trace.go:171","msg":"trace[1866811293] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:13765; }","duration":"792.701283ms","start":"2026-06-09T17:38:06.681743Z","end":"2026-06-09T17:38:07.474444Z","steps":["trace[1866811293] 'agreement among raft nodes before linearized reading' (duration: 792.588461ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:38:07.4746Z","caller":"traceutil/trace.go:171","msg":"trace[465394428] range","detail":"{range_begin:/registry/pods/openstack/keepalived-vxfvw; range_end:; response_count:1; response_revision:13765; }","duration":"806.803016ms","start":"2026-06-09T17:38:06.667778Z","end":"2026-06-09T17:38:07.474581Z","steps":["trace[465394428] 'agreement among raft nodes before linearized reading' (duration: 806.511969ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:38:07.474617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:38:06.681721Z","time spent":"792.876898ms","remote":"127.0.0.1:53768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true "} {"level":"warn","ts":"2026-06-09T17:38:07.474649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.341953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-06-09T17:38:07.474655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:38:06.667764Z","time spent":"806.881717ms","remote":"127.0.0.1:57078","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6554,"request content":"key:\"/registry/pods/openstack/keepalived-vxfvw\" "} {"level":"info","ts":"2026-06-09T17:38:07.474677Z","caller":"traceutil/trace.go:171","msg":"trace[487258468] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:13765; }","duration":"270.368534ms","start":"2026-06-09T17:38:07.2043Z","end":"2026-06-09T17:38:07.474669Z","steps":["trace[487258468] 'agreement among raft nodes before linearized reading' (duration: 270.294882ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:38:07.47472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"612.370903ms","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:5"} {"level":"info","ts":"2026-06-09T17:38:07.474771Z","caller":"traceutil/trace.go:171","msg":"trace[2126393434] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstoreusers/; range_end:/registry/ceph.rook.io/cephobjectstoreusers0; response_count:0; response_revision:13765; }","duration":"612.448445ms","start":"2026-06-09T17:38:06.86231Z","end":"2026-06-09T17:38:07.474758Z","steps":["trace[2126393434] 'agreement among raft nodes before linearized reading' (duration: 612.367173ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:38:07.474775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"507.925421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-09T17:38:07.47481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:38:06.862296Z","time spent":"612.505516ms","remote":"127.0.0.1:38046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephobjectstoreusers/\" range_end:\"/registry/ceph.rook.io/cephobjectstoreusers0\" count_only:true "} {"level":"info","ts":"2026-06-09T17:38:07.474815Z","caller":"traceutil/trace.go:171","msg":"trace[470921866] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:13765; }","duration":"507.991892ms","start":"2026-06-09T17:38:06.966812Z","end":"2026-06-09T17:38:07.474804Z","steps":["trace[470921866] 'agreement among raft nodes before linearized reading' (duration: 507.938451ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:38:07.47486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:38:06.966795Z","time spent":"508.055154ms","remote":"127.0.0.1:33966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true "} {"level":"warn","ts":"2026-06-09T17:38:07.474949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:38:06.666677Z","time spent":"807.477921ms","remote":"127.0.0.1:57168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T17:38:07.474984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"776.394008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/openvswitch-5f7r7\" ","response":"range_response_count:1 size:12599"} {"level":"info","ts":"2026-06-09T17:38:07.475032Z","caller":"traceutil/trace.go:171","msg":"trace[1088584390] range","detail":"{range_begin:/registry/pods/openstack/openvswitch-5f7r7; range_end:; response_count:1; response_revision:13765; }","duration":"776.48059ms","start":"2026-06-09T17:38:06.698537Z","end":"2026-06-09T17:38:07.475018Z","steps":["trace[1088584390] 'agreement among raft nodes before linearized reading' (duration: 776.337207ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:38:07.474564Z","caller":"traceutil/trace.go:171","msg":"trace[1813262805] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:13765; }","duration":"478.645752ms","start":"2026-06-09T17:38:06.995906Z","end":"2026-06-09T17:38:07.474552Z","steps":["trace[1813262805] 'agreement among raft nodes before linearized reading' (duration: 478.495608ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:38:07.475082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:38:06.698518Z","time spent":"776.553311ms","remote":"127.0.0.1:57078","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":12622,"request content":"key:\"/registry/pods/openstack/openvswitch-5f7r7\" "} {"level":"warn","ts":"2026-06-09T17:38:07.475213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:38:06.995886Z","time spent":"479.313967ms","remote":"127.0.0.1:57168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":553,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"info","ts":"2026-06-09T17:41:01.570445Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12938} {"level":"info","ts":"2026-06-09T17:41:01.641941Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12938,"took":"69.141526ms","hash":818419161,"current-db-size-bytes":58462208,"current-db-size":"58 MB","current-db-size-in-use-bytes":31133696,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-06-09T17:41:01.641984Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":818419161,"revision":12938,"compact-revision":10760} {"level":"info","ts":"2026-06-09T17:45:08.531992Z","caller":"traceutil/trace.go:171","msg":"trace[1597250190] transaction","detail":"{read_only:false; response_revision:17282; number_of_response:1; }","duration":"132.310908ms","start":"2026-06-09T17:45:08.399646Z","end":"2026-06-09T17:45:08.531957Z","steps":["trace[1597250190] 'process raft request' (duration: 132.136663ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:45:08.766549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.543071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-9a9fe36d-e96b-4734-8eb0-6ac8347db550\" ","response":"range_response_count:1 size:1323"} {"level":"info","ts":"2026-06-09T17:45:08.766678Z","caller":"traceutil/trace.go:171","msg":"trace[998099843] range","detail":"{range_begin:/registry/persistentvolumes/pvc-9a9fe36d-e96b-4734-8eb0-6ac8347db550; range_end:; response_count:1; response_revision:17282; }","duration":"231.701155ms","start":"2026-06-09T17:45:08.534958Z","end":"2026-06-09T17:45:08.766659Z","steps":["trace[998099843] 'range keys from in-memory index tree' (duration: 231.401127ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:45:08.766651Z","caller":"traceutil/trace.go:171","msg":"trace[1856847348] linearizableReadLoop","detail":"{readStateIndex:17865; appliedIndex:17864; }","duration":"176.016775ms","start":"2026-06-09T17:45:08.590598Z","end":"2026-06-09T17:45:08.766615Z","steps":["trace[1856847348] 'read index received' (duration: 175.454591ms)","trace[1856847348] 'applied index is now lower than readState.Index' (duration: 560.464µs)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:45:08.76682Z","caller":"traceutil/trace.go:171","msg":"trace[769546145] transaction","detail":"{read_only:false; response_revision:17283; number_of_response:1; }","duration":"221.527239ms","start":"2026-06-09T17:45:08.545265Z","end":"2026-06-09T17:45:08.766792Z","steps":["trace[769546145] 'process raft request' (duration: 220.840843ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:45:08.767607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.98699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-lzfhb\" ","response":"range_response_count:1 size:20873"} {"level":"info","ts":"2026-06-09T17:45:08.767682Z","caller":"traceutil/trace.go:171","msg":"trace[1563939650] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-lzfhb; range_end:; response_count:1; response_revision:17283; }","duration":"177.103232ms","start":"2026-06-09T17:45:08.590562Z","end":"2026-06-09T17:45:08.767665Z","steps":["trace[1563939650] 'agreement among raft nodes before linearized reading' (duration: 176.171059ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:45:09.019048Z","caller":"traceutil/trace.go:171","msg":"trace[56463059] linearizableReadLoop","detail":"{readStateIndex:17866; appliedIndex:17865; }","duration":"174.799426ms","start":"2026-06-09T17:45:08.844224Z","end":"2026-06-09T17:45:09.019023Z","steps":["trace[56463059] 'read index received' (duration: 174.55568ms)","trace[56463059] 'applied index is now lower than readState.Index' (duration: 242.836µs)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:45:09.01923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.981331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretimports/\" range_end:\"/registry/secretgen.carvel.dev/secretimports0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-09T17:45:09.019267Z","caller":"traceutil/trace.go:171","msg":"trace[1177950833] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretimports/; range_end:/registry/secretgen.carvel.dev/secretimports0; response_count:0; response_revision:17284; }","duration":"175.067204ms","start":"2026-06-09T17:45:08.844187Z","end":"2026-06-09T17:45:09.019254Z","steps":["trace[1177950833] 'agreement among raft nodes before linearized reading' (duration: 174.939361ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:45:09.019536Z","caller":"traceutil/trace.go:171","msg":"trace[555984545] transaction","detail":"{read_only:false; response_revision:17284; number_of_response:1; }","duration":"231.274695ms","start":"2026-06-09T17:45:08.78825Z","end":"2026-06-09T17:45:09.019524Z","steps":["trace[555984545] 'process raft request' (duration: 230.601438ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:46:01.576192Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15343} {"level":"info","ts":"2026-06-09T17:46:01.668466Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15343,"took":"89.648873ms","hash":595765983,"current-db-size-bytes":58462208,"current-db-size":"58 MB","current-db-size-in-use-bytes":33669120,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-06-09T17:46:01.668523Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":595765983,"revision":15343,"compact-revision":12938} {"level":"info","ts":"2026-06-09T17:49:12.570773Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"1cac722af8b2b8a6","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-09T17:49:12.581386Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-06-09T17:49:12.581568Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-06-09T17:50:18.272536Z","caller":"traceutil/trace.go:171","msg":"trace[1188494337] transaction","detail":"{read_only:false; response_revision:19775; number_of_response:1; }","duration":"100.907578ms","start":"2026-06-09T17:50:18.171605Z","end":"2026-06-09T17:50:18.272513Z","steps":["trace[1188494337] 'process raft request' (duration: 100.737024ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:50:18.329796Z","caller":"traceutil/trace.go:171","msg":"trace[1246410294] transaction","detail":"{read_only:false; response_revision:19776; number_of_response:1; }","duration":"156.632191ms","start":"2026-06-09T17:50:18.17314Z","end":"2026-06-09T17:50:18.329772Z","steps":["trace[1246410294] 'process raft request' (duration: 155.813432ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:50:18.330044Z","caller":"traceutil/trace.go:171","msg":"trace[2080218647] transaction","detail":"{read_only:false; response_revision:19777; number_of_response:1; }","duration":"153.733469ms","start":"2026-06-09T17:50:18.1763Z","end":"2026-06-09T17:50:18.330034Z","steps":["trace[2080218647] 'process raft request' (duration: 152.778157ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:50:18.330235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.137059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-09T17:50:18.330287Z","caller":"traceutil/trace.go:171","msg":"trace[455088342] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:19777; }","duration":"100.246562ms","start":"2026-06-09T17:50:18.230028Z","end":"2026-06-09T17:50:18.330274Z","steps":["trace[455088342] 'agreement among raft nodes before linearized reading' (duration: 100.1423ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:50:34.234054Z","caller":"traceutil/trace.go:171","msg":"trace[216605015] transaction","detail":"{read_only:false; response_revision:19856; number_of_response:1; }","duration":"103.920142ms","start":"2026-06-09T17:50:34.13011Z","end":"2026-06-09T17:50:34.23403Z","steps":["trace[216605015] 'process raft request' (duration: 95.360064ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:50:34.555072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.120402ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-09T17:50:34.555207Z","caller":"traceutil/trace.go:171","msg":"trace[418739755] transaction","detail":"{read_only:false; response_revision:19857; number_of_response:1; }","duration":"228.540545ms","start":"2026-06-09T17:50:34.326644Z","end":"2026-06-09T17:50:34.555184Z","steps":["trace[418739755] 'process raft request' (duration: 64.211218ms)","trace[418739755] 'compare' (duration: 163.477307ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:50:34.63009Z","caller":"traceutil/trace.go:171","msg":"trace[1913274855] transaction","detail":"{read_only:false; response_revision:19858; number_of_response:1; }","duration":"242.753221ms","start":"2026-06-09T17:50:34.3873Z","end":"2026-06-09T17:50:34.630053Z","steps":["trace[1913274855] 'process raft request' (duration: 242.578066ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:50:34.630263Z","caller":"traceutil/trace.go:171","msg":"trace[426912400] transaction","detail":"{read_only:false; response_revision:19859; number_of_response:1; }","duration":"242.600326ms","start":"2026-06-09T17:50:34.387654Z","end":"2026-06-09T17:50:34.630254Z","steps":["trace[426912400] 'process raft request' (duration: 242.357021ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:50:34.630522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.251436ms","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-06-09T17:50:34.630598Z","caller":"traceutil/trace.go:171","msg":"trace[951230765] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:19859; }","duration":"155.351859ms","start":"2026-06-09T17:50:34.475216Z","end":"2026-06-09T17:50:34.630568Z","steps":["trace[951230765] 'agreement among raft nodes before linearized reading' (duration: 155.178005ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:50:34.630759Z","caller":"traceutil/trace.go:171","msg":"trace[1084608876] linearizableReadLoop","detail":"{readStateIndex:20526; appliedIndex:20524; }","duration":"154.98ms","start":"2026-06-09T17:50:34.475262Z","end":"2026-06-09T17:50:34.630242Z","steps":["trace[1084608876] 'read index received' (duration: 154.57039ms)","trace[1084608876] 'applied index is now lower than readState.Index' (duration: 405.94µs)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:50:34.78423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.486745ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-09T17:50:34.784364Z","caller":"traceutil/trace.go:171","msg":"trace[1558194077] linearizableReadLoop","detail":"{readStateIndex:20527; appliedIndex:20526; }","duration":"106.139766ms","start":"2026-06-09T17:50:34.678205Z","end":"2026-06-09T17:50:34.784345Z","steps":["trace[1558194077] 'read index received' (duration: 1.381684ms)","trace[1558194077] 'applied index is now lower than readState.Index' (duration: 104.756922ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:50:34.784502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.32033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.210\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-06-09T17:50:34.784509Z","caller":"traceutil/trace.go:171","msg":"trace[1110285250] transaction","detail":"{read_only:false; response_revision:19860; number_of_response:1; }","duration":"149.574779ms","start":"2026-06-09T17:50:34.634904Z","end":"2026-06-09T17:50:34.784479Z","steps":["trace[1110285250] 'process raft request' (duration: 44.742546ms)","trace[1110285250] 'compare' (duration: 104.376422ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:50:34.784552Z","caller":"traceutil/trace.go:171","msg":"trace[1492972938] range","detail":"{range_begin:/registry/masterleases/199.204.45.210; range_end:; response_count:1; response_revision:19860; }","duration":"106.434752ms","start":"2026-06-09T17:50:34.678107Z","end":"2026-06-09T17:50:34.784542Z","steps":["trace[1492972938] 'agreement among raft nodes before linearized reading' (duration: 106.282519ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:50:35.223779Z","caller":"traceutil/trace.go:171","msg":"trace[1125179331] linearizableReadLoop","detail":"{readStateIndex:20529; appliedIndex:20528; }","duration":"106.870883ms","start":"2026-06-09T17:50:35.116884Z","end":"2026-06-09T17:50:35.223755Z","steps":["trace[1125179331] 'read index received' (duration: 10.667769ms)","trace[1125179331] 'applied index is now lower than readState.Index' (duration: 96.202124ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:50:35.223931Z","caller":"traceutil/trace.go:171","msg":"trace[527710351] transaction","detail":"{read_only:false; response_revision:19861; number_of_response:1; }","duration":"282.745911ms","start":"2026-06-09T17:50:34.941166Z","end":"2026-06-09T17:50:35.223912Z","steps":["trace[527710351] 'process raft request' (duration: 186.396783ms)","trace[527710351] 'compare' (duration: 95.982009ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:50:35.224253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.353594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-09T17:50:35.224331Z","caller":"traceutil/trace.go:171","msg":"trace[1641927769] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:19861; }","duration":"107.506658ms","start":"2026-06-09T17:50:35.116806Z","end":"2026-06-09T17:50:35.224313Z","steps":["trace[1641927769] 'agreement among raft nodes before linearized reading' (duration: 107.324184ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:50:35.435486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.208285ms","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-06-09T17:50:35.435574Z","caller":"traceutil/trace.go:171","msg":"trace[896936550] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:19862; }","duration":"108.36982ms","start":"2026-06-09T17:50:35.327183Z","end":"2026-06-09T17:50:35.435553Z","steps":["trace[896936550] 'range keys from in-memory index tree' (duration: 107.966529ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:51:01.600055Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17910} {"level":"info","ts":"2026-06-09T17:51:01.708971Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17910,"took":"105.752705ms","hash":2524037037,"current-db-size-bytes":58462208,"current-db-size":"58 MB","current-db-size-in-use-bytes":29564928,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-06-09T17:51:01.709056Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2524037037,"revision":17910,"compact-revision":15343} {"level":"warn","ts":"2026-06-09T17:54:16.241547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.46605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-09T17:54:16.241687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.702853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-09T17:54:16.241749Z","caller":"traceutil/trace.go:171","msg":"trace[1776142256] range","detail":"{range_begin:/registry/cert-manager.io/certificaterequests/; range_end:/registry/cert-manager.io/certificaterequests0; response_count:0; response_revision:22198; }","duration":"107.810956ms","start":"2026-06-09T17:54:16.133921Z","end":"2026-06-09T17:54:16.241732Z","steps":["trace[1776142256] 'count revisions from in-memory index tree' (duration: 107.607381ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:54:16.241694Z","caller":"traceutil/trace.go:171","msg":"trace[1855613019] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:22198; }","duration":"124.633144ms","start":"2026-06-09T17:54:16.117013Z","end":"2026-06-09T17:54:16.241646Z","steps":["trace[1855613019] 'range keys from in-memory index tree' (duration: 124.351317ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:56:01.607659Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20025} {"level":"info","ts":"2026-06-09T17:56:01.731105Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20025,"took":"117.346928ms","hash":1118346747,"current-db-size-bytes":58462208,"current-db-size":"58 MB","current-db-size-in-use-bytes":42196992,"current-db-size-in-use":"42 MB"} {"level":"info","ts":"2026-06-09T17:56:01.731181Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1118346747,"revision":20025,"compact-revision":17910} {"level":"warn","ts":"2026-06-09T17:57:20.278724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.053744ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-06-09T17:57:20.279012Z","caller":"traceutil/trace.go:171","msg":"trace[1428159651] linearizableReadLoop","detail":"{readStateIndex:24912; appliedIndex:24911; }","duration":"378.229248ms","start":"2026-06-09T17:57:19.900759Z","end":"2026-06-09T17:57:20.278988Z","steps":["trace[1428159651] 'read index received' (duration: 58.855867ms)","trace[1428159651] 'applied index is now lower than readState.Index' (duration: 319.371631ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:57:20.280136Z","caller":"traceutil/trace.go:171","msg":"trace[259750949] transaction","detail":"{read_only:false; response_revision:24124; number_of_response:1; }","duration":"219.918891ms","start":"2026-06-09T17:57:20.060187Z","end":"2026-06-09T17:57:20.280106Z","steps":["trace[259750949] 'process raft request' (duration: 218.65929ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:57:20.280356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.57547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-06-09T17:57:20.280461Z","caller":"traceutil/trace.go:171","msg":"trace[1310197159] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24123; }","duration":"379.694913ms","start":"2026-06-09T17:57:19.900742Z","end":"2026-06-09T17:57:20.280436Z","steps":["trace[1310197159] 'agreement among raft nodes before linearized reading' (duration: 378.876764ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:57:20.280522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:57:19.900685Z","time spent":"379.824147ms","remote":"127.0.0.1:57168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-06-09T17:57:20.529037Z","caller":"traceutil/trace.go:171","msg":"trace[1538827482] transaction","detail":"{read_only:false; response_revision:24126; number_of_response:1; }","duration":"420.175115ms","start":"2026-06-09T17:57:20.10884Z","end":"2026-06-09T17:57:20.529015Z","steps":["trace[1538827482] 'process raft request' (duration: 420.095263ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:57:20.529095Z","caller":"traceutil/trace.go:171","msg":"trace[1360366352] transaction","detail":"{read_only:false; response_revision:24125; number_of_response:1; }","duration":"422.844999ms","start":"2026-06-09T17:57:20.106203Z","end":"2026-06-09T17:57:20.529048Z","steps":["trace[1360366352] 'process raft request' (duration: 422.575313ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:57:20.529253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:57:20.108813Z","time spent":"420.367059ms","remote":"127.0.0.1:57168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T17:57:20.529287Z","caller":"traceutil/trace.go:171","msg":"trace[1175905002] linearizableReadLoop","detail":"{readStateIndex:24916; appliedIndex:24913; }","duration":"250.196096ms","start":"2026-06-09T17:57:20.279065Z","end":"2026-06-09T17:57:20.529261Z","steps":["trace[1175905002] 'read index received' (duration: 249.647722ms)","trace[1175905002] 'applied index is now lower than readState.Index' (duration: 546.603µs)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:57:20.529339Z","caller":"traceutil/trace.go:171","msg":"trace[1579162734] transaction","detail":"{read_only:false; response_revision:24127; number_of_response:1; }","duration":"417.163621ms","start":"2026-06-09T17:57:20.112131Z","end":"2026-06-09T17:57:20.529295Z","steps":["trace[1579162734] 'process raft request' (duration: 416.848434ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:57:20.529397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.522934ms","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":"info","ts":"2026-06-09T17:57:20.529433Z","caller":"traceutil/trace.go:171","msg":"trace[175876824] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:24127; }","duration":"413.593045ms","start":"2026-06-09T17:57:20.115831Z","end":"2026-06-09T17:57:20.529424Z","steps":["trace[175876824] 'agreement among raft nodes before linearized reading' (duration: 413.482373ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:57:20.529457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:57:20.115813Z","time spent":"413.635936ms","remote":"127.0.0.1:57362","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":19,"response size":31,"request content":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true "} {"level":"warn","ts":"2026-06-09T17:57:20.529457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"533.43696ms","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-06-09T17:57:20.529486Z","caller":"traceutil/trace.go:171","msg":"trace[1078220207] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24127; }","duration":"533.492852ms","start":"2026-06-09T17:57:19.995985Z","end":"2026-06-09T17:57:20.529477Z","steps":["trace[1078220207] 'agreement among raft nodes before linearized reading' (duration: 533.362249ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:57:20.529508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:57:19.995966Z","time spent":"533.535862ms","remote":"127.0.0.1:57168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-09T17:57:20.529541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:57:20.112121Z","time spent":"417.309715ms","remote":"127.0.0.1:57168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T17:57:20.529702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:57:20.106176Z","time spent":"423.016053ms","remote":"127.0.0.1:57168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T17:57:20.876309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.278379ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-09T17:57:20.876663Z","caller":"traceutil/trace.go:171","msg":"trace[82021006] linearizableReadLoop","detail":"{readStateIndex:24918; appliedIndex:24916; }","duration":"347.323128ms","start":"2026-06-09T17:57:20.529321Z","end":"2026-06-09T17:57:20.876644Z","steps":["trace[82021006] 'read index received' (duration: 100.603548ms)","trace[82021006] 'applied index is now lower than readState.Index' (duration: 246.71842ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:57:20.876816Z","caller":"traceutil/trace.go:171","msg":"trace[1448034383] transaction","detail":"{read_only:false; response_revision:24129; number_of_response:1; }","duration":"473.912146ms","start":"2026-06-09T17:57:20.40286Z","end":"2026-06-09T17:57:20.876772Z","steps":["trace[1448034383] 'process raft request' (duration: 473.561048ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:57:20.876893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.099785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/routerinterfaces/\" range_end:\"/registry/openstack.k-orc.cloud/routerinterfaces0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-09T17:57:20.87693Z","caller":"traceutil/trace.go:171","msg":"trace[945818875] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/routerinterfaces/; range_end:/registry/openstack.k-orc.cloud/routerinterfaces0; response_count:0; response_revision:24130; }","duration":"326.185937ms","start":"2026-06-09T17:57:20.550731Z","end":"2026-06-09T17:57:20.876917Z","steps":["trace[945818875] 'agreement among raft nodes before linearized reading' (duration: 326.079074ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:57:20.87696Z","caller":"traceutil/trace.go:171","msg":"trace[1363213089] transaction","detail":"{read_only:false; response_revision:24128; number_of_response:1; }","duration":"591.24402ms","start":"2026-06-09T17:57:20.285694Z","end":"2026-06-09T17:57:20.876938Z","steps":["trace[1363213089] 'process raft request' (duration: 344.224003ms)","trace[1363213089] 'compare' (duration: 246.093064ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:57:20.876984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:57:20.402833Z","time spent":"474.071221ms","remote":"127.0.0.1:57168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T17:57:20.877029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.913972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/podmonitors/\" range_end:\"/registry/monitoring.coreos.com/podmonitors0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-09T17:57:20.877066Z","caller":"traceutil/trace.go:171","msg":"trace[157119930] range","detail":"{range_begin:/registry/monitoring.coreos.com/podmonitors/; range_end:/registry/monitoring.coreos.com/podmonitors0; response_count:0; response_revision:24130; }","duration":"361.985834ms","start":"2026-06-09T17:57:20.51507Z","end":"2026-06-09T17:57:20.877055Z","steps":["trace[157119930] 'agreement among raft nodes before linearized reading' (duration: 361.694837ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:57:20.877132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:57:20.51505Z","time spent":"362.046315ms","remote":"127.0.0.1:33872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":6,"response size":31,"request content":"key:\"/registry/monitoring.coreos.com/podmonitors/\" range_end:\"/registry/monitoring.coreos.com/podmonitors0\" count_only:true "} {"level":"info","ts":"2026-06-09T17:57:20.877131Z","caller":"traceutil/trace.go:171","msg":"trace[891571159] transaction","detail":"{read_only:false; response_revision:24130; number_of_response:1; }","duration":"343.065776ms","start":"2026-06-09T17:57:20.53403Z","end":"2026-06-09T17:57:20.877096Z","steps":["trace[891571159] 'process raft request' (duration: 342.522673ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:57:20.877217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:57:20.534007Z","time spent":"343.159098ms","remote":"127.0.0.1:57168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T17:57:20.87706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:57:20.285673Z","time spent":"591.338253ms","remote":"127.0.0.1:57168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T17:57:20.876974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:57:20.550708Z","time spent":"326.256718ms","remote":"127.0.0.1:55758","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":29,"request content":"key:\"/registry/openstack.k-orc.cloud/routerinterfaces/\" range_end:\"/registry/openstack.k-orc.cloud/routerinterfaces0\" count_only:true "} {"level":"info","ts":"2026-06-09T17:57:21.189396Z","caller":"traceutil/trace.go:171","msg":"trace[1286931206] transaction","detail":"{read_only:false; response_revision:24131; number_of_response:1; }","duration":"251.885245ms","start":"2026-06-09T17:57:20.937489Z","end":"2026-06-09T17:57:21.189374Z","steps":["trace[1286931206] 'process raft request' (duration: 251.718221ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:57:21.2435Z","caller":"traceutil/trace.go:171","msg":"trace[1458724571] transaction","detail":"{read_only:false; response_revision:24132; number_of_response:1; }","duration":"188.55333ms","start":"2026-06-09T17:57:21.054921Z","end":"2026-06-09T17:57:21.243474Z","steps":["trace[1458724571] 'process raft request' (duration: 166.406573ms)","trace[1458724571] 'compare' (duration: 21.986504ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:57:21.243721Z","caller":"traceutil/trace.go:171","msg":"trace[1188611115] linearizableReadLoop","detail":"{readStateIndex:24921; appliedIndex:24920; }","duration":"128.901344ms","start":"2026-06-09T17:57:21.114798Z","end":"2026-06-09T17:57:21.243699Z","steps":["trace[1188611115] 'read index received' (duration: 74.75068ms)","trace[1188611115] 'applied index is now lower than readState.Index' (duration: 54.149744ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:57:21.24401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.205802ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-09T17:57:21.244054Z","caller":"traceutil/trace.go:171","msg":"trace[336545216] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24133; }","duration":"129.296044ms","start":"2026-06-09T17:57:21.114746Z","end":"2026-06-09T17:57:21.244042Z","steps":["trace[336545216] 'agreement among raft nodes before linearized reading' (duration: 129.219062ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:57:26.492886Z","caller":"traceutil/trace.go:171","msg":"trace[272790668] linearizableReadLoop","detail":"{readStateIndex:24964; appliedIndex:24963; }","duration":"139.188284ms","start":"2026-06-09T17:57:26.353671Z","end":"2026-06-09T17:57:26.49286Z","steps":["trace[272790668] 'read index received' (duration: 139.106451ms)","trace[272790668] 'applied index is now lower than readState.Index' (duration: 80.803µs)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:57:26.493702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.428689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-06-09T17:57:26.493742Z","caller":"traceutil/trace.go:171","msg":"trace[744331069] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24174; }","duration":"140.099746ms","start":"2026-06-09T17:57:26.353633Z","end":"2026-06-09T17:57:26.493733Z","steps":["trace[744331069] 'agreement among raft nodes before linearized reading' (duration: 139.332517ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:57:26.493786Z","caller":"traceutil/trace.go:171","msg":"trace[802605519] transaction","detail":"{read_only:false; response_revision:24174; number_of_response:1; }","duration":"179.026179ms","start":"2026-06-09T17:57:26.314733Z","end":"2026-06-09T17:57:26.493759Z","steps":["trace[802605519] 'process raft request' (duration: 177.900882ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:57:26.657461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.293864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-09T17:57:26.657519Z","caller":"traceutil/trace.go:171","msg":"trace[1386608749] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:24174; }","duration":"110.391335ms","start":"2026-06-09T17:57:26.547115Z","end":"2026-06-09T17:57:26.657507Z","steps":["trace[1386608749] 'range keys from in-memory index tree' (duration: 110.227171ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:57:26.92147Z","caller":"traceutil/trace.go:171","msg":"trace[929980510] transaction","detail":"{read_only:false; response_revision:24175; number_of_response:1; }","duration":"373.581515ms","start":"2026-06-09T17:57:26.547863Z","end":"2026-06-09T17:57:26.921444Z","steps":["trace[929980510] 'process raft request' (duration: 373.40221ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:57:26.921662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:57:26.547841Z","time spent":"373.721909ms","remote":"127.0.0.1:57168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T17:57:26.938406Z","caller":"traceutil/trace.go:171","msg":"trace[1840666825] linearizableReadLoop","detail":"{readStateIndex:24967; appliedIndex:24964; }","duration":"281.780271ms","start":"2026-06-09T17:57:26.656606Z","end":"2026-06-09T17:57:26.938386Z","steps":["trace[1840666825] 'read index received' (duration: 264.780808ms)","trace[1840666825] 'applied index is now lower than readState.Index' (duration: 16.998372ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:57:26.938439Z","caller":"traceutil/trace.go:171","msg":"trace[535252642] transaction","detail":"{read_only:false; response_revision:24176; number_of_response:1; }","duration":"389.05928ms","start":"2026-06-09T17:57:26.549359Z","end":"2026-06-09T17:57:26.938419Z","steps":["trace[535252642] 'process raft request' (duration: 388.851814ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:57:26.938541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:57:26.549339Z","time spent":"389.148232ms","remote":"127.0.0.1:57168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T17:57:26.93856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.939954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-09T17:57:26.938547Z","caller":"traceutil/trace.go:171","msg":"trace[1471865188] transaction","detail":"{read_only:false; response_revision:24177; number_of_response:1; }","duration":"388.750343ms","start":"2026-06-09T17:57:26.549765Z","end":"2026-06-09T17:57:26.938515Z","steps":["trace[1471865188] 'process raft request' (duration: 388.579609ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:57:26.938595Z","caller":"traceutil/trace.go:171","msg":"trace[779705708] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:24177; }","duration":"282.017895ms","start":"2026-06-09T17:57:26.656566Z","end":"2026-06-09T17:57:26.938584Z","steps":["trace[779705708] 'agreement among raft nodes before linearized reading' (duration: 281.936313ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:57:26.938654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.834995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-09T17:57:26.938713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:57:26.549747Z","time spent":"388.858435ms","remote":"127.0.0.1:57168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T17:57:26.93874Z","caller":"traceutil/trace.go:171","msg":"trace[2100575674] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:24177; }","duration":"277.965278ms","start":"2026-06-09T17:57:26.660755Z","end":"2026-06-09T17:57:26.93872Z","steps":["trace[2100575674] 'agreement among raft nodes before linearized reading' (duration: 277.813025ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:57:27.211805Z","caller":"traceutil/trace.go:171","msg":"trace[580491649] transaction","detail":"{read_only:false; response_revision:24178; number_of_response:1; }","duration":"268.15165ms","start":"2026-06-09T17:57:26.943628Z","end":"2026-06-09T17:57:27.21178Z","steps":["trace[580491649] 'process raft request' (duration: 255.575255ms)","trace[580491649] 'compare' (duration: 12.250787ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:57:27.235249Z","caller":"traceutil/trace.go:171","msg":"trace[1429504811] linearizableReadLoop","detail":"{readStateIndex:24969; appliedIndex:24967; }","duration":"283.289718ms","start":"2026-06-09T17:57:26.951937Z","end":"2026-06-09T17:57:27.235227Z","steps":["trace[1429504811] 'read index received' (duration: 247.282235ms)","trace[1429504811] 'applied index is now lower than readState.Index' (duration: 36.006422ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:57:27.235591Z","caller":"traceutil/trace.go:171","msg":"trace[83324794] transaction","detail":"{read_only:false; response_revision:24179; number_of_response:1; }","duration":"291.008034ms","start":"2026-06-09T17:57:26.944571Z","end":"2026-06-09T17:57:27.235579Z","steps":["trace[83324794] 'process raft request' (duration: 290.47794ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:57:27.235935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.990004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-06-09T17:57:27.235976Z","caller":"traceutil/trace.go:171","msg":"trace[2062564245] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24180; }","duration":"284.059516ms","start":"2026-06-09T17:57:26.951907Z","end":"2026-06-09T17:57:27.235966Z","steps":["trace[2062564245] 'agreement among raft nodes before linearized reading' (duration: 283.924913ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:57:27.236097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.637625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/superstreams/\" range_end:\"/registry/rabbitmq.com/superstreams0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-09T17:57:27.236225Z","caller":"traceutil/trace.go:171","msg":"trace[1696517055] range","detail":"{range_begin:/registry/rabbitmq.com/superstreams/; range_end:/registry/rabbitmq.com/superstreams0; response_count:0; response_revision:24180; }","duration":"217.82185ms","start":"2026-06-09T17:57:27.01838Z","end":"2026-06-09T17:57:27.236202Z","steps":["trace[1696517055] 'agreement among raft nodes before linearized reading' (duration: 217.613635ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:57:27.236214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.798864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-09T17:57:27.236294Z","caller":"traceutil/trace.go:171","msg":"trace[1601211442] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24180; }","duration":"119.927837ms","start":"2026-06-09T17:57:27.116348Z","end":"2026-06-09T17:57:27.236276Z","steps":["trace[1601211442] 'agreement among raft nodes before linearized reading' (duration: 119.798183ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:57:27.549355Z","caller":"traceutil/trace.go:171","msg":"trace[252882658] transaction","detail":"{read_only:false; response_revision:24181; number_of_response:1; }","duration":"303.844715ms","start":"2026-06-09T17:57:27.245476Z","end":"2026-06-09T17:57:27.549321Z","steps":["trace[252882658] 'process raft request' (duration: 278.679595ms)","trace[252882658] 'compare' (duration: 24.521114ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:57:27.549538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:57:27.24545Z","time spent":"304.012049ms","remote":"127.0.0.1:57168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":538,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T17:57:27.551967Z","caller":"traceutil/trace.go:171","msg":"trace[1304763943] transaction","detail":"{read_only:false; response_revision:24183; number_of_response:1; }","duration":"287.13387ms","start":"2026-06-09T17:57:27.264817Z","end":"2026-06-09T17:57:27.551951Z","steps":["trace[1304763943] 'process raft request' (duration: 287.059598ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:57:27.551987Z","caller":"traceutil/trace.go:171","msg":"trace[624559617] transaction","detail":"{read_only:false; response_revision:24182; number_of_response:1; }","duration":"288.908253ms","start":"2026-06-09T17:57:27.263061Z","end":"2026-06-09T17:57:27.55197Z","steps":["trace[624559617] 'process raft request' (duration: 288.711759ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:57:27.552089Z","caller":"traceutil/trace.go:171","msg":"trace[1345274617] transaction","detail":"{read_only:false; response_revision:24184; number_of_response:1; }","duration":"199.992648ms","start":"2026-06-09T17:57:27.352086Z","end":"2026-06-09T17:57:27.552078Z","steps":["trace[1345274617] 'process raft request' (duration: 199.832174ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:57:27.552089Z","caller":"traceutil/trace.go:171","msg":"trace[2103892537] linearizableReadLoop","detail":"{readStateIndex:24972; appliedIndex:24970; }","duration":"287.729254ms","start":"2026-06-09T17:57:27.264345Z","end":"2026-06-09T17:57:27.552075Z","steps":["trace[2103892537] 'read index received' (duration: 259.828518ms)","trace[2103892537] 'applied index is now lower than readState.Index' (duration: 27.899016ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:57:27.552312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.190692ms","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-06-09T17:57:27.552355Z","caller":"traceutil/trace.go:171","msg":"trace[859409317] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24184; }","duration":"240.261303ms","start":"2026-06-09T17:57:27.312081Z","end":"2026-06-09T17:57:27.552342Z","steps":["trace[859409317] 'agreement among raft nodes before linearized reading' (duration: 240.14234ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:57:27.553372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.012144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5684"} {"level":"info","ts":"2026-06-09T17:57:27.553467Z","caller":"traceutil/trace.go:171","msg":"trace[636674692] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:24184; }","duration":"289.135078ms","start":"2026-06-09T17:57:27.264319Z","end":"2026-06-09T17:57:27.553454Z","steps":["trace[636674692] 'agreement among raft nodes before linearized reading' (duration: 288.975214ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:57:27.798297Z","caller":"traceutil/trace.go:171","msg":"trace[1990458307] transaction","detail":"{read_only:false; response_revision:24186; number_of_response:1; }","duration":"227.631057ms","start":"2026-06-09T17:57:27.570643Z","end":"2026-06-09T17:57:27.798274Z","steps":["trace[1990458307] 'process raft request' (duration: 227.168025ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:57:27.798437Z","caller":"traceutil/trace.go:171","msg":"trace[929210067] transaction","detail":"{read_only:false; response_revision:24187; number_of_response:1; }","duration":"227.634898ms","start":"2026-06-09T17:57:27.570794Z","end":"2026-06-09T17:57:27.798429Z","steps":["trace[929210067] 'process raft request' (duration: 227.140585ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:57:28.137558Z","caller":"traceutil/trace.go:171","msg":"trace[410949848] linearizableReadLoop","detail":"{readStateIndex:24978; appliedIndex:24977; }","duration":"114.087006ms","start":"2026-06-09T17:57:28.023436Z","end":"2026-06-09T17:57:28.137523Z","steps":["trace[410949848] 'read index received' (duration: 113.778928ms)","trace[410949848] 'applied index is now lower than readState.Index' (duration: 306.888µs)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:57:28.137717Z","caller":"traceutil/trace.go:171","msg":"trace[642655699] transaction","detail":"{read_only:false; response_revision:24188; number_of_response:1; }","duration":"133.008035ms","start":"2026-06-09T17:57:28.004662Z","end":"2026-06-09T17:57:28.13767Z","steps":["trace[642655699] 'process raft request' (duration: 132.650366ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:57:28.137787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.302461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtime.cluster.x-k8s.io/extensionconfigs/\" range_end:\"/registry/runtime.cluster.x-k8s.io/extensionconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-09T17:57:28.137849Z","caller":"traceutil/trace.go:171","msg":"trace[1151966023] range","detail":"{range_begin:/registry/runtime.cluster.x-k8s.io/extensionconfigs/; range_end:/registry/runtime.cluster.x-k8s.io/extensionconfigs0; response_count:0; response_revision:24188; }","duration":"114.399403ms","start":"2026-06-09T17:57:28.023425Z","end":"2026-06-09T17:57:28.137824Z","steps":["trace[1151966023] 'agreement among raft nodes before linearized reading' (duration: 114.26645ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:57:28.625243Z","caller":"traceutil/trace.go:171","msg":"trace[1605919738] linearizableReadLoop","detail":"{readStateIndex:24979; appliedIndex:24978; }","duration":"114.749882ms","start":"2026-06-09T17:57:28.510469Z","end":"2026-06-09T17:57:28.625219Z","steps":["trace[1605919738] 'read index received' (duration: 114.489245ms)","trace[1605919738] 'applied index is now lower than readState.Index' (duration: 259.797µs)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:57:28.625382Z","caller":"traceutil/trace.go:171","msg":"trace[148966518] transaction","detail":"{read_only:false; response_revision:24189; number_of_response:1; }","duration":"126.776303ms","start":"2026-06-09T17:57:28.498548Z","end":"2026-06-09T17:57:28.625324Z","steps":["trace[148966518] 'process raft request' (duration: 126.474556ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:57:28.625515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.023948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-06-09T17:57:28.625583Z","caller":"traceutil/trace.go:171","msg":"trace[453375498] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:24189; }","duration":"115.153001ms","start":"2026-06-09T17:57:28.510412Z","end":"2026-06-09T17:57:28.625565Z","steps":["trace[453375498] 'agreement among raft nodes before linearized reading' (duration: 114.901985ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:57:28.927091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.146952ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-09T17:57:28.927446Z","caller":"traceutil/trace.go:171","msg":"trace[1696108681] transaction","detail":"{read_only:false; response_revision:24190; number_of_response:1; }","duration":"236.293838ms","start":"2026-06-09T17:57:28.69113Z","end":"2026-06-09T17:57:28.927423Z","steps":["trace[1696108681] 'process raft request' (duration: 122.734025ms)","trace[1696108681] 'compare' (duration: 112.855425ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:57:29.276772Z","caller":"traceutil/trace.go:171","msg":"trace[680682855] transaction","detail":"{read_only:false; response_revision:24191; number_of_response:1; }","duration":"337.244984ms","start":"2026-06-09T17:57:28.939496Z","end":"2026-06-09T17:57:29.276741Z","steps":["trace[680682855] 'process raft request' (duration: 337.047709ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:57:29.276957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:57:28.939467Z","time spent":"337.396148ms","remote":"127.0.0.1:57168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T17:57:29.302968Z","caller":"traceutil/trace.go:171","msg":"trace[1356083740] linearizableReadLoop","detail":"{readStateIndex:24983; appliedIndex:24980; }","duration":"188.646354ms","start":"2026-06-09T17:57:29.114301Z","end":"2026-06-09T17:57:29.302947Z","steps":["trace[1356083740] 'read index received' (duration: 162.252763ms)","trace[1356083740] 'applied index is now lower than readState.Index' (duration: 26.392361ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:57:29.303009Z","caller":"traceutil/trace.go:171","msg":"trace[1543007038] transaction","detail":"{read_only:false; response_revision:24192; number_of_response:1; }","duration":"359.161157ms","start":"2026-06-09T17:57:28.943826Z","end":"2026-06-09T17:57:29.302987Z","steps":["trace[1543007038] 'process raft request' (duration: 358.915781ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:57:29.30307Z","caller":"traceutil/trace.go:171","msg":"trace[426491167] transaction","detail":"{read_only:false; response_revision:24193; number_of_response:1; }","duration":"357.778302ms","start":"2026-06-09T17:57:28.945258Z","end":"2026-06-09T17:57:29.303037Z","steps":["trace[426491167] 'process raft request' (duration: 357.647289ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:57:29.30312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:57:28.943799Z","time spent":"359.258778ms","remote":"127.0.0.1:57168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T17:57:29.303167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.843898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-09T17:57:29.303206Z","caller":"traceutil/trace.go:171","msg":"trace[1083982006] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24193; }","duration":"188.92723ms","start":"2026-06-09T17:57:29.114269Z","end":"2026-06-09T17:57:29.303197Z","steps":["trace[1083982006] 'agreement among raft nodes before linearized reading' (duration: 188.797507ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:57:29.303325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:57:28.945245Z","time spent":"357.889275ms","remote":"127.0.0.1:57168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T17:57:29.820989Z","caller":"traceutil/trace.go:171","msg":"trace[2073950352] transaction","detail":"{read_only:false; response_revision:24197; number_of_response:1; }","duration":"263.563619ms","start":"2026-06-09T17:57:29.557404Z","end":"2026-06-09T17:57:29.820968Z","steps":["trace[2073950352] 'process raft request' (duration: 263.336783ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:57:29.821195Z","caller":"traceutil/trace.go:171","msg":"trace[440623541] transaction","detail":"{read_only:false; response_revision:24198; number_of_response:1; }","duration":"262.981235ms","start":"2026-06-09T17:57:29.558203Z","end":"2026-06-09T17:57:29.821184Z","steps":["trace[440623541] 'process raft request' (duration: 262.722728ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:57:29.821335Z","caller":"traceutil/trace.go:171","msg":"trace[1554697987] linearizableReadLoop","detail":"{readStateIndex:24989; appliedIndex:24986; }","duration":"229.104313ms","start":"2026-06-09T17:57:29.592217Z","end":"2026-06-09T17:57:29.821322Z","steps":["trace[1554697987] 'read index received' (duration: 39.282282ms)","trace[1554697987] 'applied index is now lower than readState.Index' (duration: 189.820991ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:57:29.821401Z","caller":"traceutil/trace.go:171","msg":"trace[122957808] transaction","detail":"{read_only:false; response_revision:24199; number_of_response:1; }","duration":"262.963564ms","start":"2026-06-09T17:57:29.558429Z","end":"2026-06-09T17:57:29.821393Z","steps":["trace[122957808] 'process raft request' (duration: 262.716138ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:57:29.821507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.281208ms","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-06-09T17:57:29.822487Z","caller":"traceutil/trace.go:171","msg":"trace[1863720799] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24199; }","duration":"230.285911ms","start":"2026-06-09T17:57:29.59217Z","end":"2026-06-09T17:57:29.822456Z","steps":["trace[1863720799] 'agreement among raft nodes before linearized reading' (duration: 229.215715ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:57:30.036293Z","caller":"traceutil/trace.go:171","msg":"trace[899585820] transaction","detail":"{read_only:false; response_revision:24200; number_of_response:1; }","duration":"210.973354ms","start":"2026-06-09T17:57:29.825285Z","end":"2026-06-09T17:57:30.036258Z","steps":["trace[899585820] 'process raft request' (duration: 135.962977ms)","trace[899585820] 'compare' (duration: 74.663079ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:57:30.237065Z","caller":"traceutil/trace.go:171","msg":"trace[1721533998] transaction","detail":"{read_only:false; response_revision:24201; number_of_response:1; }","duration":"411.634997ms","start":"2026-06-09T17:57:29.825389Z","end":"2026-06-09T17:57:30.237024Z","steps":["trace[1721533998] 'process raft request' (duration: 396.141142ms)","trace[1721533998] 'compare' (duration: 14.907321ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:57:30.23729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:57:29.825359Z","time spent":"411.795332ms","remote":"127.0.0.1:57168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T17:57:30.237241Z","caller":"traceutil/trace.go:171","msg":"trace[694034153] transaction","detail":"{read_only:false; response_revision:24202; number_of_response:1; }","duration":"411.195377ms","start":"2026-06-09T17:57:29.826015Z","end":"2026-06-09T17:57:30.23721Z","steps":["trace[694034153] 'process raft request' (duration: 410.855349ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:57:30.237737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:57:29.825997Z","time spent":"411.653358ms","remote":"127.0.0.1:57168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T17:57:30.381338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.366659ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-06-09T17:57:30.381418Z","caller":"traceutil/trace.go:171","msg":"trace[1082664757] linearizableReadLoop","detail":"{readStateIndex:24993; appliedIndex:24989; }","duration":"515.971077ms","start":"2026-06-09T17:57:29.865432Z","end":"2026-06-09T17:57:30.381403Z","steps":["trace[1082664757] 'read index received' (duration: 95.726722ms)","trace[1082664757] 'applied index is now lower than readState.Index' (duration: 420.243185ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:57:30.381546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.312194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-09T17:57:30.381569Z","caller":"traceutil/trace.go:171","msg":"trace[1409385618] range","detail":"{range_begin:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/; range_end:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0; response_count:0; response_revision:24202; }","duration":"497.55538ms","start":"2026-06-09T17:57:29.884004Z","end":"2026-06-09T17:57:30.38156Z","steps":["trace[1409385618] 'agreement among raft nodes before linearized reading' (duration: 497.486938ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:57:30.381594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:57:29.883975Z","time spent":"497.611062ms","remote":"127.0.0.1:47742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":126,"response count":0,"response size":29,"request content":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0\" count_only:true "} {"level":"warn","ts":"2026-06-09T17:57:30.381653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.999733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-09T17:57:30.381658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"516.199582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-09T17:57:30.381741Z","caller":"traceutil/trace.go:171","msg":"trace[145118851] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24202; }","duration":"267.125506ms","start":"2026-06-09T17:57:30.114592Z","end":"2026-06-09T17:57:30.381718Z","steps":["trace[145118851] 'agreement among raft nodes before linearized reading' (duration: 266.972442ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:57:30.381754Z","caller":"traceutil/trace.go:171","msg":"trace[903228501] range","detail":"{range_begin:/registry/cert-manager.io/certificates/; range_end:/registry/cert-manager.io/certificates0; response_count:0; response_revision:24202; }","duration":"516.350256ms","start":"2026-06-09T17:57:29.865382Z","end":"2026-06-09T17:57:30.381732Z","steps":["trace[903228501] 'agreement among raft nodes before linearized reading' (duration: 516.124381ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:57:30.381815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:57:29.865358Z","time spent":"516.446058ms","remote":"127.0.0.1:43538","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":37,"response size":31,"request content":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" count_only:true "} {"level":"info","ts":"2026-06-09T17:57:31.234413Z","caller":"traceutil/trace.go:171","msg":"trace[1853777068] transaction","detail":"{read_only:false; response_revision:24204; number_of_response:1; }","duration":"117.093158ms","start":"2026-06-09T17:57:31.117292Z","end":"2026-06-09T17:57:31.234385Z","steps":["trace[1853777068] 'process raft request' (duration: 116.885093ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:57:31.630759Z","caller":"traceutil/trace.go:171","msg":"trace[184314999] transaction","detail":"{read_only:false; response_revision:24208; number_of_response:1; }","duration":"249.814375ms","start":"2026-06-09T17:57:31.380918Z","end":"2026-06-09T17:57:31.630732Z","steps":["trace[184314999] 'process raft request' (duration: 249.585279ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:57:31.891315Z","caller":"traceutil/trace.go:171","msg":"trace[815815107] transaction","detail":"{read_only:false; response_revision:24210; number_of_response:1; }","duration":"254.599611ms","start":"2026-06-09T17:57:31.636692Z","end":"2026-06-09T17:57:31.891291Z","steps":["trace[815815107] 'process raft request' (duration: 254.439187ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:57:31.891311Z","caller":"traceutil/trace.go:171","msg":"trace[835600421] transaction","detail":"{read_only:false; response_revision:24209; number_of_response:1; }","duration":"510.31553ms","start":"2026-06-09T17:57:31.380951Z","end":"2026-06-09T17:57:31.891266Z","steps":["trace[835600421] 'process raft request' (duration: 474.697826ms)","trace[835600421] 'compare' (duration: 34.6418ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:57:31.891702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:57:31.380925Z","time spent":"510.652977ms","remote":"127.0.0.1:57168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T17:57:31.891318Z","caller":"traceutil/trace.go:171","msg":"trace[1419602509] linearizableReadLoop","detail":"{readStateIndex:25000; appliedIndex:24998; }","duration":"459.746864ms","start":"2026-06-09T17:57:31.431535Z","end":"2026-06-09T17:57:31.891282Z","steps":["trace[1419602509] 'read index received' (duration: 198.993773ms)","trace[1419602509] 'applied index is now lower than readState.Index' (duration: 260.749891ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:57:31.891814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.254116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-magnum-server-0\" ","response":"range_response_count:1 size:1860"} {"level":"warn","ts":"2026-06-09T17:57:31.892206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.383871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" ","response":"range_response_count:1 size:418384"} {"level":"info","ts":"2026-06-09T17:57:31.892278Z","caller":"traceutil/trace.go:171","msg":"trace[1967591712] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance; range_end:; response_count:1; response_revision:24212; }","duration":"345.487204ms","start":"2026-06-09T17:57:31.54677Z","end":"2026-06-09T17:57:31.892258Z","steps":["trace[1967591712] 'agreement among raft nodes before linearized reading' (duration: 345.215617ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:57:31.892339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:57:31.546751Z","time spent":"345.575045ms","remote":"127.0.0.1:43438","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":418407,"request content":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" "} {"level":"info","ts":"2026-06-09T17:57:31.892601Z","caller":"traceutil/trace.go:171","msg":"trace[617454326] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-magnum-server-0; range_end:; response_count:1; response_revision:24211; }","duration":"461.033735ms","start":"2026-06-09T17:57:31.431495Z","end":"2026-06-09T17:57:31.892529Z","steps":["trace[617454326] 'agreement among raft nodes before linearized reading' (duration: 459.942999ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:57:31.892773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:57:31.431478Z","time spent":"461.2373ms","remote":"127.0.0.1:57050","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":1,"response size":1883,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-magnum-server-0\" "} {"level":"info","ts":"2026-06-09T17:57:32.030712Z","caller":"traceutil/trace.go:171","msg":"trace[321920510] transaction","detail":"{read_only:false; response_revision:24213; number_of_response:1; }","duration":"133.392163ms","start":"2026-06-09T17:57:31.897295Z","end":"2026-06-09T17:57:32.030687Z","steps":["trace[321920510] 'process raft request' (duration: 133.238969ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:57:32.359805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.376005ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-09T17:57:32.35993Z","caller":"traceutil/trace.go:171","msg":"trace[801700941] linearizableReadLoop","detail":"{readStateIndex:25005; appliedIndex:25004; }","duration":"243.962803ms","start":"2026-06-09T17:57:32.115951Z","end":"2026-06-09T17:57:32.359914Z","steps":["trace[801700941] 'read index received' (duration: 67.383183ms)","trace[801700941] 'applied index is now lower than readState.Index' (duration: 176.57794ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:57:32.360001Z","caller":"traceutil/trace.go:171","msg":"trace[1339517125] transaction","detail":"{read_only:false; response_revision:24214; number_of_response:1; }","duration":"316.665845ms","start":"2026-06-09T17:57:32.043285Z","end":"2026-06-09T17:57:32.35995Z","steps":["trace[1339517125] 'process raft request' (duration: 140.057344ms)","trace[1339517125] 'compare' (duration: 175.693469ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:57:32.36008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.126107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-09T17:57:32.360122Z","caller":"traceutil/trace.go:171","msg":"trace[1622248644] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24214; }","duration":"244.190108ms","start":"2026-06-09T17:57:32.115908Z","end":"2026-06-09T17:57:32.360098Z","steps":["trace[1622248644] 'agreement among raft nodes before linearized reading' (duration: 244.113916ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:57:32.360227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:57:32.043244Z","time spent":"316.867879ms","remote":"127.0.0.1:57168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T17:57:32.523458Z","caller":"traceutil/trace.go:171","msg":"trace[276738072] linearizableReadLoop","detail":"{readStateIndex:25006; appliedIndex:25005; }","duration":"163.457192ms","start":"2026-06-09T17:57:32.359983Z","end":"2026-06-09T17:57:32.52344Z","steps":["trace[276738072] 'read index received' (duration: 152.993629ms)","trace[276738072] 'applied index is now lower than readState.Index' (duration: 10.462703ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:57:32.523481Z","caller":"traceutil/trace.go:171","msg":"trace[1288051500] transaction","detail":"{read_only:false; response_revision:24215; number_of_response:1; }","duration":"235.078608ms","start":"2026-06-09T17:57:32.288376Z","end":"2026-06-09T17:57:32.523455Z","steps":["trace[1288051500] 'process raft request' (duration: 224.932203ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:57:32.523714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.494412ms","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-06-09T17:57:32.523752Z","caller":"traceutil/trace.go:171","msg":"trace[506030956] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24215; }","duration":"281.575774ms","start":"2026-06-09T17:57:32.242164Z","end":"2026-06-09T17:57:32.52374Z","steps":["trace[506030956] 'agreement among raft nodes before linearized reading' (duration: 281.472102ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:57:32.52362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.347889ms","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-06-09T17:57:32.5238Z","caller":"traceutil/trace.go:171","msg":"trace[1869568543] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24215; }","duration":"281.585004ms","start":"2026-06-09T17:57:32.242207Z","end":"2026-06-09T17:57:32.523792Z","steps":["trace[1869568543] 'agreement among raft nodes before linearized reading' (duration: 281.327658ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:57:32.685866Z","caller":"traceutil/trace.go:171","msg":"trace[1479531978] linearizableReadLoop","detail":"{readStateIndex:25008; appliedIndex:25006; }","duration":"126.461275ms","start":"2026-06-09T17:57:32.559369Z","end":"2026-06-09T17:57:32.685831Z","steps":["trace[1479531978] 'read index received' (duration: 56.640433ms)","trace[1479531978] 'applied index is now lower than readState.Index' (duration: 69.819642ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:57:32.686087Z","caller":"traceutil/trace.go:171","msg":"trace[386441027] transaction","detail":"{read_only:false; response_revision:24217; number_of_response:1; }","duration":"158.772017ms","start":"2026-06-09T17:57:32.527277Z","end":"2026-06-09T17:57:32.686049Z","steps":["trace[386441027] 'process raft request' (duration: 157.307462ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:57:32.686222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.832214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-06-09T17:57:32.686267Z","caller":"traceutil/trace.go:171","msg":"trace[926087417] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24217; }","duration":"126.948856ms","start":"2026-06-09T17:57:32.559307Z","end":"2026-06-09T17:57:32.686256Z","steps":["trace[926087417] 'agreement among raft nodes before linearized reading' (duration: 126.620348ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:57:33.005458Z","caller":"traceutil/trace.go:171","msg":"trace[1870175845] linearizableReadLoop","detail":"{readStateIndex:25009; appliedIndex:25008; }","duration":"251.070985ms","start":"2026-06-09T17:57:32.754369Z","end":"2026-06-09T17:57:33.00544Z","steps":["trace[1870175845] 'read index received' (duration: 250.827549ms)","trace[1870175845] 'applied index is now lower than readState.Index' (duration: 242.716µs)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:57:33.005563Z","caller":"traceutil/trace.go:171","msg":"trace[62762270] transaction","detail":"{read_only:false; response_revision:24218; number_of_response:1; }","duration":"286.794061ms","start":"2026-06-09T17:57:32.718741Z","end":"2026-06-09T17:57:33.005535Z","steps":["trace[62762270] 'process raft request' (duration: 286.534065ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:57:33.00566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.27839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-09T17:57:33.005711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.761162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-09T17:57:33.005745Z","caller":"traceutil/trace.go:171","msg":"trace[218347102] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:24218; }","duration":"251.411863ms","start":"2026-06-09T17:57:32.754311Z","end":"2026-06-09T17:57:33.005723Z","steps":["trace[218347102] 'agreement among raft nodes before linearized reading' (duration: 251.246589ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:57:33.005802Z","caller":"traceutil/trace.go:171","msg":"trace[2077286996] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters0; response_count:0; response_revision:24218; }","duration":"112.912806ms","start":"2026-06-09T17:57:32.892869Z","end":"2026-06-09T17:57:33.005782Z","steps":["trace[2077286996] 'agreement among raft nodes before linearized reading' (duration: 112.751772ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:57:33.006757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.1222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18367"} {"level":"info","ts":"2026-06-09T17:57:33.006842Z","caller":"traceutil/trace.go:171","msg":"trace[1571684440] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:24218; }","duration":"175.236028ms","start":"2026-06-09T17:57:32.83155Z","end":"2026-06-09T17:57:33.006787Z","steps":["trace[1571684440] 'agreement among raft nodes before linearized reading' (duration: 174.024518ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:57:37.373303Z","caller":"traceutil/trace.go:171","msg":"trace[279765104] linearizableReadLoop","detail":"{readStateIndex:25039; appliedIndex:25038; }","duration":"256.678711ms","start":"2026-06-09T17:57:37.1166Z","end":"2026-06-09T17:57:37.373279Z","steps":["trace[279765104] 'read index received' (duration: 256.482246ms)","trace[279765104] 'applied index is now lower than readState.Index' (duration: 195.295µs)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:57:37.373373Z","caller":"traceutil/trace.go:171","msg":"trace[123263933] transaction","detail":"{read_only:false; response_revision:24247; number_of_response:1; }","duration":"353.817916ms","start":"2026-06-09T17:57:37.01952Z","end":"2026-06-09T17:57:37.373338Z","steps":["trace[123263933] 'process raft request' (duration: 353.544189ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:57:37.373472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.848686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-09T17:57:37.37353Z","caller":"traceutil/trace.go:171","msg":"trace[1682284649] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24247; }","duration":"256.963339ms","start":"2026-06-09T17:57:37.116556Z","end":"2026-06-09T17:57:37.373519Z","steps":["trace[1682284649] 'agreement among raft nodes before linearized reading' (duration: 256.851746ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:57:37.373536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:57:37.019496Z","time spent":"353.95003ms","remote":"127.0.0.1:57168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T17:57:37.585793Z","caller":"traceutil/trace.go:171","msg":"trace[2051704268] linearizableReadLoop","detail":"{readStateIndex:25040; appliedIndex:25039; }","duration":"191.646035ms","start":"2026-06-09T17:57:37.394106Z","end":"2026-06-09T17:57:37.585752Z","steps":["trace[2051704268] 'read index received' (duration: 151.375849ms)","trace[2051704268] 'applied index is now lower than readState.Index' (duration: 40.269166ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:57:37.586111Z","caller":"traceutil/trace.go:171","msg":"trace[960748991] transaction","detail":"{read_only:false; response_revision:24248; number_of_response:1; }","duration":"192.258359ms","start":"2026-06-09T17:57:37.393834Z","end":"2026-06-09T17:57:37.586092Z","steps":["trace[960748991] 'process raft request' (duration: 151.626025ms)","trace[960748991] 'compare' (duration: 40.156933ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:57:37.586511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.389273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinesets/\" range_end:\"/registry/cluster.x-k8s.io/machinesets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-09T17:57:37.58667Z","caller":"traceutil/trace.go:171","msg":"trace[1206469713] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinesets/; range_end:/registry/cluster.x-k8s.io/machinesets0; response_count:0; response_revision:24248; }","duration":"192.467525ms","start":"2026-06-09T17:57:37.394077Z","end":"2026-06-09T17:57:37.586545Z","steps":["trace[1206469713] 'agreement among raft nodes before linearized reading' (duration: 191.788758ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:57:37.848302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.003103ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-09T17:57:37.848537Z","caller":"traceutil/trace.go:171","msg":"trace[1942511233] transaction","detail":"{read_only:false; response_revision:24249; number_of_response:1; }","duration":"453.780199ms","start":"2026-06-09T17:57:37.394696Z","end":"2026-06-09T17:57:37.848476Z","steps":["trace[1942511233] 'process raft request' (duration: 339.505659ms)","trace[1942511233] 'compare' (duration: 113.803589ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:57:37.848613Z","caller":"traceutil/trace.go:171","msg":"trace[632677072] linearizableReadLoop","detail":"{readStateIndex:25042; appliedIndex:25040; }","duration":"195.681073ms","start":"2026-06-09T17:57:37.652906Z","end":"2026-06-09T17:57:37.848587Z","steps":["trace[632677072] 'read index received' (duration: 81.330051ms)","trace[632677072] 'applied index is now lower than readState.Index' (duration: 114.349242ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:57:37.848639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:57:37.394687Z","time spent":"453.906922ms","remote":"127.0.0.1:57168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T17:57:37.848651Z","caller":"traceutil/trace.go:171","msg":"trace[1204564398] transaction","detail":"{read_only:false; response_revision:24250; number_of_response:1; }","duration":"453.736367ms","start":"2026-06-09T17:57:37.394875Z","end":"2026-06-09T17:57:37.848612Z","steps":["trace[1204564398] 'process raft request' (duration: 453.529812ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:57:37.848815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.890578ms","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-06-09T17:57:37.848856Z","caller":"traceutil/trace.go:171","msg":"trace[1183732444] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24250; }","duration":"195.98572ms","start":"2026-06-09T17:57:37.65286Z","end":"2026-06-09T17:57:37.848846Z","steps":["trace[1183732444] 'agreement among raft nodes before linearized reading' (duration: 195.819786ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:57:37.848847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:57:37.394868Z","time spent":"453.87339ms","remote":"127.0.0.1:57168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T17:57:38.203126Z","caller":"traceutil/trace.go:171","msg":"trace[882623812] linearizableReadLoop","detail":"{readStateIndex:25045; appliedIndex:25042; }","duration":"283.380168ms","start":"2026-06-09T17:57:37.919728Z","end":"2026-06-09T17:57:38.203108Z","steps":["trace[882623812] 'read index received' (duration: 281.312117ms)","trace[882623812] 'applied index is now lower than readState.Index' (duration: 2.067411ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:57:38.203229Z","caller":"traceutil/trace.go:171","msg":"trace[1340061342] transaction","detail":"{read_only:false; response_revision:24253; number_of_response:1; }","duration":"286.773712ms","start":"2026-06-09T17:57:37.916447Z","end":"2026-06-09T17:57:38.203221Z","steps":["trace[1340061342] 'process raft request' (duration: 286.568447ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:57:38.203383Z","caller":"traceutil/trace.go:171","msg":"trace[120475636] transaction","detail":"{read_only:false; response_revision:24254; number_of_response:1; }","duration":"281.857372ms","start":"2026-06-09T17:57:37.921498Z","end":"2026-06-09T17:57:38.203356Z","steps":["trace[120475636] 'process raft request' (duration: 281.553595ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:57:38.203473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.958542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-06-09T17:57:38.203506Z","caller":"traceutil/trace.go:171","msg":"trace[1296866637] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24255; }","duration":"173.043084ms","start":"2026-06-09T17:57:38.030457Z","end":"2026-06-09T17:57:38.2035Z","steps":["trace[1296866637] 'agreement among raft nodes before linearized reading' (duration: 172.86331ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:57:38.20355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.245446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"warn","ts":"2026-06-09T17:57:38.203576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.819969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-06-09T17:57:38.203628Z","caller":"traceutil/trace.go:171","msg":"trace[1399717677] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24255; }","duration":"151.353799ms","start":"2026-06-09T17:57:38.052256Z","end":"2026-06-09T17:57:38.203609Z","steps":["trace[1399717677] 'agreement among raft nodes before linearized reading' (duration: 151.187655ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:57:38.203063Z","caller":"traceutil/trace.go:171","msg":"trace[727364933] transaction","detail":"{read_only:false; response_revision:24251; number_of_response:1; }","duration":"350.317041ms","start":"2026-06-09T17:57:37.852719Z","end":"2026-06-09T17:57:38.203036Z","steps":["trace[727364933] 'process raft request' (duration: 348.305812ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:57:38.203675Z","caller":"traceutil/trace.go:171","msg":"trace[1707374583] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24255; }","duration":"283.967563ms","start":"2026-06-09T17:57:37.919681Z","end":"2026-06-09T17:57:38.203649Z","steps":["trace[1707374583] 'agreement among raft nodes before linearized reading' (duration: 283.608374ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:57:38.20399Z","caller":"traceutil/trace.go:171","msg":"trace[918293431] transaction","detail":"{read_only:false; response_revision:24252; number_of_response:1; }","duration":"289.122938ms","start":"2026-06-09T17:57:37.914845Z","end":"2026-06-09T17:57:38.203968Z","steps":["trace[918293431] 'process raft request' (duration: 288.081983ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:57:38.204081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:57:37.852696Z","time spent":"350.994637ms","remote":"127.0.0.1:57168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T17:57:49.060232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.589705ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-09T17:57:49.060341Z","caller":"traceutil/trace.go:171","msg":"trace[601459493] linearizableReadLoop","detail":"{readStateIndex:25188; appliedIndex:25187; }","duration":"251.591007ms","start":"2026-06-09T17:57:48.808729Z","end":"2026-06-09T17:57:49.06032Z","steps":["trace[601459493] 'read index received' (duration: 75.796337ms)","trace[601459493] 'applied index is now lower than readState.Index' (duration: 175.78974ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:57:49.060476Z","caller":"traceutil/trace.go:171","msg":"trace[1288805662] transaction","detail":"{read_only:false; response_revision:24393; number_of_response:1; }","duration":"302.797739ms","start":"2026-06-09T17:57:48.757638Z","end":"2026-06-09T17:57:49.060436Z","steps":["trace[1288805662] 'process raft request' (duration: 126.895556ms)","trace[1288805662] 'compare' (duration: 175.136574ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:57:49.060609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:57:48.757622Z","time spent":"302.913262ms","remote":"127.0.0.1:57168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T17:57:49.060693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.17745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-09T17:57:49.060776Z","caller":"traceutil/trace.go:171","msg":"trace[665093688] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:24393; }","duration":"202.305244ms","start":"2026-06-09T17:57:48.85845Z","end":"2026-06-09T17:57:49.060755Z","steps":["trace[665093688] 'agreement among raft nodes before linearized reading' (duration: 202.16047ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:57:49.060606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.868734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b4aebe1d-bd92-45ea-b413-2512b524c987\" ","response":"range_response_count:1 size:3644"} {"level":"info","ts":"2026-06-09T17:57:49.061243Z","caller":"traceutil/trace.go:171","msg":"trace[1238397697] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-b4aebe1d-bd92-45ea-b413-2512b524c987; range_end:; response_count:1; response_revision:24393; }","duration":"252.547211ms","start":"2026-06-09T17:57:48.808675Z","end":"2026-06-09T17:57:49.061222Z","steps":["trace[1238397697] 'agreement among raft nodes before linearized reading' (duration: 251.767112ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:57:49.326057Z","caller":"traceutil/trace.go:171","msg":"trace[2030829253] linearizableReadLoop","detail":"{readStateIndex:25189; appliedIndex:25188; }","duration":"215.412432ms","start":"2026-06-09T17:57:49.110626Z","end":"2026-06-09T17:57:49.326039Z","steps":["trace[2030829253] 'read index received' (duration: 189.699309ms)","trace[2030829253] 'applied index is now lower than readState.Index' (duration: 25.712323ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:57:49.326141Z","caller":"traceutil/trace.go:171","msg":"trace[863988937] transaction","detail":"{read_only:false; response_revision:24394; number_of_response:1; }","duration":"258.829493ms","start":"2026-06-09T17:57:49.067302Z","end":"2026-06-09T17:57:49.326132Z","steps":["trace[863988937] 'process raft request' (duration: 233.041418ms)","trace[863988937] 'compare' (duration: 25.617701ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:57:49.326249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.352077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-09T17:57:49.326335Z","caller":"traceutil/trace.go:171","msg":"trace[1544647311] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24394; }","duration":"212.48972ms","start":"2026-06-09T17:57:49.113828Z","end":"2026-06-09T17:57:49.326318Z","steps":["trace[1544647311] 'agreement among raft nodes before linearized reading' (duration: 212.343127ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:57:49.326337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.683038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-09T17:57:49.326405Z","caller":"traceutil/trace.go:171","msg":"trace[1056956560] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:24394; }","duration":"215.809231ms","start":"2026-06-09T17:57:49.110577Z","end":"2026-06-09T17:57:49.326386Z","steps":["trace[1056956560] 'agreement among raft nodes before linearized reading' (duration: 215.653697ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:57:49.657095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.508292ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-09T17:57:49.657202Z","caller":"traceutil/trace.go:171","msg":"trace[924851535] transaction","detail":"{read_only:false; response_revision:24395; number_of_response:1; }","duration":"281.823741ms","start":"2026-06-09T17:57:49.375363Z","end":"2026-06-09T17:57:49.657187Z","steps":["trace[924851535] 'process raft request' (duration: 151.135314ms)","trace[924851535] 'compare' (duration: 130.332068ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:57:49.83357Z","caller":"traceutil/trace.go:171","msg":"trace[212395964] linearizableReadLoop","detail":"{readStateIndex:25191; appliedIndex:25190; }","duration":"213.043263ms","start":"2026-06-09T17:57:49.620504Z","end":"2026-06-09T17:57:49.833547Z","steps":["trace[212395964] 'read index received' (duration: 212.976192ms)","trace[212395964] 'applied index is now lower than readState.Index' (duration: 64.751µs)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:57:49.833649Z","caller":"traceutil/trace.go:171","msg":"trace[18299910] transaction","detail":"{read_only:false; response_revision:24396; number_of_response:1; }","duration":"423.976386ms","start":"2026-06-09T17:57:49.409658Z","end":"2026-06-09T17:57:49.833634Z","steps":["trace[18299910] 'process raft request' (duration: 423.742091ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:57:49.833744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:57:49.40963Z","time spent":"424.054548ms","remote":"127.0.0.1:57168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T17:57:49.833741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.230487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-09T17:57:49.833784Z","caller":"traceutil/trace.go:171","msg":"trace[1087031187] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:24396; }","duration":"213.31339ms","start":"2026-06-09T17:57:49.620462Z","end":"2026-06-09T17:57:49.833776Z","steps":["trace[1087031187] 'agreement among raft nodes before linearized reading' (duration: 213.192877ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:57:50.041904Z","caller":"traceutil/trace.go:171","msg":"trace[1560061465] transaction","detail":"{read_only:false; response_revision:24399; number_of_response:1; }","duration":"152.519018ms","start":"2026-06-09T17:57:49.889369Z","end":"2026-06-09T17:57:50.041888Z","steps":["trace[1560061465] 'process raft request' (duration: 152.324013ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:57:50.04192Z","caller":"traceutil/trace.go:171","msg":"trace[1927356665] transaction","detail":"{read_only:false; response_revision:24400; number_of_response:1; }","duration":"150.486008ms","start":"2026-06-09T17:57:49.891413Z","end":"2026-06-09T17:57:50.041899Z","steps":["trace[1927356665] 'process raft request' (duration: 150.327954ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:57:50.041945Z","caller":"traceutil/trace.go:171","msg":"trace[1356655728] transaction","detail":"{read_only:false; response_revision:24397; number_of_response:1; }","duration":"420.055051ms","start":"2026-06-09T17:57:49.621851Z","end":"2026-06-09T17:57:50.041906Z","steps":["trace[1356655728] 'process raft request' (duration: 410.703694ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:57:50.041969Z","caller":"traceutil/trace.go:171","msg":"trace[1855803973] transaction","detail":"{read_only:false; response_revision:24398; number_of_response:1; }","duration":"283.186154ms","start":"2026-06-09T17:57:49.758752Z","end":"2026-06-09T17:57:50.041938Z","steps":["trace[1855803973] 'process raft request' (duration: 282.831636ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:57:50.041905Z","caller":"traceutil/trace.go:171","msg":"trace[1406179235] linearizableReadLoop","detail":"{readStateIndex:25193; appliedIndex:25191; }","duration":"208.247119ms","start":"2026-06-09T17:57:49.833632Z","end":"2026-06-09T17:57:50.041879Z","steps":["trace[1406179235] 'read index received' (duration: 198.912122ms)","trace[1406179235] 'applied index is now lower than readState.Index' (duration: 9.329177ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:57:50.042149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:57:49.621834Z","time spent":"420.199234ms","remote":"127.0.0.1:57168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T17:57:50.171365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.57703ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"warn","ts":"2026-06-09T17:57:50.171832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"484.890373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b4aebe1d-bd92-45ea-b413-2512b524c987\" ","response":"range_response_count:1 size:3758"} {"level":"info","ts":"2026-06-09T17:57:50.171874Z","caller":"traceutil/trace.go:171","msg":"trace[863413396] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-b4aebe1d-bd92-45ea-b413-2512b524c987; range_end:; response_count:1; response_revision:24400; }","duration":"484.980885ms","start":"2026-06-09T17:57:49.686877Z","end":"2026-06-09T17:57:50.171858Z","steps":["trace[863413396] 'agreement among raft nodes before linearized reading' (duration: 355.064986ms)","trace[863413396] 'range keys from in-memory index tree' (duration: 129.770204ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:57:50.17191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:57:49.686856Z","time spent":"485.045457ms","remote":"127.0.0.1:57078","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3781,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b4aebe1d-bd92-45ea-b413-2512b524c987\" "} {"level":"warn","ts":"2026-06-09T17:57:50.552965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.045194ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-09T17:57:50.553215Z","caller":"traceutil/trace.go:171","msg":"trace[1584259879] linearizableReadLoop","detail":"{readStateIndex:25199; appliedIndex:25196; }","duration":"289.120047ms","start":"2026-06-09T17:57:50.264074Z","end":"2026-06-09T17:57:50.553194Z","steps":["trace[1584259879] 'read index received' (duration: 29.69353ms)","trace[1584259879] 'applied index is now lower than readState.Index' (duration: 259.425257ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:57:50.55332Z","caller":"traceutil/trace.go:171","msg":"trace[593651489] transaction","detail":"{read_only:false; response_revision:24402; number_of_response:1; }","duration":"303.24516ms","start":"2026-06-09T17:57:50.250061Z","end":"2026-06-09T17:57:50.553307Z","steps":["trace[593651489] 'process raft request' (duration: 134.775047ms)","trace[593651489] 'compare' (duration: 167.850688ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:57:50.55339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:57:50.250035Z","time spent":"303.319171ms","remote":"127.0.0.1:57168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T17:57:50.553588Z","caller":"traceutil/trace.go:171","msg":"trace[1055449163] transaction","detail":"{read_only:false; response_revision:24403; number_of_response:1; }","duration":"301.337153ms","start":"2026-06-09T17:57:50.252225Z","end":"2026-06-09T17:57:50.553562Z","steps":["trace[1055449163] 'process raft request' (duration: 300.844901ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:57:50.553668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.134739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-06-09T17:57:50.553699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:57:50.252215Z","time spent":"301.423426ms","remote":"127.0.0.1:57168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T17:57:50.553716Z","caller":"traceutil/trace.go:171","msg":"trace[1334120764] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24403; }","duration":"273.217761ms","start":"2026-06-09T17:57:50.280483Z","end":"2026-06-09T17:57:50.553701Z","steps":["trace[1334120764] 'agreement among raft nodes before linearized reading' (duration: 273.038687ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:57:50.553739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.518409ms","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-06-09T17:57:50.553833Z","caller":"traceutil/trace.go:171","msg":"trace[2120976093] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24403; }","duration":"271.637513ms","start":"2026-06-09T17:57:50.282166Z","end":"2026-06-09T17:57:50.553803Z","steps":["trace[2120976093] 'agreement among raft nodes before linearized reading' (duration: 271.395377ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:57:50.554021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.934208ms","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-06-09T17:57:50.554129Z","caller":"traceutil/trace.go:171","msg":"trace[1669593876] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24403; }","duration":"290.052451ms","start":"2026-06-09T17:57:50.264059Z","end":"2026-06-09T17:57:50.554112Z","steps":["trace[1669593876] 'agreement among raft nodes before linearized reading' (duration: 289.246531ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:57:50.780106Z","caller":"traceutil/trace.go:171","msg":"trace[2021790334] transaction","detail":"{read_only:false; response_revision:24404; number_of_response:1; }","duration":"221.940379ms","start":"2026-06-09T17:57:50.558143Z","end":"2026-06-09T17:57:50.780083Z","steps":["trace[2021790334] 'process raft request' (duration: 143.356464ms)","trace[2021790334] 'compare' (duration: 78.487072ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:57:50.856762Z","caller":"traceutil/trace.go:171","msg":"trace[1680275273] transaction","detail":"{read_only:false; response_revision:24406; number_of_response:1; }","duration":"298.197408ms","start":"2026-06-09T17:57:50.558547Z","end":"2026-06-09T17:57:50.856744Z","steps":["trace[1680275273] 'process raft request' (duration: 298.001133ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:57:50.856934Z","caller":"traceutil/trace.go:171","msg":"trace[905354139] linearizableReadLoop","detail":"{readStateIndex:25203; appliedIndex:25199; }","duration":"199.245479ms","start":"2026-06-09T17:57:50.657657Z","end":"2026-06-09T17:57:50.856902Z","steps":["trace[905354139] 'read index received' (duration: 43.854212ms)","trace[905354139] 'applied index is now lower than readState.Index' (duration: 155.388877ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:57:50.856961Z","caller":"traceutil/trace.go:171","msg":"trace[1550425791] transaction","detail":"{read_only:false; response_revision:24407; number_of_response:1; }","duration":"298.034524ms","start":"2026-06-09T17:57:50.558906Z","end":"2026-06-09T17:57:50.856941Z","steps":["trace[1550425791] 'process raft request' (duration: 297.794298ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:57:50.857145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.435633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/networks/\" range_end:\"/registry/openstack.k-orc.cloud/networks0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-09T17:57:50.857239Z","caller":"traceutil/trace.go:171","msg":"trace[1407188585] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/networks/; range_end:/registry/openstack.k-orc.cloud/networks0; response_count:0; response_revision:24407; }","duration":"199.520245ms","start":"2026-06-09T17:57:50.657651Z","end":"2026-06-09T17:57:50.857171Z","steps":["trace[1407188585] 'agreement among raft nodes before linearized reading' (duration: 199.395802ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:57:50.85723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.104215ms","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-06-09T17:57:50.857407Z","caller":"traceutil/trace.go:171","msg":"trace[1854473956] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24407; }","duration":"161.32034ms","start":"2026-06-09T17:57:50.696067Z","end":"2026-06-09T17:57:50.857387Z","steps":["trace[1854473956] 'agreement among raft nodes before linearized reading' (duration: 161.024543ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:57:50.857329Z","caller":"traceutil/trace.go:171","msg":"trace[242890794] transaction","detail":"{read_only:false; response_revision:24405; number_of_response:1; }","duration":"298.345272ms","start":"2026-06-09T17:57:50.558296Z","end":"2026-06-09T17:57:50.856642Z","steps":["trace[242890794] 'process raft request' (duration: 298.073514ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:57:51.16427Z","caller":"traceutil/trace.go:171","msg":"trace[1723613045] transaction","detail":"{read_only:false; response_revision:24408; number_of_response:1; }","duration":"303.161867ms","start":"2026-06-09T17:57:50.861067Z","end":"2026-06-09T17:57:51.164229Z","steps":["trace[1723613045] 'process raft request' (duration: 276.357168ms)","trace[1723613045] 'compare' (duration: 26.511562ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:57:51.164631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:57:50.861051Z","time spent":"303.430224ms","remote":"127.0.0.1:57168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T17:57:53.391465Z","caller":"traceutil/trace.go:171","msg":"trace[375320696] linearizableReadLoop","detail":"{readStateIndex:25220; appliedIndex:25219; }","duration":"146.188343ms","start":"2026-06-09T17:57:53.245254Z","end":"2026-06-09T17:57:53.391442Z","steps":["trace[375320696] 'read index received' (duration: 146.088761ms)","trace[375320696] 'applied index is now lower than readState.Index' (duration: 98.682µs)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:57:53.391609Z","caller":"traceutil/trace.go:171","msg":"trace[519301047] transaction","detail":"{read_only:false; response_revision:24424; number_of_response:1; }","duration":"171.805184ms","start":"2026-06-09T17:57:53.219771Z","end":"2026-06-09T17:57:53.391576Z","steps":["trace[519301047] 'process raft request' (duration: 171.536117ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:57:53.391697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.094236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b4aebe1d-bd92-45ea-b413-2512b524c987\" ","response":"range_response_count:1 size:3944"} {"level":"info","ts":"2026-06-09T17:57:53.391741Z","caller":"traceutil/trace.go:171","msg":"trace[1461397859] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-b4aebe1d-bd92-45ea-b413-2512b524c987; range_end:; response_count:1; response_revision:24424; }","duration":"206.166847ms","start":"2026-06-09T17:57:53.185562Z","end":"2026-06-09T17:57:53.391729Z","steps":["trace[1461397859] 'agreement among raft nodes before linearized reading' (duration: 205.980762ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:57:53.392403Z","caller":"traceutil/trace.go:171","msg":"trace[1339847371] transaction","detail":"{read_only:false; response_revision:24425; number_of_response:1; }","duration":"142.264828ms","start":"2026-06-09T17:57:53.250117Z","end":"2026-06-09T17:57:53.392382Z","steps":["trace[1339847371] 'process raft request' (duration: 142.173746ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:57:56.724198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.766924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0\" ","response":"range_response_count:1 size:1866"} {"level":"info","ts":"2026-06-09T17:57:56.72425Z","caller":"traceutil/trace.go:171","msg":"trace[1403686463] transaction","detail":"{read_only:false; response_revision:24468; number_of_response:1; }","duration":"149.626666ms","start":"2026-06-09T17:57:56.574585Z","end":"2026-06-09T17:57:56.724211Z","steps":["trace[1403686463] 'process raft request' (duration: 137.36794ms)","trace[1403686463] 'compare' (duration: 11.760474ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:57:56.72429Z","caller":"traceutil/trace.go:171","msg":"trace[271131277] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0; range_end:; response_count:1; response_revision:24467; }","duration":"249.899877ms","start":"2026-06-09T17:57:56.474368Z","end":"2026-06-09T17:57:56.724268Z","steps":["trace[271131277] 'range keys from in-memory index tree' (duration: 249.565999ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:57:56.724093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.765736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-09T17:57:56.724473Z","caller":"traceutil/trace.go:171","msg":"trace[2071488556] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0; response_count:0; response_revision:24467; }","duration":"196.208235ms","start":"2026-06-09T17:57:56.528252Z","end":"2026-06-09T17:57:56.72446Z","steps":["trace[2071488556] 'count revisions from in-memory index tree' (duration: 195.679463ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:57:56.885205Z","caller":"traceutil/trace.go:171","msg":"trace[1601489885] linearizableReadLoop","detail":"{readStateIndex:25266; appliedIndex:25265; }","duration":"157.526479ms","start":"2026-06-09T17:57:56.72766Z","end":"2026-06-09T17:57:56.885186Z","steps":["trace[1601489885] 'read index received' (duration: 121.075616ms)","trace[1601489885] 'applied index is now lower than readState.Index' (duration: 36.450053ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:57:56.885307Z","caller":"traceutil/trace.go:171","msg":"trace[1984192248] transaction","detail":"{read_only:false; response_revision:24469; number_of_response:1; }","duration":"309.43725ms","start":"2026-06-09T17:57:56.575861Z","end":"2026-06-09T17:57:56.885298Z","steps":["trace[1984192248] 'process raft request' (duration: 272.963016ms)","trace[1984192248] 'compare' (duration: 36.249309ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:57:56.885396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:57:56.575834Z","time spent":"309.499592ms","remote":"127.0.0.1:57168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T17:57:56.88557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.918518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-9a9fe36d-e96b-4734-8eb0-6ac8347db550\" ","response":"range_response_count:1 size:1323"} {"level":"info","ts":"2026-06-09T17:57:56.885602Z","caller":"traceutil/trace.go:171","msg":"trace[1111196018] range","detail":"{range_begin:/registry/persistentvolumes/pvc-9a9fe36d-e96b-4734-8eb0-6ac8347db550; range_end:; response_count:1; response_revision:24469; }","duration":"157.99425ms","start":"2026-06-09T17:57:56.727601Z","end":"2026-06-09T17:57:56.885595Z","steps":["trace[1111196018] 'agreement among raft nodes before linearized reading' (duration: 157.882947ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:58:16.271514Z","caller":"traceutil/trace.go:171","msg":"trace[55265542] transaction","detail":"{read_only:false; response_revision:24631; number_of_response:1; }","duration":"126.873365ms","start":"2026-06-09T17:58:16.14462Z","end":"2026-06-09T17:58:16.271493Z","steps":["trace[55265542] 'process raft request' (duration: 126.716502ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:58:16.514399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.845779ms","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-06-09T17:58:16.51452Z","caller":"traceutil/trace.go:171","msg":"trace[1746985737] range","detail":"{range_begin:/registry/rabbitmq.com/policies/; range_end:/registry/rabbitmq.com/policies0; response_count:0; response_revision:24633; }","duration":"161.049743ms","start":"2026-06-09T17:58:16.35344Z","end":"2026-06-09T17:58:16.51449Z","steps":["trace[1746985737] 'count revisions from in-memory index tree' (duration: 160.686105ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:58:16.605909Z","caller":"traceutil/trace.go:171","msg":"trace[1005691366] transaction","detail":"{read_only:false; response_revision:24635; number_of_response:1; }","duration":"124.17537ms","start":"2026-06-09T17:58:16.481698Z","end":"2026-06-09T17:58:16.605874Z","steps":["trace[1005691366] 'process raft request' (duration: 123.874683ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:58:16.788222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.84869ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-09T17:58:16.788582Z","caller":"traceutil/trace.go:171","msg":"trace[925439523] transaction","detail":"{read_only:false; response_revision:24636; number_of_response:1; }","duration":"173.990517ms","start":"2026-06-09T17:58:16.614577Z","end":"2026-06-09T17:58:16.788567Z","steps":["trace[925439523] 'process raft request' (duration: 66.730127ms)","trace[925439523] 'compare' (duration: 106.566583ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:58:16.973695Z","caller":"traceutil/trace.go:171","msg":"trace[1644592222] linearizableReadLoop","detail":"{readStateIndex:25438; appliedIndex:25437; }","duration":"163.456532ms","start":"2026-06-09T17:58:16.810211Z","end":"2026-06-09T17:58:16.973668Z","steps":["trace[1644592222] 'read index received' (duration: 163.221685ms)","trace[1644592222] 'applied index is now lower than readState.Index' (duration: 233.687µs)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:58:16.974075Z","caller":"traceutil/trace.go:171","msg":"trace[1516269995] transaction","detail":"{read_only:false; response_revision:24637; number_of_response:1; }","duration":"165.647755ms","start":"2026-06-09T17:58:16.808391Z","end":"2026-06-09T17:58:16.974039Z","steps":["trace[1516269995] 'process raft request' (duration: 165.122681ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:58:16.974121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.815222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-09T17:58:16.97414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.899193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/rabbitmq-manila.18b77b68cb108770\" ","response":"range_response_count:1 size:742"} {"level":"info","ts":"2026-06-09T17:58:16.974196Z","caller":"traceutil/trace.go:171","msg":"trace[947747944] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates0; response_count:0; response_revision:24637; }","duration":"102.945065ms","start":"2026-06-09T17:58:16.871228Z","end":"2026-06-09T17:58:16.974174Z","steps":["trace[947747944] 'agreement among raft nodes before linearized reading' (duration: 102.797092ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:58:16.974205Z","caller":"traceutil/trace.go:171","msg":"trace[1425110576] range","detail":"{range_begin:/registry/events/openstack/rabbitmq-manila.18b77b68cb108770; range_end:; response_count:1; response_revision:24637; }","duration":"163.989535ms","start":"2026-06-09T17:58:16.810198Z","end":"2026-06-09T17:58:16.974187Z","steps":["trace[1425110576] 'agreement among raft nodes before linearized reading' (duration: 163.77642ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:58:16.974226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:1444"} {"level":"info","ts":"2026-06-09T17:58:16.974282Z","caller":"traceutil/trace.go:171","msg":"trace[543759637] range","detail":"{range_begin:/registry/services/specs/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:24637; }","duration":"162.638732ms","start":"2026-06-09T17:58:16.811628Z","end":"2026-06-09T17:58:16.974267Z","steps":["trace[543759637] 'agreement among raft nodes before linearized reading' (duration: 162.334365ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:58:17.171456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.673247ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-09T17:58:17.171601Z","caller":"traceutil/trace.go:171","msg":"trace[1459459766] linearizableReadLoop","detail":"{readStateIndex:25439; appliedIndex:25438; }","duration":"191.958702ms","start":"2026-06-09T17:58:16.979623Z","end":"2026-06-09T17:58:17.171582Z","steps":["trace[1459459766] 'read index received' (duration: 68.05426ms)","trace[1459459766] 'applied index is now lower than readState.Index' (duration: 123.902872ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:58:17.171679Z","caller":"traceutil/trace.go:171","msg":"trace[857477644] transaction","detail":"{read_only:false; response_revision:24638; number_of_response:1; }","duration":"195.698953ms","start":"2026-06-09T17:58:16.975967Z","end":"2026-06-09T17:58:17.171666Z","steps":["trace[857477644] 'process raft request' (duration: 71.699048ms)","trace[857477644] 'compare' (duration: 123.491833ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:58:17.172117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.468084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-manila-server\" ","response":"range_response_count:1 size:9545"} {"level":"info","ts":"2026-06-09T17:58:17.172202Z","caller":"traceutil/trace.go:171","msg":"trace[1758656422] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-manila-server; range_end:; response_count:1; response_revision:24638; }","duration":"192.587147ms","start":"2026-06-09T17:58:16.979595Z","end":"2026-06-09T17:58:17.172182Z","steps":["trace[1758656422] 'agreement among raft nodes before linearized reading' (duration: 192.347241ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:58:17.221371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.518994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-06-09T17:58:17.221419Z","caller":"traceutil/trace.go:171","msg":"trace[1342097439] transaction","detail":"{read_only:false; response_revision:24639; number_of_response:1; }","duration":"224.243675ms","start":"2026-06-09T17:58:16.997158Z","end":"2026-06-09T17:58:17.221402Z","steps":["trace[1342097439] 'process raft request' (duration: 223.861416ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:58:17.221446Z","caller":"traceutil/trace.go:171","msg":"trace[1758759185] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24639; }","duration":"149.631306ms","start":"2026-06-09T17:58:17.0718Z","end":"2026-06-09T17:58:17.221431Z","steps":["trace[1758759185] 'agreement among raft nodes before linearized reading' (duration: 149.429011ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:58:17.221633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.859154ms","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-06-09T17:58:17.221672Z","caller":"traceutil/trace.go:171","msg":"trace[912874835] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24639; }","duration":"154.926434ms","start":"2026-06-09T17:58:17.066736Z","end":"2026-06-09T17:58:17.221663Z","steps":["trace[912874835] 'agreement among raft nodes before linearized reading' (duration: 154.823192ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:58:17.221792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.042192ms","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-06-09T17:58:17.221824Z","caller":"traceutil/trace.go:171","msg":"trace[206275455] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24639; }","duration":"149.093903ms","start":"2026-06-09T17:58:17.072722Z","end":"2026-06-09T17:58:17.221816Z","steps":["trace[206275455] 'agreement among raft nodes before linearized reading' (duration: 148.500129ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:58:17.221999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.658557ms","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-06-09T17:58:17.222092Z","caller":"traceutil/trace.go:171","msg":"trace[1689438796] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24639; }","duration":"163.794269ms","start":"2026-06-09T17:58:17.058275Z","end":"2026-06-09T17:58:17.222069Z","steps":["trace[1689438796] 'agreement among raft nodes before linearized reading' (duration: 163.510802ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:58:17.372343Z","caller":"traceutil/trace.go:171","msg":"trace[1412297298] transaction","detail":"{read_only:false; response_revision:24640; number_of_response:1; }","duration":"148.53319ms","start":"2026-06-09T17:58:17.223792Z","end":"2026-06-09T17:58:17.372325Z","steps":["trace[1412297298] 'process raft request' (duration: 76.713629ms)","trace[1412297298] 'compare' (duration: 71.687318ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:58:17.402276Z","caller":"traceutil/trace.go:171","msg":"trace[762516056] transaction","detail":"{read_only:false; response_revision:24641; number_of_response:1; }","duration":"176.9754ms","start":"2026-06-09T17:58:17.225276Z","end":"2026-06-09T17:58:17.402252Z","steps":["trace[762516056] 'process raft request' (duration: 176.778864ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:58:17.402578Z","caller":"traceutil/trace.go:171","msg":"trace[830251452] transaction","detail":"{read_only:false; response_revision:24642; number_of_response:1; }","duration":"176.498898ms","start":"2026-06-09T17:58:17.22605Z","end":"2026-06-09T17:58:17.402549Z","steps":["trace[830251452] 'process raft request' (duration: 176.156709ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:58:17.402629Z","caller":"traceutil/trace.go:171","msg":"trace[1583313830] linearizableReadLoop","detail":"{readStateIndex:25445; appliedIndex:25440; }","duration":"169.698683ms","start":"2026-06-09T17:58:17.232906Z","end":"2026-06-09T17:58:17.402604Z","steps":["trace[1583313830] 'read index received' (duration: 67.523266ms)","trace[1583313830] 'applied index is now lower than readState.Index' (duration: 102.174547ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:58:17.402642Z","caller":"traceutil/trace.go:171","msg":"trace[1362266169] transaction","detail":"{read_only:false; response_revision:24643; number_of_response:1; }","duration":"175.74137ms","start":"2026-06-09T17:58:17.226872Z","end":"2026-06-09T17:58:17.402614Z","steps":["trace[1362266169] 'process raft request' (duration: 175.572506ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:58:17.402655Z","caller":"traceutil/trace.go:171","msg":"trace[26439405] transaction","detail":"{read_only:false; response_revision:24644; number_of_response:1; }","duration":"175.490284ms","start":"2026-06-09T17:58:17.22715Z","end":"2026-06-09T17:58:17.402641Z","steps":["trace[26439405] 'process raft request' (duration: 175.410712ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:58:17.402867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.936169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-manila-server\" ","response":"range_response_count:1 size:9545"} {"level":"info","ts":"2026-06-09T17:58:17.402939Z","caller":"traceutil/trace.go:171","msg":"trace[1534800876] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-manila-server; range_end:; response_count:1; response_revision:24644; }","duration":"170.019941ms","start":"2026-06-09T17:58:17.232898Z","end":"2026-06-09T17:58:17.402918Z","steps":["trace[1534800876] 'agreement among raft nodes before linearized reading' (duration: 169.828396ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:58:17.601292Z","caller":"traceutil/trace.go:171","msg":"trace[1509049616] linearizableReadLoop","detail":"{readStateIndex:25447; appliedIndex:25446; }","duration":"140.300551ms","start":"2026-06-09T17:58:17.460967Z","end":"2026-06-09T17:58:17.601268Z","steps":["trace[1509049616] 'read index received' (duration: 92.971534ms)","trace[1509049616] 'applied index is now lower than readState.Index' (duration: 47.328117ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:58:17.601817Z","caller":"traceutil/trace.go:171","msg":"trace[1110498144] transaction","detail":"{read_only:false; response_revision:24646; number_of_response:1; }","duration":"145.103917ms","start":"2026-06-09T17:58:17.456697Z","end":"2026-06-09T17:58:17.601801Z","steps":["trace[1110498144] 'process raft request' (duration: 97.217367ms)","trace[1110498144] 'compare' (duration: 47.115901ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:58:17.602086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.09383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3235"} {"level":"info","ts":"2026-06-09T17:58:17.60217Z","caller":"traceutil/trace.go:171","msg":"trace[1233170794] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:24646; }","duration":"141.205363ms","start":"2026-06-09T17:58:17.460943Z","end":"2026-06-09T17:58:17.602148Z","steps":["trace[1233170794] 'agreement among raft nodes before linearized reading' (duration: 140.932446ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:58:17.602081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.462144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3235"} {"level":"info","ts":"2026-06-09T17:58:17.602338Z","caller":"traceutil/trace.go:171","msg":"trace[650486569] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:24646; }","duration":"102.766821ms","start":"2026-06-09T17:58:17.499547Z","end":"2026-06-09T17:58:17.602314Z","steps":["trace[650486569] 'agreement among raft nodes before linearized reading' (duration: 102.34161ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:58:17.825082Z","caller":"traceutil/trace.go:171","msg":"trace[347892627] linearizableReadLoop","detail":"{readStateIndex:25448; appliedIndex:25447; }","duration":"205.797228ms","start":"2026-06-09T17:58:17.619262Z","end":"2026-06-09T17:58:17.825059Z","steps":["trace[347892627] 'read index received' (duration: 189.76884ms)","trace[347892627] 'applied index is now lower than readState.Index' (duration: 16.027298ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:58:17.82517Z","caller":"traceutil/trace.go:171","msg":"trace[1737713947] transaction","detail":"{read_only:false; response_revision:24647; number_of_response:1; }","duration":"210.41861ms","start":"2026-06-09T17:58:17.614669Z","end":"2026-06-09T17:58:17.825088Z","steps":["trace[1737713947] 'process raft request' (duration: 194.505795ms)","trace[1737713947] 'compare' (duration: 15.652548ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:58:17.825254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.971372ms","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":"info","ts":"2026-06-09T17:58:17.82529Z","caller":"traceutil/trace.go:171","msg":"trace[780458449] range","detail":"{range_begin:/registry/resourcequotas/openstack/; range_end:/registry/resourcequotas/openstack0; response_count:0; response_revision:24647; }","duration":"206.038654ms","start":"2026-06-09T17:58:17.619239Z","end":"2026-06-09T17:58:17.825277Z","steps":["trace[780458449] 'agreement among raft nodes before linearized reading' (duration: 205.956571ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:58:18.215752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.033457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephnfses/\" range_end:\"/registry/ceph.rook.io/cephnfses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-09T17:58:18.215952Z","caller":"traceutil/trace.go:171","msg":"trace[1378736316] range","detail":"{range_begin:/registry/ceph.rook.io/cephnfses/; range_end:/registry/ceph.rook.io/cephnfses0; response_count:0; response_revision:24648; }","duration":"110.261722ms","start":"2026-06-09T17:58:18.105659Z","end":"2026-06-09T17:58:18.21592Z","steps":["trace[1378736316] 'count revisions from in-memory index tree' (duration: 109.962325ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:58:18.385809Z","caller":"traceutil/trace.go:171","msg":"trace[1102493564] transaction","detail":"{read_only:false; response_revision:24650; number_of_response:1; }","duration":"101.759188ms","start":"2026-06-09T17:58:18.284018Z","end":"2026-06-09T17:58:18.385778Z","steps":["trace[1102493564] 'process raft request' (duration: 101.48655ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:58:18.385861Z","caller":"traceutil/trace.go:171","msg":"trace[1447335445] transaction","detail":"{read_only:false; response_revision:24651; number_of_response:1; }","duration":"100.024355ms","start":"2026-06-09T17:58:18.285796Z","end":"2026-06-09T17:58:18.385821Z","steps":["trace[1447335445] 'process raft request' (duration: 99.882531ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:58:19.515454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.089566ms","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-06-09T17:58:19.51563Z","caller":"traceutil/trace.go:171","msg":"trace[1508461440] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24655; }","duration":"102.29301ms","start":"2026-06-09T17:58:19.413316Z","end":"2026-06-09T17:58:19.515609Z","steps":["trace[1508461440] 'range keys from in-memory index tree' (duration: 101.89567ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:58:19.516178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.891914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-06-09T17:58:19.51695Z","caller":"traceutil/trace.go:171","msg":"trace[242346344] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24655; }","duration":"103.675753ms","start":"2026-06-09T17:58:19.413258Z","end":"2026-06-09T17:58:19.516933Z","steps":["trace[242346344] 'range keys from in-memory index tree' (duration: 102.800032ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:58:19.516323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.928745ms","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-06-09T17:58:19.517246Z","caller":"traceutil/trace.go:171","msg":"trace[1943344797] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24655; }","duration":"103.874188ms","start":"2026-06-09T17:58:19.413349Z","end":"2026-06-09T17:58:19.517223Z","steps":["trace[1943344797] 'range keys from in-memory index tree' (duration: 102.804732ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:58:19.516518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.305354ms","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-06-09T17:58:19.517556Z","caller":"traceutil/trace.go:171","msg":"trace[46528894] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24655; }","duration":"104.35454ms","start":"2026-06-09T17:58:19.413188Z","end":"2026-06-09T17:58:19.517542Z","steps":["trace[46528894] 'range keys from in-memory index tree' (duration: 102.980396ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:58:19.811764Z","caller":"traceutil/trace.go:171","msg":"trace[1866412683] linearizableReadLoop","detail":"{readStateIndex:25461; appliedIndex:25456; }","duration":"255.834082ms","start":"2026-06-09T17:58:19.555912Z","end":"2026-06-09T17:58:19.811746Z","steps":["trace[1866412683] 'read index received' (duration: 40.560904ms)","trace[1866412683] 'applied index is now lower than readState.Index' (duration: 215.272428ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:58:19.811953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.022547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" ","response":"range_response_count:1 size:18367"} {"level":"info","ts":"2026-06-09T17:58:19.811986Z","caller":"traceutil/trace.go:171","msg":"trace[1423128509] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:1; response_revision:24660; }","duration":"256.070348ms","start":"2026-06-09T17:58:19.555906Z","end":"2026-06-09T17:58:19.811977Z","steps":["trace[1423128509] 'agreement among raft nodes before linearized reading' (duration: 255.914014ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:58:19.812584Z","caller":"traceutil/trace.go:171","msg":"trace[968501419] transaction","detail":"{read_only:false; response_revision:24659; number_of_response:1; }","duration":"290.713867ms","start":"2026-06-09T17:58:19.521839Z","end":"2026-06-09T17:58:19.812553Z","steps":["trace[968501419] 'process raft request' (duration: 289.852346ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:58:19.812669Z","caller":"traceutil/trace.go:171","msg":"trace[1199994261] transaction","detail":"{read_only:false; response_revision:24660; number_of_response:1; }","duration":"286.080734ms","start":"2026-06-09T17:58:19.526569Z","end":"2026-06-09T17:58:19.812649Z","steps":["trace[1199994261] 'process raft request' (duration: 285.149422ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:58:19.812595Z","caller":"traceutil/trace.go:171","msg":"trace[1126081241] transaction","detail":"{read_only:false; response_revision:24657; number_of_response:1; }","duration":"290.877041ms","start":"2026-06-09T17:58:19.521685Z","end":"2026-06-09T17:58:19.812571Z","steps":["trace[1126081241] 'process raft request' (duration: 289.846676ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:58:19.812834Z","caller":"traceutil/trace.go:171","msg":"trace[1746741447] transaction","detail":"{read_only:false; response_revision:24658; number_of_response:1; }","duration":"291.063865ms","start":"2026-06-09T17:58:19.521761Z","end":"2026-06-09T17:58:19.812825Z","steps":["trace[1746741447] 'process raft request' (duration: 289.903137ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:58:20.177995Z","caller":"traceutil/trace.go:171","msg":"trace[1758509735] transaction","detail":"{read_only:false; response_revision:24663; number_of_response:1; }","duration":"111.78808ms","start":"2026-06-09T17:58:20.066184Z","end":"2026-06-09T17:58:20.177972Z","steps":["trace[1758509735] 'process raft request' (duration: 111.611186ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:58:20.178289Z","caller":"traceutil/trace.go:171","msg":"trace[899219072] transaction","detail":"{read_only:false; response_revision:24664; number_of_response:1; }","duration":"103.869508ms","start":"2026-06-09T17:58:20.074383Z","end":"2026-06-09T17:58:20.178252Z","steps":["trace[899219072] 'process raft request' (duration: 103.53957ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:58:26.736363Z","caller":"traceutil/trace.go:171","msg":"trace[1275905985] transaction","detail":"{read_only:false; response_revision:24713; number_of_response:1; }","duration":"144.589924ms","start":"2026-06-09T17:58:26.591754Z","end":"2026-06-09T17:58:26.736344Z","steps":["trace[1275905985] 'process raft request' (duration: 144.473971ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:58:29.25391Z","caller":"traceutil/trace.go:171","msg":"trace[1343751154] transaction","detail":"{read_only:false; response_revision:24729; number_of_response:1; }","duration":"194.247777ms","start":"2026-06-09T17:58:29.059638Z","end":"2026-06-09T17:58:29.253886Z","steps":["trace[1343751154] 'process raft request' (duration: 194.059303ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:58:29.253855Z","caller":"traceutil/trace.go:171","msg":"trace[1367442813] linearizableReadLoop","detail":"{readStateIndex:25532; appliedIndex:25531; }","duration":"138.826984ms","start":"2026-06-09T17:58:29.115007Z","end":"2026-06-09T17:58:29.253834Z","steps":["trace[1367442813] 'read index received' (duration: 138.681051ms)","trace[1367442813] 'applied index is now lower than readState.Index' (duration: 145.013µs)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:58:29.254078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.050889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-09T17:58:29.254144Z","caller":"traceutil/trace.go:171","msg":"trace[1929248082] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24729; }","duration":"139.165922ms","start":"2026-06-09T17:58:29.11497Z","end":"2026-06-09T17:58:29.254136Z","steps":["trace[1929248082] 'agreement among raft nodes before linearized reading' (duration: 139.020529ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:58:29.707756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.494193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-06-09T17:58:29.707838Z","caller":"traceutil/trace.go:171","msg":"trace[158040338] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24729; }","duration":"359.631967ms","start":"2026-06-09T17:58:29.348189Z","end":"2026-06-09T17:58:29.707821Z","steps":["trace[158040338] 'range keys from in-memory index tree' (duration: 359.308908ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:58:29.707877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:58:29.348157Z","time spent":"359.710458ms","remote":"127.0.0.1:57078","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12240,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-06-09T17:58:29.708647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.06824ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-09T17:58:29.708801Z","caller":"traceutil/trace.go:171","msg":"trace[323317636] transaction","detail":"{read_only:false; response_revision:24730; number_of_response:1; }","duration":"349.809948ms","start":"2026-06-09T17:58:29.358951Z","end":"2026-06-09T17:58:29.708761Z","steps":["trace[323317636] 'process raft request' (duration: 78.550644ms)","trace[323317636] 'compare' (duration: 270.962566ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:58:29.708753Z","caller":"traceutil/trace.go:171","msg":"trace[501883045] linearizableReadLoop","detail":"{readStateIndex:25533; appliedIndex:25532; }","duration":"302.650187ms","start":"2026-06-09T17:58:29.406086Z","end":"2026-06-09T17:58:29.708736Z","steps":["trace[501883045] 'read index received' (duration: 31.472544ms)","trace[501883045] 'applied index is now lower than readState.Index' (duration: 271.176233ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:58:29.708924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:58:29.358934Z","time spent":"349.929042ms","remote":"127.0.0.1:57168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T17:58:29.708979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.892192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephcosidrivers/\" range_end:\"/registry/ceph.rook.io/cephcosidrivers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-09T17:58:29.70902Z","caller":"traceutil/trace.go:171","msg":"trace[1233541123] range","detail":"{range_begin:/registry/ceph.rook.io/cephcosidrivers/; range_end:/registry/ceph.rook.io/cephcosidrivers0; response_count:0; response_revision:24730; }","duration":"302.969974ms","start":"2026-06-09T17:58:29.406036Z","end":"2026-06-09T17:58:29.709006Z","steps":["trace[1233541123] 'agreement among raft nodes before linearized reading' (duration: 302.894062ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:58:29.709057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:58:29.406015Z","time spent":"303.030356ms","remote":"127.0.0.1:38164","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":29,"request content":"key:\"/registry/ceph.rook.io/cephcosidrivers/\" range_end:\"/registry/ceph.rook.io/cephcosidrivers0\" count_only:true "} {"level":"info","ts":"2026-06-09T17:58:52.407473Z","caller":"traceutil/trace.go:171","msg":"trace[544863128] transaction","detail":"{read_only:false; response_revision:24908; number_of_response:1; }","duration":"210.894371ms","start":"2026-06-09T17:58:52.196554Z","end":"2026-06-09T17:58:52.407449Z","steps":["trace[544863128] 'process raft request' (duration: 210.85467ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:58:52.407606Z","caller":"traceutil/trace.go:171","msg":"trace[1441421586] transaction","detail":"{read_only:false; response_revision:24906; number_of_response:1; }","duration":"211.996207ms","start":"2026-06-09T17:58:52.195542Z","end":"2026-06-09T17:58:52.407538Z","steps":["trace[1441421586] 'process raft request' (duration: 188.97231ms)","trace[1441421586] 'compare' (duration: 22.630158ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:58:52.407959Z","caller":"traceutil/trace.go:171","msg":"trace[411094166] transaction","detail":"{read_only:false; response_revision:24907; number_of_response:1; }","duration":"211.947026ms","start":"2026-06-09T17:58:52.195719Z","end":"2026-06-09T17:58:52.407666Z","steps":["trace[411094166] 'process raft request' (duration: 211.634969ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:58:52.416949Z","caller":"traceutil/trace.go:171","msg":"trace[492487080] transaction","detail":"{read_only:false; response_revision:24909; number_of_response:1; }","duration":"119.439656ms","start":"2026-06-09T17:58:52.297494Z","end":"2026-06-09T17:58:52.416933Z","steps":["trace[492487080] 'process raft request' (duration: 119.367784ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T18:00:31.952063Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-00000000000068d2.wal"} {"level":"info","ts":"2026-06-09T18:01:01.614168Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23339} {"level":"info","ts":"2026-06-09T18:01:01.747786Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23339,"took":"129.5647ms","hash":932413175,"current-db-size-bytes":58462208,"current-db-size":"58 MB","current-db-size-in-use-bytes":37281792,"current-db-size-in-use":"37 MB"} {"level":"info","ts":"2026-06-09T18:01:01.747838Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":932413175,"revision":23339,"compact-revision":20025} {"level":"info","ts":"2026-06-09T18:01:51.437325Z","caller":"traceutil/trace.go:171","msg":"trace[1522714811] transaction","detail":"{read_only:false; response_revision:26822; number_of_response:1; }","duration":"102.770071ms","start":"2026-06-09T18:01:51.334497Z","end":"2026-06-09T18:01:51.437267Z","steps":["trace[1522714811] 'process raft request' (duration: 18.054668ms)","trace[1522714811] 'compare' (duration: 84.277403ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T18:01:51.562785Z","caller":"traceutil/trace.go:171","msg":"trace[418773529] transaction","detail":"{read_only:false; response_revision:26825; number_of_response:1; }","duration":"100.778343ms","start":"2026-06-09T18:01:51.46197Z","end":"2026-06-09T18:01:51.562748Z","steps":["trace[418773529] 'process raft request' (duration: 78.099663ms)","trace[418773529] 'compare' (duration: 22.464815ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T18:02:09.713235Z","caller":"traceutil/trace.go:171","msg":"trace[1378846919] transaction","detail":"{read_only:false; response_revision:27059; number_of_response:1; }","duration":"131.548899ms","start":"2026-06-09T18:02:09.581667Z","end":"2026-06-09T18:02:09.713216Z","steps":["trace[1378846919] 'process raft request' (duration: 131.402895ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T18:03:12.202155Z","caller":"traceutil/trace.go:171","msg":"trace[1365187234] transaction","detail":"{read_only:false; response_revision:27541; number_of_response:1; }","duration":"274.892893ms","start":"2026-06-09T18:03:11.927232Z","end":"2026-06-09T18:03:12.202125Z","steps":["trace[1365187234] 'process raft request' (duration: 274.633186ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T18:03:13.949299Z","caller":"traceutil/trace.go:171","msg":"trace[490310411] transaction","detail":"{read_only:false; response_revision:27552; number_of_response:1; }","duration":"119.863355ms","start":"2026-06-09T18:03:13.829398Z","end":"2026-06-09T18:03:13.949261Z","steps":["trace[490310411] 'process raft request' (duration: 119.596989ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T18:03:13.961499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.173612ms","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-06-09T18:03:13.961571Z","caller":"traceutil/trace.go:171","msg":"trace[341243697] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:27553; }","duration":"108.287704ms","start":"2026-06-09T18:03:13.853269Z","end":"2026-06-09T18:03:13.961557Z","steps":["trace[341243697] 'agreement among raft nodes before linearized reading' (duration: 108.103649ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T18:06:01.680783Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26253} {"level":"info","ts":"2026-06-09T18:06:01.828008Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26253,"took":"141.042858ms","hash":3930621028,"current-db-size-bytes":58462208,"current-db-size":"58 MB","current-db-size-in-use-bytes":32739328,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-06-09T18:06:01.828074Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3930621028,"revision":26253,"compact-revision":23339} {"level":"info","ts":"2026-06-09T18:06:34.353551Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"1cac722af8b2b8a6","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-09T18:06:34.358936Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-06-09T18:06:34.359059Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-06-09T18:11:01.68676Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28843} {"level":"info","ts":"2026-06-09T18:11:01.814028Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28843,"took":"122.679303ms","hash":3052342560,"current-db-size-bytes":58462208,"current-db-size":"58 MB","current-db-size-in-use-bytes":30179328,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-06-09T18:11:01.814082Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3052342560,"revision":28843,"compact-revision":26253} {"level":"info","ts":"2026-06-09T18:16:01.693985Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":31064} {"level":"info","ts":"2026-06-09T18:16:01.8182Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":31064,"took":"119.957919ms","hash":1298598135,"current-db-size-bytes":58462208,"current-db-size":"58 MB","current-db-size-in-use-bytes":31444992,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-06-09T18:16:01.818269Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1298598135,"revision":31064,"compact-revision":28843} {"level":"info","ts":"2026-06-09T18:21:01.702377Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33355} {"level":"info","ts":"2026-06-09T18:21:01.864932Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33355,"took":"157.435071ms","hash":1189781374,"current-db-size-bytes":58462208,"current-db-size":"58 MB","current-db-size-in-use-bytes":32608256,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-06-09T18:21:01.865005Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1189781374,"revision":33355,"compact-revision":31064} {"level":"info","ts":"2026-06-09T18:26:01.708543Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35624} {"level":"info","ts":"2026-06-09T18:26:01.843508Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35624,"took":"130.286061ms","hash":1832266885,"current-db-size-bytes":58462208,"current-db-size":"58 MB","current-db-size-in-use-bytes":31207424,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-06-09T18:26:01.84358Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1832266885,"revision":35624,"compact-revision":33355} {"level":"info","ts":"2026-06-09T18:28:04.75719Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"1cac722af8b2b8a6","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-09T18:28:04.772119Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-06-09T18:28:04.772241Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004}