{"level":"warn","ts":"2026-08-22T10:40:28.246057Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-22T10:40:28.246162Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.150:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.150:2380","--initial-cluster=instance=https://199.19.213.150:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.150:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.150: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-08-22T10:40:28.246236Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-22T10:40:28.246247Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.150:2380"]} {"level":"info","ts":"2026-08-22T10:40:28.246269Z","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-08-22T10:40:28.247111Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.150:2379"]} {"level":"info","ts":"2026-08-22T10:40:28.247189Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.150:2380"],"listen-peer-urls":["https://199.19.213.150:2380"],"advertise-client-urls":["https://199.19.213.150:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.150:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.150: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-08-22T10:40:28.250211Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.768537ms"} {"level":"info","ts":"2026-08-22T10:40:28.254215Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"dbd0ffa4f8c99812","cluster-id":"98aad832cd49bc30"} {"level":"info","ts":"2026-08-22T10:40:28.254302Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dbd0ffa4f8c99812 switched to configuration voters=()"} {"level":"info","ts":"2026-08-22T10:40:28.254333Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dbd0ffa4f8c99812 became follower at term 0"} {"level":"info","ts":"2026-08-22T10:40:28.254349Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft dbd0ffa4f8c99812 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-22T10:40:28.254359Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dbd0ffa4f8c99812 became follower at term 1"} {"level":"info","ts":"2026-08-22T10:40:28.254396Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dbd0ffa4f8c99812 switched to configuration voters=(15839440973475715090)"} {"level":"warn","ts":"2026-08-22T10:40:28.259344Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-22T10:40:28.261078Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-22T10:40:28.262425Z","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-08-22T10:40:28.266159Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"dbd0ffa4f8c99812","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-22T10:40:28.267708Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"dbd0ffa4f8c99812","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-22T10:40:28.267821Z","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-08-22T10:40:28.271441Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dbd0ffa4f8c99812 switched to configuration voters=(15839440973475715090)"} {"level":"info","ts":"2026-08-22T10:40:28.271617Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"98aad832cd49bc30","local-member-id":"dbd0ffa4f8c99812","added-peer-id":"dbd0ffa4f8c99812","added-peer-peer-urls":["https://199.19.213.150:2380"]} {"level":"info","ts":"2026-08-22T10:40:28.272918Z","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-08-22T10:40:28.273497Z","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-08-22T10:40:28.274317Z","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-08-22T10:40:28.274422Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.150:2380"} {"level":"info","ts":"2026-08-22T10:40:28.274517Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.150:2380"} {"level":"info","ts":"2026-08-22T10:40:28.274662Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"dbd0ffa4f8c99812","initial-advertise-peer-urls":["https://199.19.213.150:2380"],"listen-peer-urls":["https://199.19.213.150:2380"],"advertise-client-urls":["https://199.19.213.150:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.150:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-22T10:40:28.274727Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-22T10:40:29.054708Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dbd0ffa4f8c99812 is starting a new election at term 1"} {"level":"info","ts":"2026-08-22T10:40:29.054794Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dbd0ffa4f8c99812 became pre-candidate at term 1"} {"level":"info","ts":"2026-08-22T10:40:29.05484Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dbd0ffa4f8c99812 received MsgPreVoteResp from dbd0ffa4f8c99812 at term 1"} {"level":"info","ts":"2026-08-22T10:40:29.054861Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dbd0ffa4f8c99812 became candidate at term 2"} {"level":"info","ts":"2026-08-22T10:40:29.054878Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dbd0ffa4f8c99812 received MsgVoteResp from dbd0ffa4f8c99812 at term 2"} {"level":"info","ts":"2026-08-22T10:40:29.054901Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dbd0ffa4f8c99812 became leader at term 2"} {"level":"info","ts":"2026-08-22T10:40:29.05492Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: dbd0ffa4f8c99812 elected leader dbd0ffa4f8c99812 at term 2"} {"level":"info","ts":"2026-08-22T10:40:29.058434Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"dbd0ffa4f8c99812","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.150:2379]}","request-path":"/0/members/dbd0ffa4f8c99812/attributes","cluster-id":"98aad832cd49bc30","publish-timeout":"7s"} {"level":"info","ts":"2026-08-22T10:40:29.058668Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-22T10:40:29.058745Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-22T10:40:29.059098Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-22T10:40:29.059129Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-22T10:40:29.059247Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-22T10:40:29.060904Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"98aad832cd49bc30","local-member-id":"dbd0ffa4f8c99812","cluster-version":"3.5"} {"level":"info","ts":"2026-08-22T10:40:29.061097Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-22T10:40:29.061233Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-22T10:40:29.062588Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.150:2379"} {"level":"info","ts":"2026-08-22T10:40:29.064214Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-22T10:41:30.1358Z","caller":"traceutil/trace.go:171","msg":"trace[578956423] transaction","detail":"{read_only:false; response_revision:671; number_of_response:1; }","duration":"351.761647ms","start":"2026-08-22T10:41:29.784007Z","end":"2026-08-22T10:41:30.135768Z","steps":["trace[578956423] 'process raft request' (duration: 351.590422ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:41:30.136426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:41:29.783975Z","time spent":"351.939821ms","remote":"127.0.0.1:38806","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-22T10:41:30.591822Z","caller":"traceutil/trace.go:171","msg":"trace[1612729311] linearizableReadLoop","detail":"{readStateIndex:696; appliedIndex:695; }","duration":"224.432096ms","start":"2026-08-22T10:41:30.367367Z","end":"2026-08-22T10:41:30.591799Z","steps":["trace[1612729311] 'read index received' (duration: 183.262393ms)","trace[1612729311] 'applied index is now lower than readState.Index' (duration: 41.168063ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:41:30.59185Z","caller":"traceutil/trace.go:171","msg":"trace[1279137566] transaction","detail":"{read_only:false; response_revision:672; number_of_response:1; }","duration":"437.877555ms","start":"2026-08-22T10:41:30.153951Z","end":"2026-08-22T10:41:30.591828Z","steps":["trace[1279137566] 'process raft request' (duration: 396.761423ms)","trace[1279137566] 'compare' (duration: 40.826284ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T10:41:30.592003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:41:30.153913Z","time spent":"438.034609ms","remote":"127.0.0.1:38728","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2735,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-22T10:41:30.591989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.60797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-64vvj\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-08-22T10:41:30.592048Z","caller":"traceutil/trace.go:171","msg":"trace[120552289] range","detail":"{range_begin:/registry/pods/kube-system/cilium-64vvj; range_end:; response_count:1; response_revision:672; }","duration":"224.723873ms","start":"2026-08-22T10:41:30.367315Z","end":"2026-08-22T10:41:30.592039Z","steps":["trace[120552289] 'agreement among raft nodes before linearized reading' (duration: 224.5765ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:41:31.615118Z","caller":"traceutil/trace.go:171","msg":"trace[1462414224] transaction","detail":"{read_only:false; response_revision:685; number_of_response:1; }","duration":"115.107887ms","start":"2026-08-22T10:41:31.499977Z","end":"2026-08-22T10:41:31.615085Z","steps":["trace[1462414224] 'process raft request' (duration: 114.942923ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:41:46.727753Z","caller":"traceutil/trace.go:171","msg":"trace[1452516171] transaction","detail":"{read_only:false; response_revision:836; number_of_response:1; }","duration":"249.697525ms","start":"2026-08-22T10:41:46.478032Z","end":"2026-08-22T10:41:46.727729Z","steps":["trace[1452516171] 'process raft request' (duration: 249.334936ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:42:36.946299Z","caller":"traceutil/trace.go:171","msg":"trace[1829118571] transaction","detail":"{read_only:false; response_revision:1484; number_of_response:1; }","duration":"111.204316ms","start":"2026-08-22T10:42:36.835064Z","end":"2026-08-22T10:42:36.946269Z","steps":["trace[1829118571] 'process raft request' (duration: 110.995751ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:43:02.113535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.594231ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-22T10:43:02.113669Z","caller":"traceutil/trace.go:171","msg":"trace[667831463] linearizableReadLoop","detail":"{readStateIndex:1974; appliedIndex:1973; }","duration":"237.942949ms","start":"2026-08-22T10:43:01.875709Z","end":"2026-08-22T10:43:02.113652Z","steps":["trace[667831463] 'read index received' (duration: 23.897767ms)","trace[667831463] 'applied index is now lower than readState.Index' (duration: 214.043792ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:43:02.113787Z","caller":"traceutil/trace.go:171","msg":"trace[1167639759] transaction","detail":"{read_only:false; response_revision:1902; number_of_response:1; }","duration":"242.936669ms","start":"2026-08-22T10:43:01.870788Z","end":"2026-08-22T10:43:02.113725Z","steps":["trace[1167639759] 'process raft request' (duration: 28.770095ms)","trace[1167639759] 'compare' (duration: 213.467548ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T10:43:02.113926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.184414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-580871d2-cc99-4399-acd8-deff4f1f1348\" ","response":"range_response_count:1 size:3627"} {"level":"warn","ts":"2026-08-22T10:43:02.113987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.390737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-580871d2-cc99-4399-acd8-deff4f1f1348\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-08-22T10:43:02.114063Z","caller":"traceutil/trace.go:171","msg":"trace[916483377] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-580871d2-cc99-4399-acd8-deff4f1f1348; range_end:; response_count:1; response_revision:1902; }","duration":"105.53366ms","start":"2026-08-22T10:43:02.008508Z","end":"2026-08-22T10:43:02.114041Z","steps":["trace[916483377] 'agreement among raft nodes before linearized reading' (duration: 105.258643ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:43:02.114012Z","caller":"traceutil/trace.go:171","msg":"trace[1714017881] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-580871d2-cc99-4399-acd8-deff4f1f1348; range_end:; response_count:1; response_revision:1902; }","duration":"238.335638ms","start":"2026-08-22T10:43:01.875657Z","end":"2026-08-22T10:43:02.113992Z","steps":["trace[1714017881] 'agreement among raft nodes before linearized reading' (duration: 238.058951ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:43:02.270263Z","caller":"traceutil/trace.go:171","msg":"trace[514960624] linearizableReadLoop","detail":"{readStateIndex:1975; appliedIndex:1974; }","duration":"149.689967ms","start":"2026-08-22T10:43:02.120543Z","end":"2026-08-22T10:43:02.270233Z","steps":["trace[514960624] 'read index received' (duration: 149.41279ms)","trace[514960624] 'applied index is now lower than readState.Index' (duration: 276.047µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:43:02.270445Z","caller":"traceutil/trace.go:171","msg":"trace[1888782064] transaction","detail":"{read_only:false; response_revision:1903; number_of_response:1; }","duration":"150.066206ms","start":"2026-08-22T10:43:02.120365Z","end":"2026-08-22T10:43:02.270431Z","steps":["trace[1888782064] 'process raft request' (duration: 149.671727ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:43:02.270723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.157509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.150\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-08-22T10:43:02.270758Z","caller":"traceutil/trace.go:171","msg":"trace[1582144463] range","detail":"{range_begin:/registry/masterleases/199.19.213.150; range_end:; response_count:1; response_revision:1903; }","duration":"150.259761ms","start":"2026-08-22T10:43:02.120486Z","end":"2026-08-22T10:43:02.270746Z","steps":["trace[1582144463] 'agreement among raft nodes before linearized reading' (duration: 150.095777ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:43:02.27108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.339375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-08-22T10:43:02.271194Z","caller":"traceutil/trace.go:171","msg":"trace[1431987565] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1903; }","duration":"110.484219ms","start":"2026-08-22T10:43:02.160683Z","end":"2026-08-22T10:43:02.271167Z","steps":["trace[1431987565] 'agreement among raft nodes before linearized reading' (duration: 110.186852ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:43:02.639732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.59909ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-22T10:43:02.640072Z","caller":"traceutil/trace.go:171","msg":"trace[826068776] transaction","detail":"{read_only:false; response_revision:1911; number_of_response:1; }","duration":"198.049815ms","start":"2026-08-22T10:43:02.441998Z","end":"2026-08-22T10:43:02.640048Z","steps":["trace[826068776] 'process raft request' (duration: 71.006675ms)","trace[826068776] 'compare' (duration: 126.438875ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:43:15.459605Z","caller":"traceutil/trace.go:171","msg":"trace[1975948217] linearizableReadLoop","detail":"{readStateIndex:2089; appliedIndex:2088; }","duration":"140.486843ms","start":"2026-08-22T10:43:15.319087Z","end":"2026-08-22T10:43:15.459574Z","steps":["trace[1975948217] 'read index received' (duration: 140.266238ms)","trace[1975948217] 'applied index is now lower than readState.Index' (duration: 219.285µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:43:15.459829Z","caller":"traceutil/trace.go:171","msg":"trace[615263300] transaction","detail":"{read_only:false; response_revision:2013; number_of_response:1; }","duration":"177.009256ms","start":"2026-08-22T10:43:15.282806Z","end":"2026-08-22T10:43:15.459815Z","steps":["trace[615263300] 'process raft request' (duration: 176.558605ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:43:15.460288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.190841ms","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-08-22T10:43:15.460377Z","caller":"traceutil/trace.go:171","msg":"trace[1104382160] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2013; }","duration":"141.333364ms","start":"2026-08-22T10:43:15.319021Z","end":"2026-08-22T10:43:15.460354Z","steps":["trace[1104382160] 'agreement among raft nodes before linearized reading' (duration: 141.17993ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:43:53.086395Z","caller":"traceutil/trace.go:171","msg":"trace[1844537412] transaction","detail":"{read_only:false; response_revision:2233; number_of_response:1; }","duration":"134.217023ms","start":"2026-08-22T10:43:52.952146Z","end":"2026-08-22T10:43:53.086363Z","steps":["trace[1844537412] 'process raft request' (duration: 133.984877ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:43:53.419563Z","caller":"traceutil/trace.go:171","msg":"trace[1263853363] linearizableReadLoop","detail":"{readStateIndex:2319; appliedIndex:2317; }","duration":"463.362168ms","start":"2026-08-22T10:43:52.956175Z","end":"2026-08-22T10:43:53.419537Z","steps":["trace[1263853363] 'read index received' (duration: 130.080633ms)","trace[1263853363] 'applied index is now lower than readState.Index' (duration: 333.280305ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:43:53.41967Z","caller":"traceutil/trace.go:171","msg":"trace[885826404] transaction","detail":"{read_only:false; response_revision:2234; number_of_response:1; }","duration":"466.230347ms","start":"2026-08-22T10:43:52.953395Z","end":"2026-08-22T10:43:53.419625Z","steps":["trace[885826404] 'process raft request' (duration: 437.304248ms)","trace[885826404] 'compare' (duration: 28.741525ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T10:43:53.419742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.545152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-22T10:43:53.419773Z","caller":"traceutil/trace.go:171","msg":"trace[487409312] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:2234; }","duration":"463.609523ms","start":"2026-08-22T10:43:52.956156Z","end":"2026-08-22T10:43:53.419766Z","steps":["trace[487409312] 'agreement among raft nodes before linearized reading' (duration: 463.529301ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:43:53.419798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:43:52.956149Z","time spent":"463.642035ms","remote":"127.0.0.1:38962","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":31,"request content":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true "} {"level":"warn","ts":"2026-08-22T10:43:53.419899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:43:52.953366Z","time spent":"466.406992ms","remote":"127.0.0.1:38840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T10:43:53.419939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.826245ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-22T10:43:53.419987Z","caller":"traceutil/trace.go:171","msg":"trace[1002509594] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:2234; }","duration":"457.901376ms","start":"2026-08-22T10:43:52.962073Z","end":"2026-08-22T10:43:53.419975Z","steps":["trace[1002509594] 'agreement among raft nodes before linearized reading' (duration: 457.641029ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:43:53.420021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:43:52.962061Z","time spent":"457.951578ms","remote":"127.0.0.1:39052","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":271,"response size":32,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"info","ts":"2026-08-22T10:44:45.872886Z","caller":"traceutil/trace.go:171","msg":"trace[1927466179] transaction","detail":"{read_only:false; response_revision:2689; number_of_response:1; }","duration":"168.766709ms","start":"2026-08-22T10:44:45.704089Z","end":"2026-08-22T10:44:45.872855Z","steps":["trace[1927466179] 'process raft request' (duration: 168.460091ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:45:18.86558Z","caller":"traceutil/trace.go:171","msg":"trace[1841860211] linearizableReadLoop","detail":"{readStateIndex:3001; appliedIndex:3000; }","duration":"143.263592ms","start":"2026-08-22T10:45:18.722287Z","end":"2026-08-22T10:45:18.86555Z","steps":["trace[1841860211] 'read index received' (duration: 142.977475ms)","trace[1841860211] 'applied index is now lower than readState.Index' (duration: 284.987µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:45:18.865673Z","caller":"traceutil/trace.go:171","msg":"trace[537130472] transaction","detail":"{read_only:false; response_revision:2892; number_of_response:1; }","duration":"203.154659ms","start":"2026-08-22T10:45:18.662468Z","end":"2026-08-22T10:45:18.865623Z","steps":["trace[537130472] 'process raft request' (duration: 202.916904ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:45:18.865783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.469377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T10:45:18.865822Z","caller":"traceutil/trace.go:171","msg":"trace[841157766] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:2892; }","duration":"143.580659ms","start":"2026-08-22T10:45:18.722232Z","end":"2026-08-22T10:45:18.865813Z","steps":["trace[841157766] 'agreement among raft nodes before linearized reading' (duration: 143.487927ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:49:36.598721Z","caller":"traceutil/trace.go:171","msg":"trace[56031669] linearizableReadLoop","detail":"{readStateIndex:5248; appliedIndex:5247; }","duration":"123.808721ms","start":"2026-08-22T10:49:36.474882Z","end":"2026-08-22T10:49:36.598691Z","steps":["trace[56031669] 'read index received' (duration: 106.833021ms)","trace[56031669] 'applied index is now lower than readState.Index' (duration: 16.97442ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:49:36.598787Z","caller":"traceutil/trace.go:171","msg":"trace[1602797343] transaction","detail":"{read_only:false; response_revision:5053; number_of_response:1; }","duration":"126.109437ms","start":"2026-08-22T10:49:36.472623Z","end":"2026-08-22T10:49:36.598733Z","steps":["trace[1602797343] 'process raft request' (duration: 109.092146ms)","trace[1602797343] 'compare' (duration: 16.787425ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T10:49:36.59909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.17556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:11455"} {"level":"info","ts":"2026-08-22T10:49:36.599199Z","caller":"traceutil/trace.go:171","msg":"trace[1816090599] range","detail":"{range_begin:/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:5053; }","duration":"124.315413ms","start":"2026-08-22T10:49:36.474835Z","end":"2026-08-22T10:49:36.59915Z","steps":["trace[1816090599] 'agreement among raft nodes before linearized reading' (duration: 123.995565ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:49:39.54469Z","caller":"traceutil/trace.go:171","msg":"trace[719826076] transaction","detail":"{read_only:false; response_revision:5186; number_of_response:1; }","duration":"157.133866ms","start":"2026-08-22T10:49:39.387529Z","end":"2026-08-22T10:49:39.544663Z","steps":["trace[719826076] 'process raft request' (duration: 156.929771ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:49:39.550602Z","caller":"traceutil/trace.go:171","msg":"trace[1922632277] linearizableReadLoop","detail":"{readStateIndex:5385; appliedIndex:5383; }","duration":"136.957629ms","start":"2026-08-22T10:49:39.413621Z","end":"2026-08-22T10:49:39.550579Z","steps":["trace[1922632277] 'read index received' (duration: 130.933563ms)","trace[1922632277] 'applied index is now lower than readState.Index' (duration: 6.022916ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:49:39.550751Z","caller":"traceutil/trace.go:171","msg":"trace[1962892758] transaction","detail":"{read_only:false; response_revision:5187; number_of_response:1; }","duration":"160.784804ms","start":"2026-08-22T10:49:39.389926Z","end":"2026-08-22T10:49:39.550711Z","steps":["trace[1962892758] 'process raft request' (duration: 160.496897ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:49:39.550871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.225376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-08-22T10:49:39.550906Z","caller":"traceutil/trace.go:171","msg":"trace[966720646] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5187; }","duration":"137.315377ms","start":"2026-08-22T10:49:39.413582Z","end":"2026-08-22T10:49:39.550897Z","steps":["trace[966720646] 'agreement among raft nodes before linearized reading' (duration: 137.130373ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:49:39.55093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.554278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-08-22T10:49:39.551006Z","caller":"traceutil/trace.go:171","msg":"trace[1108687113] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5187; }","duration":"114.65288ms","start":"2026-08-22T10:49:39.436321Z","end":"2026-08-22T10:49:39.550974Z","steps":["trace[1108687113] 'agreement among raft nodes before linearized reading' (duration: 114.432055ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:49:39.551124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.05262ms","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-08-22T10:49:39.551192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.431098ms","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-08-22T10:49:39.551215Z","caller":"traceutil/trace.go:171","msg":"trace[2078464471] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5187; }","duration":"103.176883ms","start":"2026-08-22T10:49:39.448015Z","end":"2026-08-22T10:49:39.551192Z","steps":["trace[2078464471] 'agreement among raft nodes before linearized reading' (duration: 102.966558ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:49:39.551236Z","caller":"traceutil/trace.go:171","msg":"trace[1045210858] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5187; }","duration":"105.512099ms","start":"2026-08-22T10:49:39.445711Z","end":"2026-08-22T10:49:39.551223Z","steps":["trace[1045210858] 'agreement among raft nodes before linearized reading' (duration: 105.301674ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:49:41.318191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.793272ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-22T10:49:41.318395Z","caller":"traceutil/trace.go:171","msg":"trace[447749975] linearizableReadLoop","detail":"{readStateIndex:5428; appliedIndex:5426; }","duration":"111.116085ms","start":"2026-08-22T10:49:41.207261Z","end":"2026-08-22T10:49:41.318377Z","steps":["trace[447749975] 'read index received' (duration: 58.631µs)","trace[447749975] 'applied index is now lower than readState.Index' (duration: 111.056344ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:49:41.318544Z","caller":"traceutil/trace.go:171","msg":"trace[1039852483] transaction","detail":"{read_only:false; response_revision:5228; number_of_response:1; }","duration":"157.006263ms","start":"2026-08-22T10:49:41.161475Z","end":"2026-08-22T10:49:41.318482Z","steps":["trace[1039852483] 'process raft request' (duration: 22.818151ms)","trace[1039852483] 'compare' (duration: 133.561467ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:49:41.318562Z","caller":"traceutil/trace.go:171","msg":"trace[813683437] transaction","detail":"{read_only:false; response_revision:5229; number_of_response:1; }","duration":"155.176679ms","start":"2026-08-22T10:49:41.163341Z","end":"2026-08-22T10:49:41.318518Z","steps":["trace[813683437] 'process raft request' (duration: 154.971124ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:49:41.318609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.349231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-dmxdn\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-08-22T10:49:41.319044Z","caller":"traceutil/trace.go:171","msg":"trace[1083802333] range","detail":"{range_begin:/registry/pods/openstack/keepalived-dmxdn; range_end:; response_count:1; response_revision:5229; }","duration":"111.826752ms","start":"2026-08-22T10:49:41.207204Z","end":"2026-08-22T10:49:41.31903Z","steps":["trace[1083802333] 'agreement among raft nodes before linearized reading' (duration: 111.271479ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:49:53.138524Z","caller":"traceutil/trace.go:171","msg":"trace[927370623] transaction","detail":"{read_only:false; response_revision:5463; number_of_response:1; }","duration":"108.925171ms","start":"2026-08-22T10:49:53.029571Z","end":"2026-08-22T10:49:53.138496Z","steps":["trace[927370623] 'process raft request' (duration: 108.736357ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:50:00.495672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.644462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T10:50:00.49579Z","caller":"traceutil/trace.go:171","msg":"trace[469064670] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:5652; }","duration":"124.842587ms","start":"2026-08-22T10:50:00.370923Z","end":"2026-08-22T10:50:00.495766Z","steps":["trace[469064670] 'count revisions from in-memory index tree' (duration: 124.521989ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:50:00.495813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.731085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/prometheus-pushgateway-7b8659c68b-j8b9r\" ","response":"range_response_count:1 size:4040"} {"level":"info","ts":"2026-08-22T10:50:00.495915Z","caller":"traceutil/trace.go:171","msg":"trace[1298504593] range","detail":"{range_begin:/registry/pods/monitoring/prometheus-pushgateway-7b8659c68b-j8b9r; range_end:; response_count:1; response_revision:5652; }","duration":"112.908209ms","start":"2026-08-22T10:50:00.382984Z","end":"2026-08-22T10:50:00.495892Z","steps":["trace[1298504593] 'range keys from in-memory index tree' (duration: 112.363615ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:50:00.716217Z","caller":"traceutil/trace.go:171","msg":"trace[1631856141] linearizableReadLoop","detail":"{readStateIndex:5864; appliedIndex:5863; }","duration":"193.815602ms","start":"2026-08-22T10:50:00.522374Z","end":"2026-08-22T10:50:00.716189Z","steps":["trace[1631856141] 'read index received' (duration: 193.581547ms)","trace[1631856141] 'applied index is now lower than readState.Index' (duration: 233.025µs)"],"step_count":2} {"level":"warn","ts":"2026-08-22T10:50:00.716434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.039798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-8af217b3-7d2c-42cb-ac82-caa2503b8d58\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-08-22T10:50:00.716393Z","caller":"traceutil/trace.go:171","msg":"trace[1738217604] transaction","detail":"{read_only:false; response_revision:5653; number_of_response:1; }","duration":"212.610036ms","start":"2026-08-22T10:50:00.503697Z","end":"2026-08-22T10:50:00.716307Z","steps":["trace[1738217604] 'process raft request' (duration: 212.325699ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:50:00.716468Z","caller":"traceutil/trace.go:171","msg":"trace[628988201] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-8af217b3-7d2c-42cb-ac82-caa2503b8d58; range_end:; response_count:1; response_revision:5653; }","duration":"194.124679ms","start":"2026-08-22T10:50:00.522332Z","end":"2026-08-22T10:50:00.716457Z","steps":["trace[628988201] 'agreement among raft nodes before linearized reading' (duration: 193.960596ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:50:00.926872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.268348ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-22T10:50:00.927437Z","caller":"traceutil/trace.go:171","msg":"trace[190968469] linearizableReadLoop","detail":"{readStateIndex:5867; appliedIndex:5864; }","duration":"166.400611ms","start":"2026-08-22T10:50:00.761022Z","end":"2026-08-22T10:50:00.927423Z","steps":["trace[190968469] 'read index received' (duration: 61.24027ms)","trace[190968469] 'applied index is now lower than readState.Index' (duration: 105.159561ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:50:00.927506Z","caller":"traceutil/trace.go:171","msg":"trace[351789357] transaction","detail":"{read_only:false; response_revision:5655; number_of_response:1; }","duration":"204.831748ms","start":"2026-08-22T10:50:00.722639Z","end":"2026-08-22T10:50:00.92747Z","steps":["trace[351789357] 'process raft request' (duration: 204.354687ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:50:00.927581Z","caller":"traceutil/trace.go:171","msg":"trace[1593730873] transaction","detail":"{read_only:false; response_revision:5656; number_of_response:1; }","duration":"198.64915ms","start":"2026-08-22T10:50:00.728891Z","end":"2026-08-22T10:50:00.92754Z","steps":["trace[1593730873] 'process raft request' (duration: 198.492436ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:50:00.927652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.344387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T10:50:00.927519Z","caller":"traceutil/trace.go:171","msg":"trace[238638295] transaction","detail":"{read_only:false; response_revision:5654; number_of_response:1; }","duration":"205.342711ms","start":"2026-08-22T10:50:00.722131Z","end":"2026-08-22T10:50:00.927473Z","steps":["trace[238638295] 'process raft request' (duration: 100.381725ms)","trace[238638295] 'compare' (duration: 104.149056ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:50:00.927713Z","caller":"traceutil/trace.go:171","msg":"trace[1206704215] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5656; }","duration":"120.45454ms","start":"2026-08-22T10:50:00.807241Z","end":"2026-08-22T10:50:00.927696Z","steps":["trace[1206704215] 'agreement among raft nodes before linearized reading' (duration: 120.358228ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:50:00.927808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.79561ms","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-08-22T10:50:00.927835Z","caller":"traceutil/trace.go:171","msg":"trace[1811530814] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5656; }","duration":"166.887692ms","start":"2026-08-22T10:50:00.76094Z","end":"2026-08-22T10:50:00.927828Z","steps":["trace[1811530814] 'agreement among raft nodes before linearized reading' (duration: 166.655606ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:50:11.74153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.830823ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-22T10:50:11.741777Z","caller":"traceutil/trace.go:171","msg":"trace[1576522050] transaction","detail":"{read_only:false; response_revision:5769; number_of_response:1; }","duration":"175.215323ms","start":"2026-08-22T10:50:11.566531Z","end":"2026-08-22T10:50:11.741746Z","steps":["trace[1576522050] 'process raft request' (duration: 41.074333ms)","trace[1576522050] 'compare' (duration: 133.69328ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:50:11.741822Z","caller":"traceutil/trace.go:171","msg":"trace[342654759] linearizableReadLoop","detail":"{readStateIndex:5984; appliedIndex:5981; }","duration":"137.045121ms","start":"2026-08-22T10:50:11.604755Z","end":"2026-08-22T10:50:11.7418Z","steps":["trace[342654759] 'read index received' (duration: 2.764677ms)","trace[342654759] 'applied index is now lower than readState.Index' (duration: 134.279004ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:50:11.741864Z","caller":"traceutil/trace.go:171","msg":"trace[552211503] transaction","detail":"{read_only:false; response_revision:5770; number_of_response:1; }","duration":"174.975857ms","start":"2026-08-22T10:50:11.566841Z","end":"2026-08-22T10:50:11.741817Z","steps":["trace[552211503] 'process raft request' (duration: 174.808433ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:50:11.741933Z","caller":"traceutil/trace.go:171","msg":"trace[1056753633] transaction","detail":"{read_only:false; response_revision:5771; number_of_response:1; }","duration":"175.045328ms","start":"2026-08-22T10:50:11.56686Z","end":"2026-08-22T10:50:11.741905Z","steps":["trace[1056753633] 'process raft request' (duration: 174.880264ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:50:11.742108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.327767ms","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-08-22T10:50:11.742177Z","caller":"traceutil/trace.go:171","msg":"trace[990132605] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5771; }","duration":"137.472651ms","start":"2026-08-22T10:50:11.604686Z","end":"2026-08-22T10:50:11.742158Z","steps":["trace[990132605] 'agreement among raft nodes before linearized reading' (duration: 137.218195ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:50:29.094241Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2948} {"level":"info","ts":"2026-08-22T10:50:29.161239Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2948,"took":"65.82436ms","hash":736177099,"current-db-size-bytes":54738944,"current-db-size":"55 MB","current-db-size-in-use-bytes":32194560,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-22T10:50:29.161306Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":736177099,"revision":2948,"compact-revision":-1} {"level":"info","ts":"2026-08-22T10:55:29.120278Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5889} {"level":"info","ts":"2026-08-22T10:55:29.223983Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5889,"took":"102.145669ms","hash":3097619377,"current-db-size-bytes":54738944,"current-db-size":"55 MB","current-db-size-in-use-bytes":26238976,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-08-22T10:55:29.224038Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3097619377,"revision":5889,"compact-revision":2948} {"level":"info","ts":"2026-08-22T10:56:09.723781Z","caller":"traceutil/trace.go:171","msg":"trace[1650278841] transaction","detail":"{read_only:false; response_revision:8459; number_of_response:1; }","duration":"243.71066ms","start":"2026-08-22T10:56:09.480032Z","end":"2026-08-22T10:56:09.723743Z","steps":["trace[1650278841] 'process raft request' (duration: 229.816585ms)","trace[1650278841] 'compare' (duration: 13.383743ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:56:09.723744Z","caller":"traceutil/trace.go:171","msg":"trace[1778971422] linearizableReadLoop","detail":"{readStateIndex:8760; appliedIndex:8759; }","duration":"225.796168ms","start":"2026-08-22T10:56:09.497922Z","end":"2026-08-22T10:56:09.723719Z","steps":["trace[1778971422] 'read index received' (duration: 211.8332ms)","trace[1778971422] 'applied index is now lower than readState.Index' (duration: 13.961408ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T10:56:09.724104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.165149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-08-22T10:56:09.724078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.120275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-dmxdn\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-08-22T10:56:09.724147Z","caller":"traceutil/trace.go:171","msg":"trace[2092737211] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8459; }","duration":"186.259522ms","start":"2026-08-22T10:56:09.537876Z","end":"2026-08-22T10:56:09.724135Z","steps":["trace[2092737211] 'agreement among raft nodes before linearized reading' (duration: 186.045857ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:56:09.724178Z","caller":"traceutil/trace.go:171","msg":"trace[1463525339] range","detail":"{range_begin:/registry/pods/openstack/keepalived-dmxdn; range_end:; response_count:1; response_revision:8459; }","duration":"226.261099ms","start":"2026-08-22T10:56:09.497895Z","end":"2026-08-22T10:56:09.724156Z","steps":["trace[1463525339] 'agreement among raft nodes before linearized reading' (duration: 225.958802ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:56:09.7243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.162031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-08-22T10:56:09.724305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.695187ms","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-08-22T10:56:09.724366Z","caller":"traceutil/trace.go:171","msg":"trace[1571489488] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8459; }","duration":"167.274964ms","start":"2026-08-22T10:56:09.557071Z","end":"2026-08-22T10:56:09.724346Z","steps":["trace[1571489488] 'agreement among raft nodes before linearized reading' (duration: 167.044448ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:56:09.72439Z","caller":"traceutil/trace.go:171","msg":"trace[746728814] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8459; }","duration":"156.82595ms","start":"2026-08-22T10:56:09.567542Z","end":"2026-08-22T10:56:09.724368Z","steps":["trace[746728814] 'agreement among raft nodes before linearized reading' (duration: 156.537883ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:56:09.724893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.414448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-08-22T10:56:09.725019Z","caller":"traceutil/trace.go:171","msg":"trace[1743986329] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8459; }","duration":"166.301099ms","start":"2026-08-22T10:56:09.558695Z","end":"2026-08-22T10:56:09.724996Z","steps":["trace[1743986329] 'agreement among raft nodes before linearized reading' (duration: 165.250984ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:56:09.919656Z","caller":"traceutil/trace.go:171","msg":"trace[1119638095] transaction","detail":"{read_only:false; response_revision:8460; number_of_response:1; }","duration":"190.542935ms","start":"2026-08-22T10:56:09.729053Z","end":"2026-08-22T10:56:09.919596Z","steps":["trace[1119638095] 'process raft request' (duration: 117.457169ms)","trace[1119638095] 'compare' (duration: 72.880281ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:56:09.924274Z","caller":"traceutil/trace.go:171","msg":"trace[2010853546] transaction","detail":"{read_only:false; response_revision:8462; number_of_response:1; }","duration":"193.847725ms","start":"2026-08-22T10:56:09.730407Z","end":"2026-08-22T10:56:09.924255Z","steps":["trace[2010853546] 'process raft request' (duration: 193.790124ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:56:09.924296Z","caller":"traceutil/trace.go:171","msg":"trace[769301895] transaction","detail":"{read_only:false; response_revision:8461; number_of_response:1; }","duration":"195.004843ms","start":"2026-08-22T10:56:09.729265Z","end":"2026-08-22T10:56:09.92427Z","steps":["trace[769301895] 'process raft request' (duration: 194.837678ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:56:09.924428Z","caller":"traceutil/trace.go:171","msg":"trace[1248656641] linearizableReadLoop","detail":"{readStateIndex:8764; appliedIndex:8760; }","duration":"193.068876ms","start":"2026-08-22T10:56:09.731351Z","end":"2026-08-22T10:56:09.92442Z","steps":["trace[1248656641] 'read index received' (duration: 115.171033ms)","trace[1248656641] 'applied index is now lower than readState.Index' (duration: 77.896753ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:56:09.924494Z","caller":"traceutil/trace.go:171","msg":"trace[1013510771] transaction","detail":"{read_only:false; response_revision:8463; number_of_response:1; }","duration":"193.915537ms","start":"2026-08-22T10:56:09.730545Z","end":"2026-08-22T10:56:09.924461Z","steps":["trace[1013510771] 'process raft request' (duration: 193.68052ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:56:09.924622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.884125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T10:56:09.924693Z","caller":"traceutil/trace.go:171","msg":"trace[1105032241] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8463; }","duration":"117.020168ms","start":"2026-08-22T10:56:09.807656Z","end":"2026-08-22T10:56:09.924676Z","steps":["trace[1105032241] 'agreement among raft nodes before linearized reading' (duration: 116.890845ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:56:09.92482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.477416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:25 size:224168"} {"level":"info","ts":"2026-08-22T10:56:09.92485Z","caller":"traceutil/trace.go:171","msg":"trace[1345131059] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:25; response_revision:8463; }","duration":"193.536817ms","start":"2026-08-22T10:56:09.731305Z","end":"2026-08-22T10:56:09.924842Z","steps":["trace[1345131059] 'agreement among raft nodes before linearized reading' (duration: 193.190819ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:56:22.214083Z","caller":"traceutil/trace.go:171","msg":"trace[932258683] linearizableReadLoop","detail":"{readStateIndex:8826; appliedIndex:8825; }","duration":"225.790777ms","start":"2026-08-22T10:56:21.988268Z","end":"2026-08-22T10:56:22.214059Z","steps":["trace[932258683] 'read index received' (duration: 225.567271ms)","trace[932258683] 'applied index is now lower than readState.Index' (duration: 222.396µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:56:22.214132Z","caller":"traceutil/trace.go:171","msg":"trace[802318661] transaction","detail":"{read_only:false; response_revision:8522; number_of_response:1; }","duration":"230.142643ms","start":"2026-08-22T10:56:21.983961Z","end":"2026-08-22T10:56:22.214104Z","steps":["trace[802318661] 'process raft request' (duration: 229.946558ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:56:22.214307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.021023ms","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-08-22T10:56:22.214348Z","caller":"traceutil/trace.go:171","msg":"trace[2110953821] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8522; }","duration":"226.107935ms","start":"2026-08-22T10:56:21.98823Z","end":"2026-08-22T10:56:22.214338Z","steps":["trace[2110953821] 'agreement among raft nodes before linearized reading' (duration: 225.959441ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:56:22.214479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.90667ms","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-08-22T10:56:22.21455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.408849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-08-22T10:56:22.214557Z","caller":"traceutil/trace.go:171","msg":"trace[1592441488] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8522; }","duration":"225.994363ms","start":"2026-08-22T10:56:21.988545Z","end":"2026-08-22T10:56:22.21454Z","steps":["trace[1592441488] 'agreement among raft nodes before linearized reading' (duration: 225.774047ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:56:22.214587Z","caller":"traceutil/trace.go:171","msg":"trace[769301546] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8522; }","duration":"225.453959ms","start":"2026-08-22T10:56:21.989124Z","end":"2026-08-22T10:56:22.214578Z","steps":["trace[769301546] 'agreement among raft nodes before linearized reading' (duration: 225.348827ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:56:22.214589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.410581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-dmxdn\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-08-22T10:56:22.214667Z","caller":"traceutil/trace.go:171","msg":"trace[962130257] range","detail":"{range_begin:/registry/pods/openstack/keepalived-dmxdn; range_end:; response_count:1; response_revision:8522; }","duration":"216.535484ms","start":"2026-08-22T10:56:21.998115Z","end":"2026-08-22T10:56:22.21465Z","steps":["trace[962130257] 'agreement among raft nodes before linearized reading' (duration: 216.337669ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:56:24.445645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.807456ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-22T10:56:24.445764Z","caller":"traceutil/trace.go:171","msg":"trace[1000606516] transaction","detail":"{read_only:false; response_revision:8535; number_of_response:1; }","duration":"164.490765ms","start":"2026-08-22T10:56:24.281255Z","end":"2026-08-22T10:56:24.445746Z","steps":["trace[1000606516] 'process raft request' (duration: 44.497835ms)","trace[1000606516] 'compare' (duration: 119.695933ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:56:24.46673Z","caller":"traceutil/trace.go:171","msg":"trace[1059609100] transaction","detail":"{read_only:false; response_revision:8536; number_of_response:1; }","duration":"184.762726ms","start":"2026-08-22T10:56:24.281947Z","end":"2026-08-22T10:56:24.466709Z","steps":["trace[1059609100] 'process raft request' (duration: 184.554971ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:56:24.466745Z","caller":"traceutil/trace.go:171","msg":"trace[1734145244] linearizableReadLoop","detail":"{readStateIndex:8842; appliedIndex:8839; }","duration":"184.621152ms","start":"2026-08-22T10:56:24.282054Z","end":"2026-08-22T10:56:24.466676Z","steps":["trace[1734145244] 'read index received' (duration: 43.714865ms)","trace[1734145244] 'applied index is now lower than readState.Index' (duration: 140.904977ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:56:24.466851Z","caller":"traceutil/trace.go:171","msg":"trace[1218722395] transaction","detail":"{read_only:false; response_revision:8537; number_of_response:1; }","duration":"184.849908ms","start":"2026-08-22T10:56:24.281988Z","end":"2026-08-22T10:56:24.466838Z","steps":["trace[1218722395] 'process raft request' (duration: 184.647003ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:56:24.467115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.038332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-dmxdn\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-08-22T10:56:24.467195Z","caller":"traceutil/trace.go:171","msg":"trace[446795659] range","detail":"{range_begin:/registry/pods/openstack/keepalived-dmxdn; range_end:; response_count:1; response_revision:8537; }","duration":"185.152585ms","start":"2026-08-22T10:56:24.282025Z","end":"2026-08-22T10:56:24.467177Z","steps":["trace[446795659] 'agreement among raft nodes before linearized reading' (duration: 184.93221ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:56:51.302246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.31416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T10:56:51.302418Z","caller":"traceutil/trace.go:171","msg":"trace[1516201576] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:8736; }","duration":"286.535335ms","start":"2026-08-22T10:56:51.015864Z","end":"2026-08-22T10:56:51.3024Z","steps":["trace[1516201576] 'range keys from in-memory index tree' (duration: 286.249728ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:56:51.429736Z","caller":"traceutil/trace.go:171","msg":"trace[1190912174] transaction","detail":"{read_only:false; response_revision:8737; number_of_response:1; }","duration":"197.239187ms","start":"2026-08-22T10:56:51.232471Z","end":"2026-08-22T10:56:51.42971Z","steps":["trace[1190912174] 'process raft request' (duration: 197.023102ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:56:51.461668Z","caller":"traceutil/trace.go:171","msg":"trace[374398947] linearizableReadLoop","detail":"{readStateIndex:9050; appliedIndex:9048; }","duration":"155.478659ms","start":"2026-08-22T10:56:51.306159Z","end":"2026-08-22T10:56:51.461638Z","steps":["trace[374398947] 'read index received' (duration: 123.384423ms)","trace[374398947] 'applied index is now lower than readState.Index' (duration: 32.093156ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T10:56:51.461815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.640372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T10:56:51.461854Z","caller":"traceutil/trace.go:171","msg":"trace[239562800] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:8738; }","duration":"155.727375ms","start":"2026-08-22T10:56:51.306115Z","end":"2026-08-22T10:56:51.461842Z","steps":["trace[239562800] 'agreement among raft nodes before linearized reading' (duration: 155.625332ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:56:51.46193Z","caller":"traceutil/trace.go:171","msg":"trace[1337113112] transaction","detail":"{read_only:false; response_revision:8738; number_of_response:1; }","duration":"192.963744ms","start":"2026-08-22T10:56:51.268846Z","end":"2026-08-22T10:56:51.46181Z","steps":["trace[1337113112] 'process raft request' (duration: 192.655436ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:57:00.730088Z","caller":"traceutil/trace.go:171","msg":"trace[1076365931] transaction","detail":"{read_only:false; response_revision:8786; number_of_response:1; }","duration":"110.718757ms","start":"2026-08-22T10:57:00.619342Z","end":"2026-08-22T10:57:00.73006Z","steps":["trace[1076365931] 'process raft request' (duration: 108.151155ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:57:00.732302Z","caller":"traceutil/trace.go:171","msg":"trace[813420903] linearizableReadLoop","detail":"{readStateIndex:9101; appliedIndex:9099; }","duration":"110.373627ms","start":"2026-08-22T10:57:00.621908Z","end":"2026-08-22T10:57:00.732282Z","steps":["trace[813420903] 'read index received' (duration: 105.567831ms)","trace[813420903] 'applied index is now lower than readState.Index' (duration: 4.804996ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:57:00.732435Z","caller":"traceutil/trace.go:171","msg":"trace[295741095] transaction","detail":"{read_only:false; response_revision:8787; number_of_response:1; }","duration":"111.246708ms","start":"2026-08-22T10:57:00.621092Z","end":"2026-08-22T10:57:00.732338Z","steps":["trace[295741095] 'process raft request' (duration: 111.047053ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:57:00.73247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.547152ms","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-08-22T10:57:00.732585Z","caller":"traceutil/trace.go:171","msg":"trace[1594910521] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8788; }","duration":"110.684385ms","start":"2026-08-22T10:57:00.621864Z","end":"2026-08-22T10:57:00.732549Z","steps":["trace[1594910521] 'agreement among raft nodes before linearized reading' (duration: 110.48928ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:57:04.036223Z","caller":"traceutil/trace.go:171","msg":"trace[554648047] transaction","detail":"{read_only:false; response_revision:8814; number_of_response:1; }","duration":"189.197483ms","start":"2026-08-22T10:57:03.846998Z","end":"2026-08-22T10:57:04.036195Z","steps":["trace[554648047] 'process raft request' (duration: 89.445622ms)","trace[554648047] 'compare' (duration: 99.625018ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:57:04.036232Z","caller":"traceutil/trace.go:171","msg":"trace[546485206] transaction","detail":"{read_only:false; response_revision:8815; number_of_response:1; }","duration":"167.623402ms","start":"2026-08-22T10:57:03.86859Z","end":"2026-08-22T10:57:04.036214Z","steps":["trace[546485206] 'process raft request' (duration: 167.573681ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:57:04.036412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.186775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-v5mrh\" ","response":"range_response_count:1 size:4645"} {"level":"info","ts":"2026-08-22T10:57:04.036473Z","caller":"traceutil/trace.go:171","msg":"trace[1346842611] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-v5mrh; range_end:; response_count:1; response_revision:8815; }","duration":"168.284108ms","start":"2026-08-22T10:57:03.868175Z","end":"2026-08-22T10:57:04.036459Z","steps":["trace[1346842611] 'agreement among raft nodes before linearized reading' (duration: 168.121924ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:57:04.036243Z","caller":"traceutil/trace.go:171","msg":"trace[1794606287] linearizableReadLoop","detail":"{readStateIndex:9129; appliedIndex:9128; }","duration":"168.003381ms","start":"2026-08-22T10:57:03.868205Z","end":"2026-08-22T10:57:04.036208Z","steps":["trace[1794606287] 'read index received' (duration: 68.248279ms)","trace[1794606287] 'applied index is now lower than readState.Index' (duration: 99.752032ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:57:28.73069Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000024b8.wal"} {"level":"info","ts":"2026-08-22T10:58:29.004674Z","caller":"traceutil/trace.go:171","msg":"trace[1950404046] transaction","detail":"{read_only:false; response_revision:9640; number_of_response:1; }","duration":"122.129118ms","start":"2026-08-22T10:58:28.882509Z","end":"2026-08-22T10:58:29.004638Z","steps":["trace[1950404046] 'process raft request' (duration: 42.291764ms)","trace[1950404046] 'compare' (duration: 79.713401ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:58:32.40446Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"dbd0ffa4f8c99812","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-22T10:58:32.40944Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-08-22T10:58:32.409532Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-08-22T11:00:29.126487Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8066} {"level":"info","ts":"2026-08-22T11:00:29.190109Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8066,"took":"61.351786ms","hash":2166954652,"current-db-size-bytes":54738944,"current-db-size":"55 MB","current-db-size-in-use-bytes":28434432,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-08-22T11:00:29.190187Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2166954652,"revision":8066,"compact-revision":5889} {"level":"info","ts":"2026-08-22T11:05:29.133508Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10509} {"level":"info","ts":"2026-08-22T11:05:29.214478Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10509,"took":"78.245634ms","hash":4158879303,"current-db-size-bytes":54738944,"current-db-size":"55 MB","current-db-size-in-use-bytes":27652096,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-08-22T11:05:29.214537Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4158879303,"revision":10509,"compact-revision":8066} {"level":"info","ts":"2026-08-22T11:08:56.605741Z","caller":"traceutil/trace.go:171","msg":"trace[464426135] transaction","detail":"{read_only:false; response_revision:14208; number_of_response:1; }","duration":"142.085896ms","start":"2026-08-22T11:08:56.463611Z","end":"2026-08-22T11:08:56.605697Z","steps":["trace[464426135] 'process raft request' (duration: 141.909282ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:08:56.614271Z","caller":"traceutil/trace.go:171","msg":"trace[540444477] linearizableReadLoop","detail":"{readStateIndex:14699; appliedIndex:14698; }","duration":"148.657315ms","start":"2026-08-22T11:08:56.465571Z","end":"2026-08-22T11:08:56.614228Z","steps":["trace[540444477] 'read index received' (duration: 140.233691ms)","trace[540444477] 'applied index is now lower than readState.Index' (duration: 8.422264ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:08:56.614335Z","caller":"traceutil/trace.go:171","msg":"trace[1553280241] transaction","detail":"{read_only:false; response_revision:14209; number_of_response:1; }","duration":"150.627333ms","start":"2026-08-22T11:08:56.463662Z","end":"2026-08-22T11:08:56.614289Z","steps":["trace[1553280241] 'process raft request' (duration: 150.260904ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:08:56.614538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.936022ms","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-08-22T11:08:56.614596Z","caller":"traceutil/trace.go:171","msg":"trace[797546553] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:14210; }","duration":"149.030634ms","start":"2026-08-22T11:08:56.465543Z","end":"2026-08-22T11:08:56.614574Z","steps":["trace[797546553] 'agreement among raft nodes before linearized reading' (duration: 148.834999ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:08:57.561616Z","caller":"traceutil/trace.go:171","msg":"trace[902918870] transaction","detail":"{read_only:false; response_revision:14214; number_of_response:1; }","duration":"284.221233ms","start":"2026-08-22T11:08:57.277365Z","end":"2026-08-22T11:08:57.561586Z","steps":["trace[902918870] 'process raft request' (duration: 284.031719ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:09:06.99013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.908236ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-22T11:09:06.990445Z","caller":"traceutil/trace.go:171","msg":"trace[1624124981] transaction","detail":"{read_only:false; response_revision:14380; number_of_response:1; }","duration":"322.114289ms","start":"2026-08-22T11:09:06.668307Z","end":"2026-08-22T11:09:06.990422Z","steps":["trace[1624124981] 'process raft request' (duration: 321.958275ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:09:06.990454Z","caller":"traceutil/trace.go:171","msg":"trace[1172018583] transaction","detail":"{read_only:false; response_revision:14379; number_of_response:1; }","duration":"322.112489ms","start":"2026-08-22T11:09:06.668293Z","end":"2026-08-22T11:09:06.990405Z","steps":["trace[1172018583] 'process raft request' (duration: 68.795203ms)","trace[1172018583] 'compare' (duration: 252.585537ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T11:09:06.990539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:09:06.668291Z","time spent":"322.204622ms","remote":"127.0.0.1:38840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T11:09:06.990585Z","caller":"traceutil/trace.go:171","msg":"trace[1372411772] linearizableReadLoop","detail":"{readStateIndex:14874; appliedIndex:14871; }","duration":"183.706933ms","start":"2026-08-22T11:09:06.806845Z","end":"2026-08-22T11:09:06.990552Z","steps":["trace[1372411772] 'read index received' (duration: 41.981µs)","trace[1372411772] 'applied index is now lower than readState.Index' (duration: 183.663502ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:09:06.990637Z","caller":"traceutil/trace.go:171","msg":"trace[95927884] transaction","detail":"{read_only:false; response_revision:14381; number_of_response:1; }","duration":"285.156275ms","start":"2026-08-22T11:09:06.705436Z","end":"2026-08-22T11:09:06.990592Z","steps":["trace[95927884] 'process raft request' (duration: 284.92099ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:09:06.990765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.915718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-22T11:09:06.990798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:09:06.668265Z","time spent":"322.303744ms","remote":"127.0.0.1:38840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T11:09:06.990844Z","caller":"traceutil/trace.go:171","msg":"trace[2002081682] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14381; }","duration":"184.020271ms","start":"2026-08-22T11:09:06.806801Z","end":"2026-08-22T11:09:06.990821Z","steps":["trace[2002081682] 'agreement among raft nodes before linearized reading' (duration: 183.860457ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:09:07.165082Z","caller":"traceutil/trace.go:171","msg":"trace[811217232] transaction","detail":"{read_only:false; response_revision:14382; number_of_response:1; }","duration":"166.801953ms","start":"2026-08-22T11:09:06.998251Z","end":"2026-08-22T11:09:07.165053Z","steps":["trace[811217232] 'process raft request' (duration: 161.848133ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:10:29.139394Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12797} {"level":"info","ts":"2026-08-22T11:10:29.217559Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12797,"took":"76.041569ms","hash":134997721,"current-db-size-bytes":54738944,"current-db-size":"55 MB","current-db-size-in-use-bytes":30842880,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-22T11:10:29.217639Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":134997721,"revision":12797,"compact-revision":10509} {"level":"info","ts":"2026-08-22T11:10:46.633283Z","caller":"traceutil/trace.go:171","msg":"trace[1304139629] transaction","detail":"{read_only:false; response_revision:15472; number_of_response:1; }","duration":"138.168191ms","start":"2026-08-22T11:10:46.49509Z","end":"2026-08-22T11:10:46.633258Z","steps":["trace[1304139629] 'process raft request' (duration: 138.010237ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:10:46.659725Z","caller":"traceutil/trace.go:171","msg":"trace[1041048120] linearizableReadLoop","detail":"{readStateIndex:16002; appliedIndex:16000; }","duration":"151.928514ms","start":"2026-08-22T11:10:46.507769Z","end":"2026-08-22T11:10:46.659697Z","steps":["trace[1041048120] 'read index received' (duration: 125.421663ms)","trace[1041048120] 'applied index is now lower than readState.Index' (duration: 26.505671ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:10:46.659852Z","caller":"traceutil/trace.go:171","msg":"trace[1970935531] transaction","detail":"{read_only:false; response_revision:15473; number_of_response:1; }","duration":"163.092074ms","start":"2026-08-22T11:10:46.496731Z","end":"2026-08-22T11:10:46.659823Z","steps":["trace[1970935531] 'process raft request' (duration: 162.811827ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:10:46.660096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.303504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"warn","ts":"2026-08-22T11:10:46.660101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.134472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T11:10:46.660161Z","caller":"traceutil/trace.go:171","msg":"trace[701849823] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15473; }","duration":"152.400226ms","start":"2026-08-22T11:10:46.507743Z","end":"2026-08-22T11:10:46.660143Z","steps":["trace[701849823] 'agreement among raft nodes before linearized reading' (duration: 152.109288ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:10:46.660182Z","caller":"traceutil/trace.go:171","msg":"trace[1157759775] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15473; }","duration":"117.275616ms","start":"2026-08-22T11:10:46.542888Z","end":"2026-08-22T11:10:46.660164Z","steps":["trace[1157759775] 'agreement among raft nodes before linearized reading' (duration: 117.106561ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:10:46.660259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.721356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-08-22T11:10:46.660344Z","caller":"traceutil/trace.go:171","msg":"trace[1679733253] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:15473; }","duration":"107.846669ms","start":"2026-08-22T11:10:46.552476Z","end":"2026-08-22T11:10:46.660322Z","steps":["trace[1679733253] 'agreement among raft nodes before linearized reading' (duration: 107.607183ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:15:29.146578Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15384} {"level":"info","ts":"2026-08-22T11:15:29.24259Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15384,"took":"93.395198ms","hash":794226843,"current-db-size-bytes":54738944,"current-db-size":"55 MB","current-db-size-in-use-bytes":32153600,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-22T11:15:29.242667Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":794226843,"revision":15384,"compact-revision":12797} {"level":"warn","ts":"2026-08-22T11:16:30.902483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.879704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-heat\" ","response":"range_response_count:1 size:3299"} {"level":"info","ts":"2026-08-22T11:16:30.902576Z","caller":"traceutil/trace.go:171","msg":"trace[670490498] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-heat; range_end:; response_count:1; response_revision:18106; }","duration":"109.020726ms","start":"2026-08-22T11:16:30.793536Z","end":"2026-08-22T11:16:30.902557Z","steps":["trace[670490498] 'range keys from in-memory index tree' (duration: 108.658418ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:16:31.151951Z","caller":"traceutil/trace.go:171","msg":"trace[1570207387] linearizableReadLoop","detail":"{readStateIndex:18723; appliedIndex:18722; }","duration":"160.851471ms","start":"2026-08-22T11:16:30.991075Z","end":"2026-08-22T11:16:31.151926Z","steps":["trace[1570207387] 'read index received' (duration: 62.762209ms)","trace[1570207387] 'applied index is now lower than readState.Index' (duration: 98.087602ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:16:31.152003Z","caller":"traceutil/trace.go:171","msg":"trace[670862188] transaction","detail":"{read_only:false; response_revision:18109; number_of_response:1; }","duration":"165.79174ms","start":"2026-08-22T11:16:30.986189Z","end":"2026-08-22T11:16:31.151981Z","steps":["trace[670862188] 'process raft request' (duration: 67.613416ms)","trace[670862188] 'compare' (duration: 97.891567ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T11:16:31.152237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.123318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-heat-server\" ","response":"range_response_count:1 size:9566"} {"level":"info","ts":"2026-08-22T11:16:31.152385Z","caller":"traceutil/trace.go:171","msg":"trace[1533816986] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-heat-server; range_end:; response_count:1; response_revision:18109; }","duration":"161.339962ms","start":"2026-08-22T11:16:30.991031Z","end":"2026-08-22T11:16:31.152371Z","steps":["trace[1533816986] 'agreement among raft nodes before linearized reading' (duration: 160.990404ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:16:31.662216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.239307ms","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-08-22T11:16:31.662304Z","caller":"traceutil/trace.go:171","msg":"trace[1332878868] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:18113; }","duration":"115.375391ms","start":"2026-08-22T11:16:31.546909Z","end":"2026-08-22T11:16:31.662285Z","steps":["trace[1332878868] 'count revisions from in-memory index tree' (duration: 115.084303ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:18:56.838588Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"dbd0ffa4f8c99812","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-22T11:18:56.845322Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-08-22T11:18:56.845478Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-08-22T11:20:29.15636Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17600} {"level":"info","ts":"2026-08-22T11:20:29.247866Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17600,"took":"88.732295ms","hash":719158255,"current-db-size-bytes":54738944,"current-db-size":"55 MB","current-db-size-in-use-bytes":34439168,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-08-22T11:20:29.247931Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":719158255,"revision":17600,"compact-revision":15384} {"level":"info","ts":"2026-08-22T11:21:51.569952Z","caller":"traceutil/trace.go:171","msg":"trace[943589330] transaction","detail":"{read_only:false; response_revision:20574; number_of_response:1; }","duration":"127.485153ms","start":"2026-08-22T11:21:51.442442Z","end":"2026-08-22T11:21:51.569927Z","steps":["trace[943589330] 'process raft request' (duration: 127.337959ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:22:07.955946Z","caller":"traceutil/trace.go:171","msg":"trace[1444238956] transaction","detail":"{read_only:false; response_revision:20658; number_of_response:1; }","duration":"104.166428ms","start":"2026-08-22T11:22:07.851745Z","end":"2026-08-22T11:22:07.955911Z","steps":["trace[1444238956] 'process raft request' (duration: 103.874281ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:22:08.513986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.561497ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-22T11:22:08.514287Z","caller":"traceutil/trace.go:171","msg":"trace[1458840615] transaction","detail":"{read_only:false; response_revision:20660; number_of_response:1; }","duration":"230.720017ms","start":"2026-08-22T11:22:08.28354Z","end":"2026-08-22T11:22:08.51426Z","steps":["trace[1458840615] 'process raft request' (duration: 51.777681ms)","trace[1458840615] 'compare' (duration: 178.333302ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:25:29.166906Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20030} {"level":"info","ts":"2026-08-22T11:25:29.274496Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20030,"took":"104.462085ms","hash":4144198911,"current-db-size-bytes":54738944,"current-db-size":"55 MB","current-db-size-in-use-bytes":30556160,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-22T11:25:29.274663Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4144198911,"revision":20030,"compact-revision":17600} {"level":"info","ts":"2026-08-22T11:25:57.848289Z","caller":"traceutil/trace.go:171","msg":"trace[1755741922] transaction","detail":"{read_only:false; response_revision:23081; number_of_response:1; }","duration":"101.585036ms","start":"2026-08-22T11:25:57.746663Z","end":"2026-08-22T11:25:57.848248Z","steps":["trace[1755741922] 'process raft request' (duration: 101.213237ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:25:58.166976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.246334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-08-22T11:25:58.167109Z","caller":"traceutil/trace.go:171","msg":"trace[40558732] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:23081; }","duration":"200.388517ms","start":"2026-08-22T11:25:57.966694Z","end":"2026-08-22T11:25:58.167083Z","steps":["trace[40558732] 'range keys from in-memory index tree' (duration: 200.070949ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:25:58.168025Z","caller":"traceutil/trace.go:171","msg":"trace[120852541] transaction","detail":"{read_only:false; response_revision:23082; number_of_response:1; }","duration":"131.606283ms","start":"2026-08-22T11:25:58.036386Z","end":"2026-08-22T11:25:58.167993Z","steps":["trace[120852541] 'process raft request' (duration: 131.455139ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:25:58.170364Z","caller":"traceutil/trace.go:171","msg":"trace[586932277] transaction","detail":"{read_only:false; response_revision:23083; number_of_response:1; }","duration":"130.933746ms","start":"2026-08-22T11:25:58.039275Z","end":"2026-08-22T11:25:58.170208Z","steps":["trace[586932277] 'process raft request' (duration: 130.524897ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:29:24.518558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.450536ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-22T11:29:24.518691Z","caller":"traceutil/trace.go:171","msg":"trace[520044324] transaction","detail":"{read_only:false; response_revision:25158; number_of_response:1; }","duration":"263.222214ms","start":"2026-08-22T11:29:24.255445Z","end":"2026-08-22T11:29:24.518667Z","steps":["trace[520044324] 'process raft request' (duration: 134.540573ms)","trace[520044324] 'compare' (duration: 128.118238ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:29:24.520195Z","caller":"traceutil/trace.go:171","msg":"trace[1673136488] transaction","detail":"{read_only:false; response_revision:25159; number_of_response:1; }","duration":"140.950658ms","start":"2026-08-22T11:29:24.379214Z","end":"2026-08-22T11:29:24.520164Z","steps":["trace[1673136488] 'process raft request' (duration: 140.821065ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:29:24.71419Z","caller":"traceutil/trace.go:171","msg":"trace[1771719740] transaction","detail":"{read_only:false; response_revision:25160; number_of_response:1; }","duration":"179.362668ms","start":"2026-08-22T11:29:24.534793Z","end":"2026-08-22T11:29:24.714156Z","steps":["trace[1771719740] 'process raft request' (duration: 143.413908ms)","trace[1771719740] 'compare' (duration: 35.753665ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T11:29:24.970582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.875635ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-22T11:29:24.970673Z","caller":"traceutil/trace.go:171","msg":"trace[1536493063] linearizableReadLoop","detail":"{readStateIndex:25987; appliedIndex:25986; }","duration":"222.504451ms","start":"2026-08-22T11:29:24.748155Z","end":"2026-08-22T11:29:24.97066Z","steps":["trace[1536493063] 'read index received' (duration: 96.372021ms)","trace[1536493063] 'applied index is now lower than readState.Index' (duration: 126.13162ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:29:24.97072Z","caller":"traceutil/trace.go:171","msg":"trace[1068649238] transaction","detail":"{read_only:false; response_revision:25162; number_of_response:1; }","duration":"249.998426ms","start":"2026-08-22T11:29:24.720715Z","end":"2026-08-22T11:29:24.970713Z","steps":["trace[1068649238] 'process raft request' (duration: 123.918757ms)","trace[1068649238] 'compare' (duration: 125.773931ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T11:29:24.970808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.632704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/clusters/\" range_end:\"/registry/cluster.x-k8s.io/clusters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T11:29:24.970901Z","caller":"traceutil/trace.go:171","msg":"trace[1445746289] range","detail":"{range_begin:/registry/cluster.x-k8s.io/clusters/; range_end:/registry/cluster.x-k8s.io/clusters0; response_count:0; response_revision:25162; }","duration":"222.775308ms","start":"2026-08-22T11:29:24.748104Z","end":"2026-08-22T11:29:24.970879Z","steps":["trace[1445746289] 'agreement among raft nodes before linearized reading' (duration: 222.632254ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:29:25.041991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.349646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T11:29:25.042208Z","caller":"traceutil/trace.go:171","msg":"trace[1493897971] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25164; }","duration":"236.616942ms","start":"2026-08-22T11:29:24.805563Z","end":"2026-08-22T11:29:25.04218Z","steps":["trace[1493897971] 'agreement among raft nodes before linearized reading' (duration: 236.305354ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:29:25.042179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.094851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephbucketnotifications/\" range_end:\"/registry/ceph.rook.io/cephbucketnotifications0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T11:29:25.042558Z","caller":"traceutil/trace.go:171","msg":"trace[775141527] range","detail":"{range_begin:/registry/ceph.rook.io/cephbucketnotifications/; range_end:/registry/ceph.rook.io/cephbucketnotifications0; response_count:0; response_revision:25164; }","duration":"148.538692ms","start":"2026-08-22T11:29:24.893985Z","end":"2026-08-22T11:29:25.042523Z","steps":["trace[775141527] 'agreement among raft nodes before linearized reading' (duration: 148.03116ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:29:29.22895Z","caller":"traceutil/trace.go:171","msg":"trace[45461106] transaction","detail":"{read_only:false; response_revision:25195; number_of_response:1; }","duration":"173.801063ms","start":"2026-08-22T11:29:29.055126Z","end":"2026-08-22T11:29:29.228927Z","steps":["trace[45461106] 'process raft request' (duration: 173.66741ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:29:29.241182Z","caller":"traceutil/trace.go:171","msg":"trace[1303633793] transaction","detail":"{read_only:false; response_revision:25196; number_of_response:1; }","duration":"112.522661ms","start":"2026-08-22T11:29:29.128633Z","end":"2026-08-22T11:29:29.241155Z","steps":["trace[1303633793] 'process raft request' (duration: 112.106182ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:29:29.840014Z","caller":"traceutil/trace.go:171","msg":"trace[1215708418] transaction","detail":"{read_only:false; response_revision:25200; number_of_response:1; }","duration":"342.78829ms","start":"2026-08-22T11:29:29.497198Z","end":"2026-08-22T11:29:29.839987Z","steps":["trace[1215708418] 'process raft request' (duration: 342.642286ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:29:29.840166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:29:29.497186Z","time spent":"342.903502ms","remote":"127.0.0.1:38840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T11:29:29.862875Z","caller":"traceutil/trace.go:171","msg":"trace[1372462121] linearizableReadLoop","detail":"{readStateIndex:26027; appliedIndex:26025; }","duration":"181.419508ms","start":"2026-08-22T11:29:29.681419Z","end":"2026-08-22T11:29:29.862838Z","steps":["trace[1372462121] 'read index received' (duration: 158.507303ms)","trace[1372462121] 'applied index is now lower than readState.Index' (duration: 22.910675ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:29:29.86291Z","caller":"traceutil/trace.go:171","msg":"trace[912159658] transaction","detail":"{read_only:false; response_revision:25201; number_of_response:1; }","duration":"361.033461ms","start":"2026-08-22T11:29:29.501837Z","end":"2026-08-22T11:29:29.86287Z","steps":["trace[912159658] 'process raft request' (duration: 360.738644ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:29:29.862937Z","caller":"traceutil/trace.go:171","msg":"trace[1886275926] transaction","detail":"{read_only:false; response_revision:25202; number_of_response:1; }","duration":"142.912786ms","start":"2026-08-22T11:29:29.719994Z","end":"2026-08-22T11:29:29.862907Z","steps":["trace[1886275926] 'process raft request' (duration: 142.775583ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:29:29.863061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.617583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T11:29:29.863114Z","caller":"traceutil/trace.go:171","msg":"trace[869472964] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:25202; }","duration":"181.719815ms","start":"2026-08-22T11:29:29.681376Z","end":"2026-08-22T11:29:29.863096Z","steps":["trace[869472964] 'agreement among raft nodes before linearized reading' (duration: 181.602592ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:29:29.86316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:29:29.501815Z","time spent":"361.229755ms","remote":"127.0.0.1:38840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T11:29:30.675514Z","caller":"traceutil/trace.go:171","msg":"trace[2003791205] transaction","detail":"{read_only:false; response_revision:25204; number_of_response:1; }","duration":"138.755215ms","start":"2026-08-22T11:29:30.536735Z","end":"2026-08-22T11:29:30.675491Z","steps":["trace[2003791205] 'process raft request' (duration: 138.57198ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:29:30.812295Z","caller":"traceutil/trace.go:171","msg":"trace[905225721] linearizableReadLoop","detail":"{readStateIndex:26031; appliedIndex:26030; }","duration":"115.123643ms","start":"2026-08-22T11:29:30.697148Z","end":"2026-08-22T11:29:30.812272Z","steps":["trace[905225721] 'read index received' (duration: 114.930029ms)","trace[905225721] 'applied index is now lower than readState.Index' (duration: 192.684µs)"],"step_count":2} {"level":"warn","ts":"2026-08-22T11:29:30.812438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.274998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T11:29:30.812406Z","caller":"traceutil/trace.go:171","msg":"trace[276484705] transaction","detail":"{read_only:false; response_revision:25205; number_of_response:1; }","duration":"272.827187ms","start":"2026-08-22T11:29:30.539548Z","end":"2026-08-22T11:29:30.812376Z","steps":["trace[276484705] 'process raft request' (duration: 272.587441ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:29:30.812469Z","caller":"traceutil/trace.go:171","msg":"trace[472642554] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:25205; }","duration":"115.36553ms","start":"2026-08-22T11:29:30.697096Z","end":"2026-08-22T11:29:30.812461Z","steps":["trace[472642554] 'agreement among raft nodes before linearized reading' (duration: 115.288468ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:29:31.059251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.566665ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-22T11:29:31.059382Z","caller":"traceutil/trace.go:171","msg":"trace[197160810] transaction","detail":"{read_only:false; response_revision:25206; number_of_response:1; }","duration":"241.776817ms","start":"2026-08-22T11:29:30.817585Z","end":"2026-08-22T11:29:31.059362Z","steps":["trace[197160810] 'process raft request' (duration: 89.799582ms)","trace[197160810] 'compare' (duration: 151.437142ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:29:31.059366Z","caller":"traceutil/trace.go:171","msg":"trace[1788084397] linearizableReadLoop","detail":"{readStateIndex:26032; appliedIndex:26031; }","duration":"179.089181ms","start":"2026-08-22T11:29:30.880258Z","end":"2026-08-22T11:29:31.059348Z","steps":["trace[1788084397] 'read index received' (duration: 27.058175ms)","trace[1788084397] 'applied index is now lower than readState.Index' (duration: 152.029426ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T11:29:31.059692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.372068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeaturerules/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeaturerules0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T11:29:31.059824Z","caller":"traceutil/trace.go:171","msg":"trace[68819363] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeaturerules/; range_end:/registry/nfd.k8s-sigs.io/nodefeaturerules0; response_count:0; response_revision:25206; }","duration":"179.549482ms","start":"2026-08-22T11:29:30.88022Z","end":"2026-08-22T11:29:31.059769Z","steps":["trace[68819363] 'agreement among raft nodes before linearized reading' (duration: 179.237505ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:29:31.084678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.09727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephrbdmirrors/\" range_end:\"/registry/ceph.rook.io/cephrbdmirrors0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T11:29:31.084808Z","caller":"traceutil/trace.go:171","msg":"trace[1294978521] range","detail":"{range_begin:/registry/ceph.rook.io/cephrbdmirrors/; range_end:/registry/ceph.rook.io/cephrbdmirrors0; response_count:0; response_revision:25207; }","duration":"138.282234ms","start":"2026-08-22T11:29:30.946498Z","end":"2026-08-22T11:29:31.08478Z","steps":["trace[1294978521] 'agreement among raft nodes before linearized reading' (duration: 138.046098ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:29:31.085083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.710543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinepools/\" range_end:\"/registry/cluster.x-k8s.io/machinepools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T11:29:31.08569Z","caller":"traceutil/trace.go:171","msg":"trace[723963692] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinepools/; range_end:/registry/cluster.x-k8s.io/machinepools0; response_count:0; response_revision:25207; }","duration":"121.870538ms","start":"2026-08-22T11:29:30.963278Z","end":"2026-08-22T11:29:31.085148Z","steps":["trace[723963692] 'agreement among raft nodes before linearized reading' (duration: 121.721974ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:29:31.381507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.568843ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-22T11:29:31.381726Z","caller":"traceutil/trace.go:171","msg":"trace[1754006934] transaction","detail":"{read_only:false; response_revision:25208; number_of_response:1; }","duration":"290.912825ms","start":"2026-08-22T11:29:31.090791Z","end":"2026-08-22T11:29:31.381704Z","steps":["trace[1754006934] 'process raft request' (duration: 70.029154ms)","trace[1754006934] 'compare' (duration: 220.291487ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:29:31.381755Z","caller":"traceutil/trace.go:171","msg":"trace[1740650753] transaction","detail":"{read_only:false; response_revision:25209; number_of_response:1; }","duration":"147.778193ms","start":"2026-08-22T11:29:31.233957Z","end":"2026-08-22T11:29:31.381735Z","steps":["trace[1740650753] 'process raft request' (duration: 147.67704ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:29:31.38187Z","caller":"traceutil/trace.go:171","msg":"trace[1151215898] linearizableReadLoop","detail":"{readStateIndex:26034; appliedIndex:26033; }","duration":"232.243725ms","start":"2026-08-22T11:29:31.149585Z","end":"2026-08-22T11:29:31.381829Z","steps":["trace[1151215898] 'read index received' (duration: 11.099447ms)","trace[1151215898] 'applied index is now lower than readState.Index' (duration: 221.140598ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T11:29:31.382093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.473651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T11:29:31.382219Z","caller":"traceutil/trace.go:171","msg":"trace[619318553] range","detail":"{range_begin:/registry/ipam.cluster.x-k8s.io/ipaddressclaims/; range_end:/registry/ipam.cluster.x-k8s.io/ipaddressclaims0; response_count:0; response_revision:25209; }","duration":"232.673588ms","start":"2026-08-22T11:29:31.149528Z","end":"2026-08-22T11:29:31.382202Z","steps":["trace[619318553] 'agreement among raft nodes before linearized reading' (duration: 232.38068ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:29:31.384547Z","caller":"traceutil/trace.go:171","msg":"trace[100692179] transaction","detail":"{read_only:false; response_revision:25210; number_of_response:1; }","duration":"136.538402ms","start":"2026-08-22T11:29:31.247971Z","end":"2026-08-22T11:29:31.384509Z","steps":["trace[100692179] 'process raft request' (duration: 136.297866ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:29:31.384734Z","caller":"traceutil/trace.go:171","msg":"trace[1059741275] transaction","detail":"{read_only:false; response_revision:25211; number_of_response:1; }","duration":"136.694285ms","start":"2026-08-22T11:29:31.248022Z","end":"2026-08-22T11:29:31.384716Z","steps":["trace[1059741275] 'process raft request' (duration: 136.425668ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:29:31.411482Z","caller":"traceutil/trace.go:171","msg":"trace[775069395] transaction","detail":"{read_only:false; response_revision:25212; number_of_response:1; }","duration":"149.970997ms","start":"2026-08-22T11:29:31.261485Z","end":"2026-08-22T11:29:31.411456Z","steps":["trace[775069395] 'process raft request' (duration: 149.772482ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:29:31.411781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.670912ms","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-08-22T11:29:31.411878Z","caller":"traceutil/trace.go:171","msg":"trace[1872738732] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25213; }","duration":"128.810895ms","start":"2026-08-22T11:29:31.283039Z","end":"2026-08-22T11:29:31.41185Z","steps":["trace[1872738732] 'agreement among raft nodes before linearized reading' (duration: 128.57865ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:29:31.682861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.149847ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-22T11:29:31.683423Z","caller":"traceutil/trace.go:171","msg":"trace[2065597693] linearizableReadLoop","detail":"{readStateIndex:26040; appliedIndex:26039; }","duration":"196.40108ms","start":"2026-08-22T11:29:31.48693Z","end":"2026-08-22T11:29:31.683331Z","steps":["trace[2065597693] 'read index received' (duration: 52.578582ms)","trace[2065597693] 'applied index is now lower than readState.Index' (duration: 143.453519ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:29:31.683471Z","caller":"traceutil/trace.go:171","msg":"trace[1841556138] transaction","detail":"{read_only:false; response_revision:25214; number_of_response:1; }","duration":"266.627107ms","start":"2026-08-22T11:29:31.416662Z","end":"2026-08-22T11:29:31.683289Z","steps":["trace[1841556138] 'process raft request' (duration: 122.914482ms)","trace[1841556138] 'compare' (duration: 142.017064ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T11:29:31.683546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.574953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/volumetypes/\" range_end:\"/registry/openstack.k-orc.cloud/volumetypes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T11:29:31.683647Z","caller":"traceutil/trace.go:171","msg":"trace[820640409] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/volumetypes/; range_end:/registry/openstack.k-orc.cloud/volumetypes0; response_count:0; response_revision:25214; }","duration":"196.747517ms","start":"2026-08-22T11:29:31.486875Z","end":"2026-08-22T11:29:31.683622Z","steps":["trace[820640409] 'agreement among raft nodes before linearized reading' (duration: 196.560473ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:29:31.962492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.118656ms","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-08-22T11:29:31.962581Z","caller":"traceutil/trace.go:171","msg":"trace[201700179] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25214; }","duration":"118.269591ms","start":"2026-08-22T11:29:31.844292Z","end":"2026-08-22T11:29:31.962562Z","steps":["trace[201700179] 'range keys from in-memory index tree' (duration: 117.629925ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:29:32.28694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.96725ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-22T11:29:32.287048Z","caller":"traceutil/trace.go:171","msg":"trace[742005048] linearizableReadLoop","detail":"{readStateIndex:26042; appliedIndex:26041; }","duration":"222.712336ms","start":"2026-08-22T11:29:32.064317Z","end":"2026-08-22T11:29:32.287029Z","steps":["trace[742005048] 'read index received' (duration: 107.617152ms)","trace[742005048] 'applied index is now lower than readState.Index' (duration: 115.093924ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T11:29:32.287142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.83437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T11:29:32.287171Z","caller":"traceutil/trace.go:171","msg":"trace[1066475814] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25216; }","duration":"222.941041ms","start":"2026-08-22T11:29:32.064221Z","end":"2026-08-22T11:29:32.287162Z","steps":["trace[1066475814] 'agreement among raft nodes before linearized reading' (duration: 222.858489ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:29:32.287468Z","caller":"traceutil/trace.go:171","msg":"trace[668950442] transaction","detail":"{read_only:false; response_revision:25216; number_of_response:1; }","duration":"319.690691ms","start":"2026-08-22T11:29:31.967746Z","end":"2026-08-22T11:29:32.287437Z","steps":["trace[668950442] 'process raft request' (duration: 204.135896ms)","trace[668950442] 'compare' (duration: 114.794076ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T11:29:32.287708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:29:31.967719Z","time spent":"319.815894ms","remote":"127.0.0.1:38840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T11:29:32.602011Z","caller":"traceutil/trace.go:171","msg":"trace[1764236749] linearizableReadLoop","detail":"{readStateIndex:26043; appliedIndex:26042; }","duration":"304.843942ms","start":"2026-08-22T11:29:32.297099Z","end":"2026-08-22T11:29:32.601943Z","steps":["trace[1764236749] 'read index received' (duration: 304.73885ms)","trace[1764236749] 'applied index is now lower than readState.Index' (duration: 103.912µs)"],"step_count":2} {"level":"warn","ts":"2026-08-22T11:29:32.60201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:29:32.289991Z","time spent":"312.009005ms","remote":"127.0.0.1:38582","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-08-22T11:29:32.602455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.316544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" ","response":"range_response_count:1 size:418304"} {"level":"info","ts":"2026-08-22T11:29:32.602557Z","caller":"traceutil/trace.go:171","msg":"trace[483029361] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance; range_end:; response_count:1; response_revision:25216; }","duration":"305.463036ms","start":"2026-08-22T11:29:32.297069Z","end":"2026-08-22T11:29:32.602532Z","steps":["trace[483029361] 'agreement among raft nodes before linearized reading' (duration: 305.034966ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:29:32.602603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:29:32.297055Z","time spent":"305.538749ms","remote":"127.0.0.1:50080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":418328,"request content":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" "} {"level":"warn","ts":"2026-08-22T11:29:32.842495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.727519ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-22T11:29:32.842713Z","caller":"traceutil/trace.go:171","msg":"trace[180172297] transaction","detail":"{read_only:false; response_revision:25217; number_of_response:1; }","duration":"481.075474ms","start":"2026-08-22T11:29:32.361601Z","end":"2026-08-22T11:29:32.842676Z","steps":["trace[180172297] 'process raft request' (duration: 341.086349ms)","trace[180172297] 'compare' (duration: 139.36294ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T11:29:32.842862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:29:32.361576Z","time spent":"481.209027ms","remote":"127.0.0.1:38840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T11:29:32.84306Z","caller":"traceutil/trace.go:171","msg":"trace[1373495077] transaction","detail":"{read_only:false; response_revision:25219; number_of_response:1; }","duration":"160.636844ms","start":"2026-08-22T11:29:32.682403Z","end":"2026-08-22T11:29:32.843039Z","steps":["trace[1373495077] 'process raft request' (duration: 160.293845ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:29:32.843117Z","caller":"traceutil/trace.go:171","msg":"trace[1953665108] linearizableReadLoop","detail":"{readStateIndex:26044; appliedIndex:26043; }","duration":"241.027808ms","start":"2026-08-22T11:29:32.602077Z","end":"2026-08-22T11:29:32.843105Z","steps":["trace[1953665108] 'read index received' (duration: 100.627073ms)","trace[1953665108] 'applied index is now lower than readState.Index' (duration: 140.399825ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:29:32.843202Z","caller":"traceutil/trace.go:171","msg":"trace[202887270] transaction","detail":"{read_only:false; response_revision:25218; number_of_response:1; }","duration":"239.911332ms","start":"2026-08-22T11:29:32.603281Z","end":"2026-08-22T11:29:32.843192Z","steps":["trace[202887270] 'process raft request' (duration: 239.328518ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:29:32.843395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.973511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T11:29:32.843429Z","caller":"traceutil/trace.go:171","msg":"trace[943851767] range","detail":"{range_begin:/registry/acme.cert-manager.io/orders/; range_end:/registry/acme.cert-manager.io/orders0; response_count:0; response_revision:25220; }","duration":"426.053743ms","start":"2026-08-22T11:29:32.417365Z","end":"2026-08-22T11:29:32.843419Z","steps":["trace[943851767] 'agreement among raft nodes before linearized reading' (duration: 425.989421ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:29:32.84346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:29:32.417345Z","time spent":"426.106534ms","remote":"127.0.0.1:42062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":30,"request content":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true "} {"level":"warn","ts":"2026-08-22T11:29:32.843542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.950265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machines/\" range_end:\"/registry/cluster.x-k8s.io/machines0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T11:29:32.84358Z","caller":"traceutil/trace.go:171","msg":"trace[775405524] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machines/; range_end:/registry/cluster.x-k8s.io/machines0; response_count:0; response_revision:25220; }","duration":"181.026147ms","start":"2026-08-22T11:29:32.662543Z","end":"2026-08-22T11:29:32.843569Z","steps":["trace[775405524] 'agreement among raft nodes before linearized reading' (duration: 180.956796ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:29:33.113401Z","caller":"traceutil/trace.go:171","msg":"trace[1386525978] transaction","detail":"{read_only:false; response_revision:25221; number_of_response:1; }","duration":"136.127402ms","start":"2026-08-22T11:29:32.97722Z","end":"2026-08-22T11:29:33.113347Z","steps":["trace[1386525978] 'process raft request' (duration: 135.66958ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:29:49.026346Z","caller":"traceutil/trace.go:171","msg":"trace[211641982] transaction","detail":"{read_only:false; response_revision:25396; number_of_response:1; }","duration":"132.258498ms","start":"2026-08-22T11:29:48.89406Z","end":"2026-08-22T11:29:49.026319Z","steps":["trace[211641982] 'process raft request' (duration: 132.067693ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:29:49.354109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.024911ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-22T11:29:49.354407Z","caller":"traceutil/trace.go:171","msg":"trace[1037521907] transaction","detail":"{read_only:false; response_revision:25399; number_of_response:1; }","duration":"184.394159ms","start":"2026-08-22T11:29:49.169992Z","end":"2026-08-22T11:29:49.354386Z","steps":["trace[1037521907] 'process raft request' (duration: 184.327878ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:29:49.354571Z","caller":"traceutil/trace.go:171","msg":"trace[107664953] linearizableReadLoop","detail":"{readStateIndex:26228; appliedIndex:26225; }","duration":"356.248135ms","start":"2026-08-22T11:29:48.998312Z","end":"2026-08-22T11:29:49.35456Z","steps":["trace[107664953] 'read index received' (duration: 27.938655ms)","trace[107664953] 'applied index is now lower than readState.Index' (duration: 328.30823ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:29:49.354577Z","caller":"traceutil/trace.go:171","msg":"trace[1767165824] transaction","detail":"{read_only:false; response_revision:25398; number_of_response:1; }","duration":"455.431384ms","start":"2026-08-22T11:29:48.899091Z","end":"2026-08-22T11:29:49.354523Z","steps":["trace[1767165824] 'process raft request' (duration: 455.133367ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:29:49.354687Z","caller":"traceutil/trace.go:171","msg":"trace[1926953758] transaction","detail":"{read_only:false; response_revision:25397; number_of_response:1; }","duration":"460.327152ms","start":"2026-08-22T11:29:48.894241Z","end":"2026-08-22T11:29:49.354568Z","steps":["trace[1926953758] 'process raft request' (duration: 246.706246ms)","trace[1926953758] 'compare' (duration: 212.690984ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T11:29:49.354977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:29:48.899071Z","time spent":"455.597228ms","remote":"127.0.0.1:38840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T11:29:49.354973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.097792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T11:29:49.35505Z","caller":"traceutil/trace.go:171","msg":"trace[1168361403] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:25399; }","duration":"189.386189ms","start":"2026-08-22T11:29:49.165648Z","end":"2026-08-22T11:29:49.355035Z","steps":["trace[1168361403] 'agreement among raft nodes before linearized reading' (duration: 189.036711ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:29:49.355109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:29:48.894184Z","time spent":"460.815604ms","remote":"127.0.0.1:38840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T11:29:49.355192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.87121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-66d43a33-767e-461a-9647-0cd86ea14366\" ","response":"range_response_count:1 size:3644"} {"level":"info","ts":"2026-08-22T11:29:49.355239Z","caller":"traceutil/trace.go:171","msg":"trace[331060285] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-66d43a33-767e-461a-9647-0cd86ea14366; range_end:; response_count:1; response_revision:25399; }","duration":"356.948061ms","start":"2026-08-22T11:29:48.998278Z","end":"2026-08-22T11:29:49.355226Z","steps":["trace[331060285] 'agreement among raft nodes before linearized reading' (duration: 356.796428ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:29:49.355277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:29:48.998262Z","time spent":"357.005753ms","remote":"127.0.0.1:38728","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3668,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-66d43a33-767e-461a-9647-0cd86ea14366\" "} {"level":"warn","ts":"2026-08-22T11:29:49.355308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.848049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"warn","ts":"2026-08-22T11:29:49.355271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.369934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1936"} {"level":"info","ts":"2026-08-22T11:29:49.35537Z","caller":"traceutil/trace.go:171","msg":"trace[2125231989] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:25399; }","duration":"116.524437ms","start":"2026-08-22T11:29:49.23882Z","end":"2026-08-22T11:29:49.355344Z","steps":["trace[2125231989] 'agreement among raft nodes before linearized reading' (duration: 116.21757ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:29:49.355327Z","caller":"traceutil/trace.go:171","msg":"trace[906555736] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25399; }","duration":"172.914391ms","start":"2026-08-22T11:29:49.182407Z","end":"2026-08-22T11:29:49.355322Z","steps":["trace[906555736] 'agreement among raft nodes before linearized reading' (duration: 172.814188ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:29:49.690537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.533417ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-22T11:29:49.690631Z","caller":"traceutil/trace.go:171","msg":"trace[170157813] linearizableReadLoop","detail":"{readStateIndex:26230; appliedIndex:26229; }","duration":"331.171398ms","start":"2026-08-22T11:29:49.359444Z","end":"2026-08-22T11:29:49.690615Z","steps":["trace[170157813] 'read index received' (duration: 169.812496ms)","trace[170157813] 'applied index is now lower than readState.Index' (duration: 161.357492ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:29:49.690703Z","caller":"traceutil/trace.go:171","msg":"trace[30119798] transaction","detail":"{read_only:false; response_revision:25400; number_of_response:1; }","duration":"331.321622ms","start":"2026-08-22T11:29:49.359361Z","end":"2026-08-22T11:29:49.690683Z","steps":["trace[30119798] 'process raft request' (duration: 169.56724ms)","trace[30119798] 'compare' (duration: 161.342682ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T11:29:49.690756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:29:49.359344Z","time spent":"331.388634ms","remote":"127.0.0.1:38840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T11:29:49.691033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.571648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-7c77dd75-bddf-48cc-af2f-958b6c1c6d55\" ","response":"range_response_count:1 size:1451"} {"level":"info","ts":"2026-08-22T11:29:49.691108Z","caller":"traceutil/trace.go:171","msg":"trace[1125496959] range","detail":"{range_begin:/registry/persistentvolumes/pvc-7c77dd75-bddf-48cc-af2f-958b6c1c6d55; range_end:; response_count:1; response_revision:25400; }","duration":"331.692621ms","start":"2026-08-22T11:29:49.359397Z","end":"2026-08-22T11:29:49.691089Z","steps":["trace[1125496959] 'agreement among raft nodes before linearized reading' (duration: 331.327312ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:29:49.691142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:29:49.35938Z","time spent":"331.754813ms","remote":"127.0.0.1:38702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1475,"request content":"key:\"/registry/persistentvolumes/pvc-7c77dd75-bddf-48cc-af2f-958b6c1c6d55\" "} {"level":"warn","ts":"2026-08-22T11:29:50.00033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.60238ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-22T11:29:50.000608Z","caller":"traceutil/trace.go:171","msg":"trace[1623258444] linearizableReadLoop","detail":"{readStateIndex:26235; appliedIndex:26230; }","duration":"309.945336ms","start":"2026-08-22T11:29:49.690648Z","end":"2026-08-22T11:29:50.000593Z","steps":["trace[1623258444] 'read index received' (duration: 143.010668ms)","trace[1623258444] 'applied index is now lower than readState.Index' (duration: 166.933828ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:29:50.000665Z","caller":"traceutil/trace.go:171","msg":"trace[762878193] transaction","detail":"{read_only:false; response_revision:25401; number_of_response:1; }","duration":"639.059695ms","start":"2026-08-22T11:29:49.361598Z","end":"2026-08-22T11:29:50.000658Z","steps":["trace[762878193] 'process raft request' (duration: 472.046305ms)","trace[762878193] 'compare' (duration: 166.21462ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T11:29:50.000703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:29:49.36158Z","time spent":"639.101145ms","remote":"127.0.0.1:38728","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3743,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T11:29:50.000926Z","caller":"traceutil/trace.go:171","msg":"trace[1956191492] transaction","detail":"{read_only:false; response_revision:25402; number_of_response:1; }","duration":"492.944841ms","start":"2026-08-22T11:29:49.50797Z","end":"2026-08-22T11:29:50.000915Z","steps":["trace[1956191492] 'process raft request' (duration: 492.469439ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:29:50.000881Z","caller":"traceutil/trace.go:171","msg":"trace[380860186] transaction","detail":"{read_only:false; response_revision:25405; number_of_response:1; }","duration":"448.147568ms","start":"2026-08-22T11:29:49.552678Z","end":"2026-08-22T11:29:50.000826Z","steps":["trace[380860186] 'process raft request' (duration: 447.888811ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:29:50.000917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.29096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/roles/\" range_end:\"/registry/openstack.k-orc.cloud/roles0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-22T11:29:50.000993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.024519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-66d43a33-767e-461a-9647-0cd86ea14366\" ","response":"range_response_count:1 size:3761"} {"level":"warn","ts":"2026-08-22T11:29:50.001017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:29:49.507942Z","time spent":"493.002452ms","remote":"127.0.0.1:38840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T11:29:50.001021Z","caller":"traceutil/trace.go:171","msg":"trace[1011385910] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/roles/; range_end:/registry/openstack.k-orc.cloud/roles0; response_count:0; response_revision:25405; }","duration":"431.454014ms","start":"2026-08-22T11:29:49.569549Z","end":"2026-08-22T11:29:50.001003Z","steps":["trace[1011385910] 'agreement among raft nodes before linearized reading' (duration: 431.28586ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:29:50.001021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.50778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"warn","ts":"2026-08-22T11:29:50.001056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:29:49.569524Z","time spent":"431.523836ms","remote":"127.0.0.1:35328","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":30,"request content":"key:\"/registry/openstack.k-orc.cloud/roles/\" range_end:\"/registry/openstack.k-orc.cloud/roles0\" count_only:true "} {"level":"info","ts":"2026-08-22T11:29:50.001047Z","caller":"traceutil/trace.go:171","msg":"trace[485659239] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-66d43a33-767e-461a-9647-0cd86ea14366; range_end:; response_count:1; response_revision:25405; }","duration":"351.09289ms","start":"2026-08-22T11:29:49.649939Z","end":"2026-08-22T11:29:50.001032Z","steps":["trace[485659239] 'agreement among raft nodes before linearized reading' (duration: 350.960107ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:29:50.001011Z","caller":"traceutil/trace.go:171","msg":"trace[392728577] transaction","detail":"{read_only:false; response_revision:25403; number_of_response:1; }","duration":"449.984152ms","start":"2026-08-22T11:29:49.550993Z","end":"2026-08-22T11:29:50.000978Z","steps":["trace[392728577] 'process raft request' (duration: 449.524761ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:29:50.001068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:29:49.552653Z","time spent":"448.332512ms","remote":"127.0.0.1:38840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T11:29:50.001094Z","caller":"traceutil/trace.go:171","msg":"trace[1981203153] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25405; }","duration":"444.634813ms","start":"2026-08-22T11:29:49.556445Z","end":"2026-08-22T11:29:50.00108Z","steps":["trace[1981203153] 'agreement among raft nodes before linearized reading' (duration: 444.401707ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:29:50.0011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:29:49.649884Z","time spent":"351.206743ms","remote":"127.0.0.1:38728","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3785,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-66d43a33-767e-461a-9647-0cd86ea14366\" "} {"level":"warn","ts":"2026-08-22T11:29:50.00118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:29:49.550966Z","time spent":"450.138816ms","remote":"127.0.0.1:38840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T11:29:50.001114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:29:49.55643Z","time spent":"444.679704ms","remote":"127.0.0.1:38840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-08-22T11:29:50.001196Z","caller":"traceutil/trace.go:171","msg":"trace[2028426112] transaction","detail":"{read_only:false; response_revision:25404; number_of_response:1; }","duration":"448.977527ms","start":"2026-08-22T11:29:49.552206Z","end":"2026-08-22T11:29:50.001184Z","steps":["trace[2028426112] 'process raft request' (duration: 448.340282ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:29:50.001416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:29:49.552193Z","time spent":"449.099701ms","remote":"127.0.0.1:38840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T11:29:50.335491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.525729ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-22T11:29:50.33568Z","caller":"traceutil/trace.go:171","msg":"trace[126989429] transaction","detail":"{read_only:false; response_revision:25407; number_of_response:1; }","duration":"329.641691ms","start":"2026-08-22T11:29:50.006023Z","end":"2026-08-22T11:29:50.335665Z","steps":["trace[126989429] 'process raft request' (duration: 329.56818ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:29:50.33573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:29:50.005998Z","time spent":"329.712522ms","remote":"127.0.0.1:38840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T11:29:50.335823Z","caller":"traceutil/trace.go:171","msg":"trace[614070018] linearizableReadLoop","detail":"{readStateIndex:26236; appliedIndex:26235; }","duration":"335.188485ms","start":"2026-08-22T11:29:50.000629Z","end":"2026-08-22T11:29:50.335817Z","steps":["trace[614070018] 'read index received' (duration: 165.238236ms)","trace[614070018] 'applied index is now lower than readState.Index' (duration: 169.949419ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:29:50.335801Z","caller":"traceutil/trace.go:171","msg":"trace[1257172434] transaction","detail":"{read_only:false; response_revision:25406; number_of_response:1; }","duration":"442.570592ms","start":"2026-08-22T11:29:49.893182Z","end":"2026-08-22T11:29:50.335752Z","steps":["trace[1257172434] 'process raft request' (duration: 272.672415ms)","trace[1257172434] 'compare' (duration: 169.233862ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T11:29:50.335922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.736702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T11:29:50.335954Z","caller":"traceutil/trace.go:171","msg":"trace[140140001] range","detail":"{range_begin:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/; range_end:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0; response_count:0; response_revision:25407; }","duration":"417.820244ms","start":"2026-08-22T11:29:49.918126Z","end":"2026-08-22T11:29:50.335946Z","steps":["trace[140140001] 'agreement among raft nodes before linearized reading' (duration: 417.757002ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:29:50.335972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:29:49.918088Z","time spent":"417.879326ms","remote":"127.0.0.1:45432","response type":"/etcdserverpb.KV/Range","request count":0,"request size":144,"response count":0,"response size":30,"request content":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0\" count_only:true "} {"level":"warn","ts":"2026-08-22T11:29:50.33599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:29:49.893159Z","time spent":"442.728457ms","remote":"127.0.0.1:38840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":628,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T11:29:50.336132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"641.121183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1782"} {"level":"info","ts":"2026-08-22T11:29:50.336152Z","caller":"traceutil/trace.go:171","msg":"trace[1585174836] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:25407; }","duration":"641.154814ms","start":"2026-08-22T11:29:49.694992Z","end":"2026-08-22T11:29:50.336147Z","steps":["trace[1585174836] 'agreement among raft nodes before linearized reading' (duration: 641.071922ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:29:50.336166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:29:49.694979Z","time spent":"641.183234ms","remote":"127.0.0.1:38706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":1806,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" "} {"level":"warn","ts":"2026-08-22T11:29:50.336168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"517.144845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephbuckettopics/\" range_end:\"/registry/ceph.rook.io/cephbuckettopics0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-22T11:29:50.336259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.896108ms","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-08-22T11:29:50.336278Z","caller":"traceutil/trace.go:171","msg":"trace[244479713] 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:25407; }","duration":"310.95113ms","start":"2026-08-22T11:29:50.02532Z","end":"2026-08-22T11:29:50.336271Z","steps":["trace[244479713] 'agreement among raft nodes before linearized reading' (duration: 310.917049ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:29:50.336292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:29:50.025299Z","time spent":"310.9897ms","remote":"127.0.0.1:45398","response type":"/etcdserverpb.KV/Range","request count":0,"request size":126,"response count":0,"response size":30,"request content":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0\" count_only:true "} {"level":"info","ts":"2026-08-22T11:29:50.336294Z","caller":"traceutil/trace.go:171","msg":"trace[1014907355] range","detail":"{range_begin:/registry/ceph.rook.io/cephbuckettopics/; range_end:/registry/ceph.rook.io/cephbuckettopics0; response_count:0; response_revision:25407; }","duration":"517.326901ms","start":"2026-08-22T11:29:49.818935Z","end":"2026-08-22T11:29:50.336261Z","steps":["trace[1014907355] 'agreement among raft nodes before linearized reading' (duration: 517.123845ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:29:50.336366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:29:49.818913Z","time spent":"517.436214ms","remote":"127.0.0.1:40240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":30,"request content":"key:\"/registry/ceph.rook.io/cephbuckettopics/\" range_end:\"/registry/ceph.rook.io/cephbuckettopics0\" count_only:true "} {"level":"warn","ts":"2026-08-22T11:29:50.336418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.942907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-08-22T11:29:50.336436Z","caller":"traceutil/trace.go:171","msg":"trace[2091084801] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25407; }","duration":"174.000669ms","start":"2026-08-22T11:29:50.162431Z","end":"2026-08-22T11:29:50.336432Z","steps":["trace[2091084801] 'agreement among raft nodes before linearized reading' (duration: 173.940177ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:29:50.336479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"529.010583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T11:29:50.33656Z","caller":"traceutil/trace.go:171","msg":"trace[1181716593] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25407; }","duration":"529.121326ms","start":"2026-08-22T11:29:49.807419Z","end":"2026-08-22T11:29:50.33654Z","steps":["trace[1181716593] 'agreement among raft nodes before linearized reading' (duration: 528.995923ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:29:50.336609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:29:49.807401Z","time spent":"529.196538ms","remote":"127.0.0.1:38552","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-22T11:29:50.336572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"575.988569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-08-22T11:29:50.336677Z","caller":"traceutil/trace.go:171","msg":"trace[907084365] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25407; }","duration":"576.131673ms","start":"2026-08-22T11:29:49.760524Z","end":"2026-08-22T11:29:50.336655Z","steps":["trace[907084365] 'agreement among raft nodes before linearized reading' (duration: 575.387724ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:29:50.336729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:29:49.760508Z","time spent":"576.212004ms","remote":"127.0.0.1:38840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":519,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-22T11:29:50.589372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.885219ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-22T11:29:50.589634Z","caller":"traceutil/trace.go:171","msg":"trace[812613921] transaction","detail":"{read_only:false; response_revision:25408; number_of_response:1; }","duration":"249.458203ms","start":"2026-08-22T11:29:50.34015Z","end":"2026-08-22T11:29:50.589608Z","steps":["trace[812613921] 'process raft request' (duration: 129.251346ms)","trace[812613921] 'compare' (duration: 119.718225ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:29:50.590111Z","caller":"traceutil/trace.go:171","msg":"trace[1752710343] linearizableReadLoop","detail":"{readStateIndex:26239; appliedIndex:26237; }","duration":"220.203414ms","start":"2026-08-22T11:29:50.369894Z","end":"2026-08-22T11:29:50.590097Z","steps":["trace[1752710343] 'read index received' (duration: 99.526996ms)","trace[1752710343] 'applied index is now lower than readState.Index' (duration: 120.675328ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:29:50.590183Z","caller":"traceutil/trace.go:171","msg":"trace[777674804] transaction","detail":"{read_only:false; response_revision:25409; number_of_response:1; }","duration":"249.537995ms","start":"2026-08-22T11:29:50.340613Z","end":"2026-08-22T11:29:50.590151Z","steps":["trace[777674804] 'process raft request' (duration: 248.951611ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:29:50.59034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.43478ms","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-08-22T11:29:50.590397Z","caller":"traceutil/trace.go:171","msg":"trace[1218951] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25409; }","duration":"220.543812ms","start":"2026-08-22T11:29:50.369839Z","end":"2026-08-22T11:29:50.590383Z","steps":["trace[1218951] 'agreement among raft nodes before linearized reading' (duration: 220.312967ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:29:50.884162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.566041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T11:29:50.884257Z","caller":"traceutil/trace.go:171","msg":"trace[2081378530] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25411; }","duration":"193.719905ms","start":"2026-08-22T11:29:50.690516Z","end":"2026-08-22T11:29:50.884235Z","steps":["trace[2081378530] 'range keys from in-memory index tree' (duration: 193.459269ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:29:51.272113Z","caller":"traceutil/trace.go:171","msg":"trace[1064284721] transaction","detail":"{read_only:false; response_revision:25412; number_of_response:1; }","duration":"240.320141ms","start":"2026-08-22T11:29:51.031765Z","end":"2026-08-22T11:29:51.272085Z","steps":["trace[1064284721] 'process raft request' (duration: 240.070075ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:29:51.524938Z","caller":"traceutil/trace.go:171","msg":"trace[1650957228] transaction","detail":"{read_only:false; response_revision:25413; number_of_response:1; }","duration":"163.638998ms","start":"2026-08-22T11:29:51.361263Z","end":"2026-08-22T11:29:51.524902Z","steps":["trace[1650957228] 'process raft request' (duration: 119.669675ms)","trace[1650957228] 'compare' (duration: 43.810869ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:29:51.548425Z","caller":"traceutil/trace.go:171","msg":"trace[2091094487] transaction","detail":"{read_only:false; response_revision:25415; number_of_response:1; }","duration":"186.663754ms","start":"2026-08-22T11:29:51.361735Z","end":"2026-08-22T11:29:51.548398Z","steps":["trace[2091094487] 'process raft request' (duration: 186.616773ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:29:51.54859Z","caller":"traceutil/trace.go:171","msg":"trace[1915992854] transaction","detail":"{read_only:false; response_revision:25414; number_of_response:1; }","duration":"187.003051ms","start":"2026-08-22T11:29:51.361579Z","end":"2026-08-22T11:29:51.548582Z","steps":["trace[1915992854] 'process raft request' (duration: 186.648413ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:29:51.859084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.713069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-08-22T11:29:51.859465Z","caller":"traceutil/trace.go:171","msg":"trace[1244167700] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25415; }","duration":"164.139639ms","start":"2026-08-22T11:29:51.695306Z","end":"2026-08-22T11:29:51.859445Z","steps":["trace[1244167700] 'range keys from in-memory index tree' (duration: 163.515183ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:29:51.885458Z","caller":"traceutil/trace.go:171","msg":"trace[1198880429] linearizableReadLoop","detail":"{readStateIndex:26246; appliedIndex:26245; }","duration":"166.075047ms","start":"2026-08-22T11:29:51.719356Z","end":"2026-08-22T11:29:51.885431Z","steps":["trace[1198880429] 'read index received' (duration: 165.919353ms)","trace[1198880429] 'applied index is now lower than readState.Index' (duration: 154.724µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:29:51.885742Z","caller":"traceutil/trace.go:171","msg":"trace[1125793020] transaction","detail":"{read_only:false; response_revision:25416; number_of_response:1; }","duration":"168.931126ms","start":"2026-08-22T11:29:51.7168Z","end":"2026-08-22T11:29:51.885731Z","steps":["trace[1125793020] 'process raft request' (duration: 168.490545ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:29:51.885901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.534618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemmirrors/\" range_end:\"/registry/ceph.rook.io/cephfilesystemmirrors0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T11:29:51.885924Z","caller":"traceutil/trace.go:171","msg":"trace[829029765] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemmirrors/; range_end:/registry/ceph.rook.io/cephfilesystemmirrors0; response_count:0; response_revision:25416; }","duration":"166.62558ms","start":"2026-08-22T11:29:51.719291Z","end":"2026-08-22T11:29:51.885917Z","steps":["trace[829029765] 'agreement among raft nodes before linearized reading' (duration: 166.565838ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:29:52.071643Z","caller":"traceutil/trace.go:171","msg":"trace[49041929] transaction","detail":"{read_only:false; response_revision:25417; number_of_response:1; }","duration":"208.01781ms","start":"2026-08-22T11:29:51.863599Z","end":"2026-08-22T11:29:52.071617Z","steps":["trace[49041929] 'process raft request' (duration: 207.772595ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:29:52.071606Z","caller":"traceutil/trace.go:171","msg":"trace[470241314] linearizableReadLoop","detail":"{readStateIndex:26247; appliedIndex:26246; }","duration":"186.043708ms","start":"2026-08-22T11:29:51.885527Z","end":"2026-08-22T11:29:52.071571Z","steps":["trace[470241314] 'read index received' (duration: 185.877844ms)","trace[470241314] 'applied index is now lower than readState.Index' (duration: 164.544µs)"],"step_count":2} {"level":"warn","ts":"2026-08-22T11:29:52.071815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.593305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T11:29:52.071849Z","caller":"traceutil/trace.go:171","msg":"trace[2134485458] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25417; }","duration":"263.684477ms","start":"2026-08-22T11:29:51.808153Z","end":"2026-08-22T11:29:52.071837Z","steps":["trace[2134485458] 'agreement among raft nodes before linearized reading' (duration: 263.601635ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:29:52.706851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.789594ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T11:29:52.707002Z","caller":"traceutil/trace.go:171","msg":"trace[1307211145] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:25421; }","duration":"226.961638ms","start":"2026-08-22T11:29:52.48002Z","end":"2026-08-22T11:29:52.706982Z","steps":["trace[1307211145] 'range keys from in-memory index tree' (duration: 226.766784ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:29:52.707477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.938457ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-22T11:29:52.707621Z","caller":"traceutil/trace.go:171","msg":"trace[1126303830] transaction","detail":"{read_only:false; response_revision:25422; number_of_response:1; }","duration":"298.332984ms","start":"2026-08-22T11:29:52.409264Z","end":"2026-08-22T11:29:52.707597Z","steps":["trace[1126303830] 'process raft request' (duration: 185.927246ms)","trace[1126303830] 'compare' (duration: 111.751482ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T11:29:53.069779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.04964ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-22T11:29:53.069888Z","caller":"traceutil/trace.go:171","msg":"trace[1012295449] linearizableReadLoop","detail":"{readStateIndex:26254; appliedIndex:26252; }","duration":"631.991253ms","start":"2026-08-22T11:29:52.437879Z","end":"2026-08-22T11:29:53.06987Z","steps":["trace[1012295449] 'read index received' (duration: 157.313374ms)","trace[1012295449] 'applied index is now lower than readState.Index' (duration: 474.676579ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:29:53.07038Z","caller":"traceutil/trace.go:171","msg":"trace[1120573477] transaction","detail":"{read_only:false; response_revision:25423; number_of_response:1; }","duration":"658.577077ms","start":"2026-08-22T11:29:52.41178Z","end":"2026-08-22T11:29:53.070357Z","steps":["trace[1120573477] 'process raft request' (duration: 497.85358ms)","trace[1120573477] 'compare' (duration: 159.505057ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T11:29:53.07047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"453.919337ms","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-08-22T11:29:53.070512Z","caller":"traceutil/trace.go:171","msg":"trace[2122404073] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25423; }","duration":"454.004361ms","start":"2026-08-22T11:29:52.616492Z","end":"2026-08-22T11:29:53.070497Z","steps":["trace[2122404073] 'agreement among raft nodes before linearized reading' (duration: 453.801385ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:29:53.070486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.066443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"warn","ts":"2026-08-22T11:29:53.070548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:29:52.616468Z","time spent":"454.073572ms","remote":"127.0.0.1:38840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":465,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-08-22T11:29:53.070568Z","caller":"traceutil/trace.go:171","msg":"trace[1043764541] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:25423; }","duration":"182.199576ms","start":"2026-08-22T11:29:52.888349Z","end":"2026-08-22T11:29:53.070548Z","steps":["trace[1043764541] 'agreement among raft nodes before linearized reading' (duration: 181.937119ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:29:53.070631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:29:52.411756Z","time spent":"658.7227ms","remote":"127.0.0.1:38840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T11:29:53.070542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"632.64975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3309"} {"level":"warn","ts":"2026-08-22T11:29:53.070734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.437491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:423"} {"level":"warn","ts":"2026-08-22T11:29:53.070734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.14804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-08-22T11:29:53.070786Z","caller":"traceutil/trace.go:171","msg":"trace[1576422052] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:25423; }","duration":"361.522142ms","start":"2026-08-22T11:29:52.709251Z","end":"2026-08-22T11:29:53.070774Z","steps":["trace[1576422052] 'agreement among raft nodes before linearized reading' (duration: 361.100022ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:29:53.070411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"475.822928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-08-22T11:29:53.070794Z","caller":"traceutil/trace.go:171","msg":"trace[1614412514] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25423; }","duration":"477.247553ms","start":"2026-08-22T11:29:52.593531Z","end":"2026-08-22T11:29:53.070779Z","steps":["trace[1614412514] 'agreement among raft nodes before linearized reading' (duration: 476.740669ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:29:53.07082Z","caller":"traceutil/trace.go:171","msg":"trace[932403711] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25423; }","duration":"476.259697ms","start":"2026-08-22T11:29:52.594553Z","end":"2026-08-22T11:29:53.070812Z","steps":["trace[932403711] 'agreement among raft nodes before linearized reading' (duration: 475.749444ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:29:53.070833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:29:52.593509Z","time spent":"477.311154ms","remote":"127.0.0.1:38840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":519,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-22T11:29:53.070818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:29:52.709231Z","time spent":"361.580384ms","remote":"127.0.0.1:38710","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":447,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"warn","ts":"2026-08-22T11:29:53.070851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:29:52.594529Z","time spent":"476.314969ms","remote":"127.0.0.1:38840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-22T11:29:53.07039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.846772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T11:29:53.070783Z","caller":"traceutil/trace.go:171","msg":"trace[549532201] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:25423; }","duration":"632.947287ms","start":"2026-08-22T11:29:52.437807Z","end":"2026-08-22T11:29:53.070754Z","steps":["trace[549532201] 'agreement among raft nodes before linearized reading' (duration: 632.518827ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:29:53.070982Z","caller":"traceutil/trace.go:171","msg":"trace[1416529722] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:25423; }","duration":"166.497407ms","start":"2026-08-22T11:29:52.904471Z","end":"2026-08-22T11:29:53.070969Z","steps":["trace[1416529722] 'agreement among raft nodes before linearized reading' (duration: 165.860872ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:29:53.071147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:29:52.437777Z","time spent":"633.353007ms","remote":"127.0.0.1:41332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":1,"response size":3333,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" "} {"level":"info","ts":"2026-08-22T11:29:53.269439Z","caller":"traceutil/trace.go:171","msg":"trace[1924375079] linearizableReadLoop","detail":"{readStateIndex:26256; appliedIndex:26254; }","duration":"192.596858ms","start":"2026-08-22T11:29:53.076816Z","end":"2026-08-22T11:29:53.269412Z","steps":["trace[1924375079] 'read index received' (duration: 83.055948ms)","trace[1924375079] 'applied index is now lower than readState.Index' (duration: 109.53985ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:29:53.269431Z","caller":"traceutil/trace.go:171","msg":"trace[1817939942] transaction","detail":"{read_only:false; response_revision:25425; number_of_response:1; }","duration":"193.51998ms","start":"2026-08-22T11:29:53.075866Z","end":"2026-08-22T11:29:53.269386Z","steps":["trace[1817939942] 'process raft request' (duration: 193.277873ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:29:53.269535Z","caller":"traceutil/trace.go:171","msg":"trace[278557919] transaction","detail":"{read_only:false; response_revision:25426; number_of_response:1; }","duration":"192.428323ms","start":"2026-08-22T11:29:53.077093Z","end":"2026-08-22T11:29:53.269522Z","steps":["trace[278557919] 'process raft request' (duration: 192.208468ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:29:53.269594Z","caller":"traceutil/trace.go:171","msg":"trace[393771019] transaction","detail":"{read_only:false; response_revision:25427; number_of_response:1; }","duration":"167.202563ms","start":"2026-08-22T11:29:53.102356Z","end":"2026-08-22T11:29:53.269559Z","steps":["trace[393771019] 'process raft request' (duration: 166.992348ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:29:53.270435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.424175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-66d43a33-767e-461a-9647-0cd86ea14366\" ","response":"range_response_count:1 size:3761"} {"level":"warn","ts":"2026-08-22T11:29:53.269705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.852774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T11:29:53.270498Z","caller":"traceutil/trace.go:171","msg":"trace[1620406715] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-66d43a33-767e-461a-9647-0cd86ea14366; range_end:; response_count:1; response_revision:25427; }","duration":"171.518847ms","start":"2026-08-22T11:29:53.098954Z","end":"2026-08-22T11:29:53.270473Z","steps":["trace[1620406715] 'agreement among raft nodes before linearized reading' (duration: 171.341403ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:29:53.270549Z","caller":"traceutil/trace.go:171","msg":"trace[1851699831] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:25427; }","duration":"193.755905ms","start":"2026-08-22T11:29:53.076774Z","end":"2026-08-22T11:29:53.27053Z","steps":["trace[1851699831] 'agreement among raft nodes before linearized reading' (duration: 192.804253ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:29:55.389539Z","caller":"traceutil/trace.go:171","msg":"trace[18238372] linearizableReadLoop","detail":"{readStateIndex:26273; appliedIndex:26272; }","duration":"107.205282ms","start":"2026-08-22T11:29:55.282308Z","end":"2026-08-22T11:29:55.389513Z","steps":["trace[18238372] 'read index received' (duration: 107.166831ms)","trace[18238372] 'applied index is now lower than readState.Index' (duration: 37.401µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:29:55.389646Z","caller":"traceutil/trace.go:171","msg":"trace[525843051] transaction","detail":"{read_only:false; response_revision:25442; number_of_response:1; }","duration":"111.335722ms","start":"2026-08-22T11:29:55.278303Z","end":"2026-08-22T11:29:55.389639Z","steps":["trace[525843051] 'process raft request' (duration: 111.071146ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:29:55.38982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.493429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-08-22T11:29:55.389842Z","caller":"traceutil/trace.go:171","msg":"trace[1148017970] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25442; }","duration":"107.544171ms","start":"2026-08-22T11:29:55.282291Z","end":"2026-08-22T11:29:55.389836Z","steps":["trace[1148017970] 'agreement among raft nodes before linearized reading' (duration: 107.450019ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:29:55.566196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.785662ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-22T11:29:55.566441Z","caller":"traceutil/trace.go:171","msg":"trace[1305653091] transaction","detail":"{read_only:false; response_revision:25445; number_of_response:1; }","duration":"171.58125ms","start":"2026-08-22T11:29:55.39484Z","end":"2026-08-22T11:29:55.566421Z","steps":["trace[1305653091] 'process raft request' (duration: 171.530459ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:29:55.566591Z","caller":"traceutil/trace.go:171","msg":"trace[741326949] linearizableReadLoop","detail":"{readStateIndex:26275; appliedIndex:26273; }","duration":"176.959851ms","start":"2026-08-22T11:29:55.389608Z","end":"2026-08-22T11:29:55.566568Z","steps":["trace[741326949] 'read index received' (duration: 38.752407ms)","trace[741326949] 'applied index is now lower than readState.Index' (duration: 138.204293ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:29:55.56658Z","caller":"traceutil/trace.go:171","msg":"trace[273708214] transaction","detail":"{read_only:false; response_revision:25444; number_of_response:1; }","duration":"224.860769ms","start":"2026-08-22T11:29:55.341688Z","end":"2026-08-22T11:29:55.566549Z","steps":["trace[273708214] 'process raft request' (duration: 224.610362ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:29:55.566683Z","caller":"traceutil/trace.go:171","msg":"trace[810750483] transaction","detail":"{read_only:false; response_revision:25443; number_of_response:1; }","duration":"282.996644ms","start":"2026-08-22T11:29:55.283654Z","end":"2026-08-22T11:29:55.566651Z","steps":["trace[810750483] 'process raft request' (duration: 144.665947ms)","trace[810750483] 'compare' (duration: 137.640849ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T11:29:55.566796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.775311ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-66d43a33-767e-461a-9647-0cd86ea14366\" ","response":"range_response_count:1 size:3947"} {"level":"info","ts":"2026-08-22T11:29:55.566848Z","caller":"traceutil/trace.go:171","msg":"trace[93394161] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-66d43a33-767e-461a-9647-0cd86ea14366; range_end:; response_count:1; response_revision:25445; }","duration":"280.842103ms","start":"2026-08-22T11:29:55.285989Z","end":"2026-08-22T11:29:55.566831Z","steps":["trace[93394161] 'agreement among raft nodes before linearized reading' (duration: 280.649278ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:30:03.432531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.145714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T11:30:03.43264Z","caller":"traceutil/trace.go:171","msg":"trace[1461535792] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:25545; }","duration":"127.293028ms","start":"2026-08-22T11:30:03.305325Z","end":"2026-08-22T11:30:03.432618Z","steps":["trace[1461535792] 'range keys from in-memory index tree' (duration: 127.046161ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:30:03.693556Z","caller":"traceutil/trace.go:171","msg":"trace[562670063] transaction","detail":"{read_only:false; response_revision:25546; number_of_response:1; }","duration":"248.95775ms","start":"2026-08-22T11:30:03.444577Z","end":"2026-08-22T11:30:03.693534Z","steps":["trace[562670063] 'process raft request' (duration: 248.852118ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:30:03.693599Z","caller":"traceutil/trace.go:171","msg":"trace[1298105029] linearizableReadLoop","detail":"{readStateIndex:26380; appliedIndex:26380; }","duration":"168.529936ms","start":"2026-08-22T11:30:03.525022Z","end":"2026-08-22T11:30:03.693552Z","steps":["trace[1298105029] 'read index received' (duration: 168.509075ms)","trace[1298105029] 'applied index is now lower than readState.Index' (duration: 18.001µs)"],"step_count":2} {"level":"warn","ts":"2026-08-22T11:30:03.693876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.818643ms","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:6"} {"level":"info","ts":"2026-08-22T11:30:03.693984Z","caller":"traceutil/trace.go:171","msg":"trace[57346642] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:25546; }","duration":"168.957566ms","start":"2026-08-22T11:30:03.525005Z","end":"2026-08-22T11:30:03.693963Z","steps":["trace[57346642] 'agreement among raft nodes before linearized reading' (duration: 168.729711ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:30:03.872007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.442568ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-22T11:30:03.872273Z","caller":"traceutil/trace.go:171","msg":"trace[1155946547] linearizableReadLoop","detail":"{readStateIndex:26384; appliedIndex:26380; }","duration":"178.568997ms","start":"2026-08-22T11:30:03.693685Z","end":"2026-08-22T11:30:03.872254Z","steps":["trace[1155946547] 'read index received' (duration: 51.805962ms)","trace[1155946547] 'applied index is now lower than readState.Index' (duration: 126.761565ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:30:03.872527Z","caller":"traceutil/trace.go:171","msg":"trace[221993925] transaction","detail":"{read_only:false; response_revision:25547; number_of_response:1; }","duration":"283.071966ms","start":"2026-08-22T11:30:03.58944Z","end":"2026-08-22T11:30:03.872512Z","steps":["trace[221993925] 'process raft request' (duration: 156.035554ms)","trace[221993925] 'compare' (duration: 126.323935ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:30:03.872722Z","caller":"traceutil/trace.go:171","msg":"trace[494050073] transaction","detail":"{read_only:false; response_revision:25548; number_of_response:1; }","duration":"219.466838ms","start":"2026-08-22T11:30:03.653246Z","end":"2026-08-22T11:30:03.872712Z","steps":["trace[494050073] 'process raft request' (duration: 218.869793ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:30:03.872836Z","caller":"traceutil/trace.go:171","msg":"trace[1571650709] transaction","detail":"{read_only:false; response_revision:25549; number_of_response:1; }","duration":"218.597816ms","start":"2026-08-22T11:30:03.654229Z","end":"2026-08-22T11:30:03.872827Z","steps":["trace[1571650709] 'process raft request' (duration: 217.942379ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:30:03.873155Z","caller":"traceutil/trace.go:171","msg":"trace[1672567405] transaction","detail":"{read_only:false; response_revision:25550; number_of_response:1; }","duration":"218.216167ms","start":"2026-08-22T11:30:03.654915Z","end":"2026-08-22T11:30:03.873131Z","steps":["trace[1672567405] 'process raft request' (duration: 217.292434ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:30:03.873314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.650316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T11:30:03.873348Z","caller":"traceutil/trace.go:171","msg":"trace[777815209] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesets/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesets0; response_count:0; response_revision:25550; }","duration":"239.725587ms","start":"2026-08-22T11:30:03.633612Z","end":"2026-08-22T11:30:03.873338Z","steps":["trace[777815209] 'agreement among raft nodes before linearized reading' (duration: 239.651355ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:30:03.873459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.247501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-08-22T11:30:03.873563Z","caller":"traceutil/trace.go:171","msg":"trace[1259360622] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25550; }","duration":"276.379304ms","start":"2026-08-22T11:30:03.597157Z","end":"2026-08-22T11:30:03.873536Z","steps":["trace[1259360622] 'agreement among raft nodes before linearized reading' (duration: 276.140598ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:30:03.9858Z","caller":"traceutil/trace.go:171","msg":"trace[341970462] transaction","detail":"{read_only:false; response_revision:25551; number_of_response:1; }","duration":"108.243108ms","start":"2026-08-22T11:30:03.877534Z","end":"2026-08-22T11:30:03.985777Z","steps":["trace[341970462] 'process raft request' (duration: 107.429719ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:30:09.743399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.715954ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-22T11:30:09.743583Z","caller":"traceutil/trace.go:171","msg":"trace[839866251] transaction","detail":"{read_only:false; response_revision:25593; number_of_response:1; }","duration":"421.213736ms","start":"2026-08-22T11:30:09.322317Z","end":"2026-08-22T11:30:09.743531Z","steps":["trace[839866251] 'process raft request' (duration: 165.219806ms)","trace[839866251] 'compare' (duration: 255.468398ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T11:30:09.743752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:30:09.322284Z","time spent":"421.38472ms","remote":"127.0.0.1:38840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T11:30:11.869496Z","caller":"traceutil/trace.go:171","msg":"trace[654961968] linearizableReadLoop","detail":"{readStateIndex:26446; appliedIndex:26445; }","duration":"107.51744ms","start":"2026-08-22T11:30:11.761948Z","end":"2026-08-22T11:30:11.869466Z","steps":["trace[654961968] 'read index received' (duration: 107.282424ms)","trace[654961968] 'applied index is now lower than readState.Index' (duration: 233.756µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:30:11.86954Z","caller":"traceutil/trace.go:171","msg":"trace[1727119535] transaction","detail":"{read_only:false; response_revision:25611; number_of_response:1; }","duration":"116.864217ms","start":"2026-08-22T11:30:11.752636Z","end":"2026-08-22T11:30:11.869501Z","steps":["trace[1727119535] 'process raft request' (duration: 116.619241ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:30:11.869823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.833057ms","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-08-22T11:30:11.869959Z","caller":"traceutil/trace.go:171","msg":"trace[1810655465] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25611; }","duration":"108.014402ms","start":"2026-08-22T11:30:11.761914Z","end":"2026-08-22T11:30:11.869929Z","steps":["trace[1810655465] 'agreement among raft nodes before linearized reading' (duration: 107.669524ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:30:17.784539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.453319ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:30"} {"level":"warn","ts":"2026-08-22T11:30:17.784862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.47106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T11:30:17.784946Z","caller":"traceutil/trace.go:171","msg":"trace[515752541] range","detail":"{range_begin:/registry/ipam.cluster.x-k8s.io/ipaddressclaims/; range_end:/registry/ipam.cluster.x-k8s.io/ipaddressclaims0; response_count:0; response_revision:25671; }","duration":"155.575823ms","start":"2026-08-22T11:30:17.629351Z","end":"2026-08-22T11:30:17.784927Z","steps":["trace[515752541] 'agreement among raft nodes before linearized reading' (duration: 155.426979ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:30:17.7847Z","caller":"traceutil/trace.go:171","msg":"trace[303732091] linearizableReadLoop","detail":"{readStateIndex:26509; appliedIndex:26508; }","duration":"155.313657ms","start":"2026-08-22T11:30:17.629357Z","end":"2026-08-22T11:30:17.784671Z","steps":["trace[303732091] 'read index received' (duration: 39.121µs)","trace[303732091] 'applied index is now lower than readState.Index' (duration: 155.272876ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T11:30:17.785388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.539177ms","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-08-22T11:30:17.785451Z","caller":"traceutil/trace.go:171","msg":"trace[131870776] 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:25671; }","duration":"106.639899ms","start":"2026-08-22T11:30:17.678789Z","end":"2026-08-22T11:30:17.785429Z","steps":["trace[131870776] 'agreement among raft nodes before linearized reading' (duration: 106.535816ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:30:18.030424Z","caller":"traceutil/trace.go:171","msg":"trace[222285804] linearizableReadLoop","detail":"{readStateIndex:26510; appliedIndex:26509; }","duration":"112.104481ms","start":"2026-08-22T11:30:17.918293Z","end":"2026-08-22T11:30:18.030398Z","steps":["trace[222285804] 'read index received' (duration: 111.908647ms)","trace[222285804] 'applied index is now lower than readState.Index' (duration: 194.674µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:30:18.030521Z","caller":"traceutil/trace.go:171","msg":"trace[1013604441] transaction","detail":"{read_only:false; response_revision:25672; number_of_response:1; }","duration":"118.328521ms","start":"2026-08-22T11:30:17.912162Z","end":"2026-08-22T11:30:18.030491Z","steps":["trace[1013604441] 'process raft request' (duration: 118.055075ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:30:18.030604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.302576ms","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-08-22T11:30:18.030631Z","caller":"traceutil/trace.go:171","msg":"trace[542550796] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25672; }","duration":"112.378769ms","start":"2026-08-22T11:30:17.918245Z","end":"2026-08-22T11:30:18.030623Z","steps":["trace[542550796] 'agreement among raft nodes before linearized reading' (duration: 112.264805ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:30:18.145218Z","caller":"traceutil/trace.go:171","msg":"trace[1914952110] linearizableReadLoop","detail":"{readStateIndex:26514; appliedIndex:26513; }","duration":"114.706954ms","start":"2026-08-22T11:30:18.030485Z","end":"2026-08-22T11:30:18.145192Z","steps":["trace[1914952110] 'read index received' (duration: 114.674523ms)","trace[1914952110] 'applied index is now lower than readState.Index' (duration: 31.411µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:30:18.14526Z","caller":"traceutil/trace.go:171","msg":"trace[1783541099] transaction","detail":"{read_only:false; response_revision:25673; number_of_response:1; }","duration":"201.987844ms","start":"2026-08-22T11:30:17.943237Z","end":"2026-08-22T11:30:18.145224Z","steps":["trace[1783541099] 'process raft request' (duration: 201.696157ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:30:18.145337Z","caller":"traceutil/trace.go:171","msg":"trace[1410367416] transaction","detail":"{read_only:false; response_revision:25674; number_of_response:1; }","duration":"201.970273ms","start":"2026-08-22T11:30:17.943358Z","end":"2026-08-22T11:30:18.145328Z","steps":["trace[1410367416] 'process raft request' (duration: 201.748358ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:30:18.145441Z","caller":"traceutil/trace.go:171","msg":"trace[1677471191] transaction","detail":"{read_only:false; response_revision:25675; number_of_response:1; }","duration":"199.720239ms","start":"2026-08-22T11:30:17.945715Z","end":"2026-08-22T11:30:18.145436Z","steps":["trace[1677471191] 'process raft request' (duration: 199.423482ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:30:18.145506Z","caller":"traceutil/trace.go:171","msg":"trace[532048940] transaction","detail":"{read_only:false; response_revision:25676; number_of_response:1; }","duration":"195.908898ms","start":"2026-08-22T11:30:17.949592Z","end":"2026-08-22T11:30:18.145501Z","steps":["trace[532048940] 'process raft request' (duration: 195.570119ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:30:18.145622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.611488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-22T11:30:18.145655Z","caller":"traceutil/trace.go:171","msg":"trace[2127080071] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:25676; }","duration":"192.715481ms","start":"2026-08-22T11:30:17.95293Z","end":"2026-08-22T11:30:18.145646Z","steps":["trace[2127080071] 'agreement among raft nodes before linearized reading' (duration: 192.622179ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:30:18.315393Z","caller":"traceutil/trace.go:171","msg":"trace[1284435736] transaction","detail":"{read_only:false; response_revision:25679; number_of_response:1; }","duration":"101.482904ms","start":"2026-08-22T11:30:18.213878Z","end":"2026-08-22T11:30:18.315361Z","steps":["trace[1284435736] 'process raft request' (duration: 101.437183ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:30:18.315565Z","caller":"traceutil/trace.go:171","msg":"trace[576380954] transaction","detail":"{read_only:false; response_revision:25678; number_of_response:1; }","duration":"122.870361ms","start":"2026-08-22T11:30:18.192672Z","end":"2026-08-22T11:30:18.315542Z","steps":["trace[576380954] 'process raft request' (duration: 122.601475ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:30:18.315722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.955749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3236"} {"level":"info","ts":"2026-08-22T11:30:18.315805Z","caller":"traceutil/trace.go:171","msg":"trace[694668361] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:25679; }","duration":"196.082462ms","start":"2026-08-22T11:30:18.119703Z","end":"2026-08-22T11:30:18.315785Z","steps":["trace[694668361] 'agreement among raft nodes before linearized reading' (duration: 195.845156ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:30:18.315938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.842774ms","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-08-22T11:30:18.315993Z","caller":"traceutil/trace.go:171","msg":"trace[743458707] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25679; }","duration":"264.942407ms","start":"2026-08-22T11:30:18.051039Z","end":"2026-08-22T11:30:18.315981Z","steps":["trace[743458707] 'agreement among raft nodes before linearized reading' (duration: 264.562438ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:30:18.31557Z","caller":"traceutil/trace.go:171","msg":"trace[408565694] transaction","detail":"{read_only:false; response_revision:25677; number_of_response:1; }","duration":"281.253831ms","start":"2026-08-22T11:30:18.034248Z","end":"2026-08-22T11:30:18.315502Z","steps":["trace[408565694] 'process raft request' (duration: 185.505636ms)","trace[408565694] 'compare' (duration: 95.379226ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:30:18.315548Z","caller":"traceutil/trace.go:171","msg":"trace[246359173] linearizableReadLoop","detail":"{readStateIndex:26515; appliedIndex:26514; }","duration":"170.222957ms","start":"2026-08-22T11:30:18.1453Z","end":"2026-08-22T11:30:18.315523Z","steps":["trace[246359173] 'read index received' (duration: 74.470041ms)","trace[246359173] 'applied index is now lower than readState.Index' (duration: 95.750136ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:30:18.347793Z","caller":"traceutil/trace.go:171","msg":"trace[495317866] transaction","detail":"{read_only:false; response_revision:25680; number_of_response:1; }","duration":"125.196758ms","start":"2026-08-22T11:30:18.222565Z","end":"2026-08-22T11:30:18.347762Z","steps":["trace[495317866] 'process raft request' (duration: 124.753517ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:30:18.347844Z","caller":"traceutil/trace.go:171","msg":"trace[1979708090] transaction","detail":"{read_only:false; response_revision:25681; number_of_response:1; }","duration":"123.875365ms","start":"2026-08-22T11:30:18.223945Z","end":"2026-08-22T11:30:18.34782Z","steps":["trace[1979708090] 'process raft request' (duration: 123.736242ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:30:18.347913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.54554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-08-22T11:30:18.348062Z","caller":"traceutil/trace.go:171","msg":"trace[1673006228] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25681; }","duration":"145.675193ms","start":"2026-08-22T11:30:18.20234Z","end":"2026-08-22T11:30:18.348015Z","steps":["trace[1673006228] 'agreement among raft nodes before linearized reading' (duration: 145.404286ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:30:20.327815Z","caller":"traceutil/trace.go:171","msg":"trace[1118339067] transaction","detail":"{read_only:false; response_revision:25693; number_of_response:1; }","duration":"176.236002ms","start":"2026-08-22T11:30:20.151544Z","end":"2026-08-22T11:30:20.32778Z","steps":["trace[1118339067] 'process raft request' (duration: 103.131613ms)","trace[1118339067] 'compare' (duration: 72.853522ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:30:20.328106Z","caller":"traceutil/trace.go:171","msg":"trace[554582047] transaction","detail":"{read_only:false; response_revision:25694; number_of_response:1; }","duration":"175.422823ms","start":"2026-08-22T11:30:20.152659Z","end":"2026-08-22T11:30:20.328082Z","steps":["trace[554582047] 'process raft request' (duration: 175.072125ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:30:20.328286Z","caller":"traceutil/trace.go:171","msg":"trace[2014179563] transaction","detail":"{read_only:false; response_revision:25695; number_of_response:1; }","duration":"171.292422ms","start":"2026-08-22T11:30:20.156979Z","end":"2026-08-22T11:30:20.328272Z","steps":["trace[2014179563] 'process raft request' (duration: 171.033256ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:30:20.46766Z","caller":"traceutil/trace.go:171","msg":"trace[632594950] transaction","detail":"{read_only:false; response_revision:25697; number_of_response:1; }","duration":"135.417024ms","start":"2026-08-22T11:30:20.332193Z","end":"2026-08-22T11:30:20.46761Z","steps":["trace[632594950] 'process raft request' (duration: 95.822977ms)","trace[632594950] 'compare' (duration: 39.344121ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:30:20.467824Z","caller":"traceutil/trace.go:171","msg":"trace[216062893] transaction","detail":"{read_only:false; response_revision:25698; number_of_response:1; }","duration":"135.61578ms","start":"2026-08-22T11:30:20.332184Z","end":"2026-08-22T11:30:20.4678Z","steps":["trace[216062893] 'process raft request' (duration: 135.349363ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:30:20.468062Z","caller":"traceutil/trace.go:171","msg":"trace[1836376482] transaction","detail":"{read_only:false; response_revision:25700; number_of_response:1; }","duration":"114.369266ms","start":"2026-08-22T11:30:20.353681Z","end":"2026-08-22T11:30:20.46805Z","steps":["trace[1836376482] 'process raft request' (duration: 114.199542ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:30:20.468188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.657943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-08-22T11:30:20.468215Z","caller":"traceutil/trace.go:171","msg":"trace[706535985] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25700; }","duration":"109.747785ms","start":"2026-08-22T11:30:20.35846Z","end":"2026-08-22T11:30:20.468208Z","steps":["trace[706535985] 'agreement among raft nodes before linearized reading' (duration: 109.635262ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:30:20.468243Z","caller":"traceutil/trace.go:171","msg":"trace[1768790749] transaction","detail":"{read_only:false; response_revision:25699; number_of_response:1; }","duration":"114.626812ms","start":"2026-08-22T11:30:20.353541Z","end":"2026-08-22T11:30:20.468167Z","steps":["trace[1768790749] 'process raft request' (duration: 114.17649ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:30:20.469819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.436661ms","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-08-22T11:30:20.469968Z","caller":"traceutil/trace.go:171","msg":"trace[662819107] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25700; }","duration":"115.669207ms","start":"2026-08-22T11:30:20.354266Z","end":"2026-08-22T11:30:20.469935Z","steps":["trace[662819107] 'agreement among raft nodes before linearized reading' (duration: 113.736831ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:30:25.242295Z","caller":"traceutil/trace.go:171","msg":"trace[991532686] transaction","detail":"{read_only:false; response_revision:25733; number_of_response:1; }","duration":"125.776152ms","start":"2026-08-22T11:30:25.116486Z","end":"2026-08-22T11:30:25.242262Z","steps":["trace[991532686] 'process raft request' (duration: 125.573877ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:30:29.177782Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22311} {"level":"info","ts":"2026-08-22T11:30:29.280033Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22311,"took":"98.247686ms","hash":3958011470,"current-db-size-bytes":57270272,"current-db-size":"57 MB","current-db-size-in-use-bytes":44965888,"current-db-size-in-use":"45 MB"} {"level":"info","ts":"2026-08-22T11:30:29.280099Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3958011470,"revision":22311,"compact-revision":20030} {"level":"info","ts":"2026-08-22T11:30:32.784261Z","caller":"traceutil/trace.go:171","msg":"trace[1493480160] transaction","detail":"{read_only:false; response_revision:25793; number_of_response:1; }","duration":"102.691043ms","start":"2026-08-22T11:30:32.681543Z","end":"2026-08-22T11:30:32.784234Z","steps":["trace[1493480160] 'process raft request' (duration: 102.361316ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:30:49.934599Z","caller":"traceutil/trace.go:171","msg":"trace[301837721] linearizableReadLoop","detail":"{readStateIndex:26761; appliedIndex:26760; }","duration":"127.36728ms","start":"2026-08-22T11:30:49.807197Z","end":"2026-08-22T11:30:49.934565Z","steps":["trace[301837721] 'read index received' (duration: 127.106594ms)","trace[301837721] 'applied index is now lower than readState.Index' (duration: 259.466µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:30:49.934708Z","caller":"traceutil/trace.go:171","msg":"trace[1002398989] transaction","detail":"{read_only:false; response_revision:25916; number_of_response:1; }","duration":"250.791915ms","start":"2026-08-22T11:30:49.683877Z","end":"2026-08-22T11:30:49.934669Z","steps":["trace[1002398989] 'process raft request' (duration: 250.522308ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:30:49.934757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.543014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T11:30:49.934804Z","caller":"traceutil/trace.go:171","msg":"trace[1408558867] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25916; }","duration":"127.639167ms","start":"2026-08-22T11:30:49.807151Z","end":"2026-08-22T11:30:49.93479Z","steps":["trace[1408558867] 'agreement among raft nodes before linearized reading' (duration: 127.546234ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:30:50.359795Z","caller":"traceutil/trace.go:171","msg":"trace[1867214155] transaction","detail":"{read_only:false; response_revision:25917; number_of_response:1; }","duration":"111.433955ms","start":"2026-08-22T11:30:50.248337Z","end":"2026-08-22T11:30:50.359771Z","steps":["trace[1867214155] 'process raft request' (duration: 111.261711ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:30:50.613476Z","caller":"traceutil/trace.go:171","msg":"trace[1893207003] transaction","detail":"{read_only:false; response_revision:25918; number_of_response:1; }","duration":"247.544737ms","start":"2026-08-22T11:30:50.365912Z","end":"2026-08-22T11:30:50.613457Z","steps":["trace[1893207003] 'process raft request' (duration: 247.420263ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:30:50.868082Z","caller":"traceutil/trace.go:171","msg":"trace[1068092234] transaction","detail":"{read_only:false; response_revision:25919; number_of_response:1; }","duration":"501.861476ms","start":"2026-08-22T11:30:50.366191Z","end":"2026-08-22T11:30:50.868053Z","steps":["trace[1068092234] 'process raft request' (duration: 501.467777ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:30:50.868278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:30:50.36618Z","time spent":"501.992299ms","remote":"127.0.0.1:38710","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":676,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T11:30:50.868387Z","caller":"traceutil/trace.go:171","msg":"trace[890641175] transaction","detail":"{read_only:false; response_revision:25920; number_of_response:1; }","duration":"500.059244ms","start":"2026-08-22T11:30:50.368297Z","end":"2026-08-22T11:30:50.868356Z","steps":["trace[890641175] 'process raft request' (duration: 499.649983ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:30:50.868548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:30:50.368275Z","time spent":"500.199216ms","remote":"127.0.0.1:39032","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2789,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T11:30:50.868746Z","caller":"traceutil/trace.go:171","msg":"trace[179169997] transaction","detail":"{read_only:false; response_revision:25921; number_of_response:1; }","duration":"384.712145ms","start":"2026-08-22T11:30:50.484023Z","end":"2026-08-22T11:30:50.868735Z","steps":["trace[179169997] 'process raft request' (duration: 384.183041ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:30:50.868802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:30:50.483998Z","time spent":"384.769735ms","remote":"127.0.0.1:38840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T11:30:50.868866Z","caller":"traceutil/trace.go:171","msg":"trace[209115908] transaction","detail":"{read_only:false; response_revision:25922; number_of_response:1; }","duration":"379.904148ms","start":"2026-08-22T11:30:50.488926Z","end":"2026-08-22T11:30:50.86883Z","steps":["trace[209115908] 'process raft request' (duration: 379.712043ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:30:50.869079Z","caller":"traceutil/trace.go:171","msg":"trace[655024188] transaction","detail":"{read_only:false; response_revision:25923; number_of_response:1; }","duration":"373.086732ms","start":"2026-08-22T11:30:50.495961Z","end":"2026-08-22T11:30:50.869047Z","steps":["trace[655024188] 'process raft request' (duration: 372.821156ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:30:50.869137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:30:50.488899Z","time spent":"380.143883ms","remote":"127.0.0.1:38840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T11:30:50.869248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:30:50.49594Z","time spent":"373.231546ms","remote":"127.0.0.1:38840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T11:30:50.869277Z","caller":"traceutil/trace.go:171","msg":"trace[694122778] transaction","detail":"{read_only:false; response_revision:25924; number_of_response:1; }","duration":"369.351752ms","start":"2026-08-22T11:30:50.499904Z","end":"2026-08-22T11:30:50.869255Z","steps":["trace[694122778] 'process raft request' (duration: 369.030844ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:30:50.86932Z","caller":"traceutil/trace.go:171","msg":"trace[1735313789] transaction","detail":"{read_only:false; response_revision:25925; number_of_response:1; }","duration":"353.921048ms","start":"2026-08-22T11:30:50.515385Z","end":"2026-08-22T11:30:50.869306Z","steps":["trace[1735313789] 'process raft request' (duration: 353.775105ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:30:50.869385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:30:50.515354Z","time spent":"353.998641ms","remote":"127.0.0.1:38840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T11:30:50.869381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:30:50.499892Z","time spent":"369.437804ms","remote":"127.0.0.1:38840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T11:30:50.869421Z","caller":"traceutil/trace.go:171","msg":"trace[906698119] transaction","detail":"{read_only:false; response_revision:25928; number_of_response:1; }","duration":"262.896478ms","start":"2026-08-22T11:30:50.606515Z","end":"2026-08-22T11:30:50.869411Z","steps":["trace[906698119] 'process raft request' (duration: 262.840026ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:30:50.869388Z","caller":"traceutil/trace.go:171","msg":"trace[484017307] transaction","detail":"{read_only:false; response_revision:25926; number_of_response:1; }","duration":"266.574556ms","start":"2026-08-22T11:30:50.602802Z","end":"2026-08-22T11:30:50.869376Z","steps":["trace[484017307] 'process raft request' (duration: 266.439903ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:30:50.869441Z","caller":"traceutil/trace.go:171","msg":"trace[145244941] linearizableReadLoop","detail":"{readStateIndex:26765; appliedIndex:26762; }","duration":"436.933496ms","start":"2026-08-22T11:30:50.432486Z","end":"2026-08-22T11:30:50.86942Z","steps":["trace[145244941] 'read index received' (duration: 180.903654ms)","trace[145244941] 'applied index is now lower than readState.Index' (duration: 256.028592ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:30:50.869461Z","caller":"traceutil/trace.go:171","msg":"trace[668204240] transaction","detail":"{read_only:false; response_revision:25927; number_of_response:1; }","duration":"264.403953ms","start":"2026-08-22T11:30:50.605017Z","end":"2026-08-22T11:30:50.869421Z","steps":["trace[668204240] 'process raft request' (duration: 264.28766ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:30:50.869688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.175143ms","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-08-22T11:30:50.869745Z","caller":"traceutil/trace.go:171","msg":"trace[1470417437] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25928; }","duration":"437.286094ms","start":"2026-08-22T11:30:50.43244Z","end":"2026-08-22T11:30:50.869726Z","steps":["trace[1470417437] 'agreement among raft nodes before linearized reading' (duration: 437.068299ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:30:50.869797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:30:50.432418Z","time spent":"437.368257ms","remote":"127.0.0.1:38840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-22T11:30:51.052515Z","caller":"traceutil/trace.go:171","msg":"trace[1051350317] transaction","detail":"{read_only:false; response_revision:25929; number_of_response:1; }","duration":"384.494358ms","start":"2026-08-22T11:30:50.667989Z","end":"2026-08-22T11:30:51.052484Z","steps":["trace[1051350317] 'process raft request' (duration: 384.333494ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:30:51.05266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:30:50.667962Z","time spent":"384.627761ms","remote":"127.0.0.1:38840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":628,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T11:30:51.052894Z","caller":"traceutil/trace.go:171","msg":"trace[819754290] linearizableReadLoop","detail":"{readStateIndex:26775; appliedIndex:26773; }","duration":"183.388924ms","start":"2026-08-22T11:30:50.869492Z","end":"2026-08-22T11:30:51.052881Z","steps":["trace[819754290] 'read index received' (duration: 182.893042ms)","trace[819754290] 'applied index is now lower than readState.Index' (duration: 494.772µs)"],"step_count":2} {"level":"warn","ts":"2026-08-22T11:30:51.053106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.446346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-08-22T11:30:51.053148Z","caller":"traceutil/trace.go:171","msg":"trace[2033156846] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25930; }","duration":"439.530148ms","start":"2026-08-22T11:30:50.613606Z","end":"2026-08-22T11:30:51.053136Z","steps":["trace[2033156846] 'agreement among raft nodes before linearized reading' (duration: 439.374464ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:30:51.053178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:30:50.61359Z","time spent":"439.580849ms","remote":"127.0.0.1:38840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":519,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-08-22T11:30:51.053216Z","caller":"traceutil/trace.go:171","msg":"trace[806752163] transaction","detail":"{read_only:false; response_revision:25930; number_of_response:1; }","duration":"369.31415ms","start":"2026-08-22T11:30:50.683853Z","end":"2026-08-22T11:30:51.053167Z","steps":["trace[806752163] 'process raft request' (duration: 368.601543ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:30:51.053307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.531033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-08-22T11:30:51.053338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.283712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"warn","ts":"2026-08-22T11:30:51.053322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.582359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-22T11:30:51.053427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:30:50.683825Z","time spent":"369.502516ms","remote":"127.0.0.1:38840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T11:30:51.053436Z","caller":"traceutil/trace.go:171","msg":"trace[2058229754] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25930; }","duration":"434.436507ms","start":"2026-08-22T11:30:50.618978Z","end":"2026-08-22T11:30:51.053415Z","steps":["trace[2058229754] 'agreement among raft nodes before linearized reading' (duration: 434.125588ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:30:51.053473Z","caller":"traceutil/trace.go:171","msg":"trace[1966418295] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25930; }","duration":"245.824165ms","start":"2026-08-22T11:30:50.807619Z","end":"2026-08-22T11:30:51.053444Z","steps":["trace[1966418295] 'agreement among raft nodes before linearized reading' (duration: 245.447045ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:30:51.053492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:30:50.618956Z","time spent":"434.527229ms","remote":"127.0.0.1:38840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":465,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-08-22T11:30:51.053397Z","caller":"traceutil/trace.go:171","msg":"trace[2134996518] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25930; }","duration":"435.628085ms","start":"2026-08-22T11:30:50.617748Z","end":"2026-08-22T11:30:51.053376Z","steps":["trace[2134996518] 'agreement among raft nodes before linearized reading' (duration: 435.407469ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:30:51.053561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:30:50.617706Z","time spent":"435.83266ms","remote":"127.0.0.1:38840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":506,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-08-22T11:30:51.333308Z","caller":"traceutil/trace.go:171","msg":"trace[212751056] transaction","detail":"{read_only:false; response_revision:25931; number_of_response:1; }","duration":"457.600956ms","start":"2026-08-22T11:30:50.875678Z","end":"2026-08-22T11:30:51.333279Z","steps":["trace[212751056] 'process raft request' (duration: 457.377131ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:30:51.334238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:30:50.875653Z","time spent":"458.508208ms","remote":"127.0.0.1:38840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T11:30:51.334261Z","caller":"traceutil/trace.go:171","msg":"trace[752396913] transaction","detail":"{read_only:false; response_revision:25932; number_of_response:1; }","duration":"458.087248ms","start":"2026-08-22T11:30:50.876113Z","end":"2026-08-22T11:30:51.334201Z","steps":["trace[752396913] 'process raft request' (duration: 457.098383ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:30:51.334417Z","caller":"traceutil/trace.go:171","msg":"trace[2140347170] linearizableReadLoop","detail":"{readStateIndex:26777; appliedIndex:26775; }","duration":"279.571511ms","start":"2026-08-22T11:30:51.054786Z","end":"2026-08-22T11:30:51.334358Z","steps":["trace[2140347170] 'read index received' (duration: 278.303229ms)","trace[2140347170] 'applied index is now lower than readState.Index' (duration: 1.258931ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T11:30:51.334531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:30:50.876104Z","time spent":"458.237952ms","remote":"127.0.0.1:38996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3020,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T11:30:51.334625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.813077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T11:30:51.334719Z","caller":"traceutil/trace.go:171","msg":"trace[468338789] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25932; }","duration":"279.975531ms","start":"2026-08-22T11:30:51.05472Z","end":"2026-08-22T11:30:51.334696Z","steps":["trace[468338789] 'agreement among raft nodes before linearized reading' (duration: 279.810447ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:30:51.389801Z","caller":"traceutil/trace.go:171","msg":"trace[315654716] transaction","detail":"{read_only:false; response_revision:25933; number_of_response:1; }","duration":"331.786644ms","start":"2026-08-22T11:30:51.057982Z","end":"2026-08-22T11:30:51.389769Z","steps":["trace[315654716] 'process raft request' (duration: 331.576649ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:30:51.389986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:30:51.057957Z","time spent":"331.934898ms","remote":"127.0.0.1:38840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T11:30:51.39006Z","caller":"traceutil/trace.go:171","msg":"trace[1582308642] transaction","detail":"{read_only:false; response_revision:25934; number_of_response:1; }","duration":"332.005379ms","start":"2026-08-22T11:30:51.05802Z","end":"2026-08-22T11:30:51.390026Z","steps":["trace[1582308642] 'process raft request' (duration: 331.700172ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:30:51.390231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.379968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbucketclaims/\" range_end:\"/registry/objectbucket.io/objectbucketclaims0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-22T11:30:51.390244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:30:51.057992Z","time spent":"332.163123ms","remote":"127.0.0.1:38840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T11:30:51.390277Z","caller":"traceutil/trace.go:171","msg":"trace[653129631] range","detail":"{range_begin:/registry/objectbucket.io/objectbucketclaims/; range_end:/registry/objectbucket.io/objectbucketclaims0; response_count:0; response_revision:25935; }","duration":"241.47237ms","start":"2026-08-22T11:30:51.148789Z","end":"2026-08-22T11:30:51.390262Z","steps":["trace[653129631] 'agreement among raft nodes before linearized reading' (duration: 241.373397ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:30:51.390294Z","caller":"traceutil/trace.go:171","msg":"trace[996136224] transaction","detail":"{read_only:false; response_revision:25935; number_of_response:1; }","duration":"331.440145ms","start":"2026-08-22T11:30:51.05884Z","end":"2026-08-22T11:30:51.39028Z","steps":["trace[996136224] 'process raft request' (duration: 331.107247ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:30:51.390355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:30:51.058821Z","time spent":"331.501597ms","remote":"127.0.0.1:38840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T11:30:51.390242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.92481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/clusters/\" range_end:\"/registry/cluster.x-k8s.io/clusters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T11:30:51.390404Z","caller":"traceutil/trace.go:171","msg":"trace[1121904059] range","detail":"{range_begin:/registry/cluster.x-k8s.io/clusters/; range_end:/registry/cluster.x-k8s.io/clusters0; response_count:0; response_revision:25935; }","duration":"132.135975ms","start":"2026-08-22T11:30:51.258254Z","end":"2026-08-22T11:30:51.39039Z","steps":["trace[1121904059] 'agreement among raft nodes before linearized reading' (duration: 131.9139ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:30:51.390527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.844685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2083"} {"level":"warn","ts":"2026-08-22T11:30:51.390477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.987154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/projects/\" range_end:\"/registry/openstack.k-orc.cloud/projects0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T11:30:51.390569Z","caller":"traceutil/trace.go:171","msg":"trace[174592200] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:25935; }","duration":"197.928047ms","start":"2026-08-22T11:30:51.192628Z","end":"2026-08-22T11:30:51.390556Z","steps":["trace[174592200] 'agreement among raft nodes before linearized reading' (duration: 197.808474ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:30:51.390605Z","caller":"traceutil/trace.go:171","msg":"trace[946386597] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/projects/; range_end:/registry/openstack.k-orc.cloud/projects0; response_count:0; response_revision:25935; }","duration":"154.169898ms","start":"2026-08-22T11:30:51.236407Z","end":"2026-08-22T11:30:51.390577Z","steps":["trace[946386597] 'agreement among raft nodes before linearized reading' (duration: 153.984494ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:31:02.657863Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-000000000000692e.wal"} {"level":"info","ts":"2026-08-22T11:31:06.22655Z","caller":"traceutil/trace.go:171","msg":"trace[551770759] transaction","detail":"{read_only:false; response_revision:26181; number_of_response:1; }","duration":"161.100276ms","start":"2026-08-22T11:31:06.065419Z","end":"2026-08-22T11:31:06.226519Z","steps":["trace[551770759] 'process raft request' (duration: 160.888231ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:31:06.230799Z","caller":"traceutil/trace.go:171","msg":"trace[837423209] linearizableReadLoop","detail":"{readStateIndex:27031; appliedIndex:27029; }","duration":"109.060067ms","start":"2026-08-22T11:31:06.12171Z","end":"2026-08-22T11:31:06.23077Z","steps":["trace[837423209] 'read index received' (duration: 104.621471ms)","trace[837423209] 'applied index is now lower than readState.Index' (duration: 4.436866ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:31:06.230862Z","caller":"traceutil/trace.go:171","msg":"trace[131545830] transaction","detail":"{read_only:false; response_revision:26182; number_of_response:1; }","duration":"155.750296ms","start":"2026-08-22T11:31:06.075085Z","end":"2026-08-22T11:31:06.230835Z","steps":["trace[131545830] 'process raft request' (duration: 155.48864ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:31:06.231052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.298573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T11:31:06.231108Z","caller":"traceutil/trace.go:171","msg":"trace[1877374336] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:26182; }","duration":"109.390166ms","start":"2026-08-22T11:31:06.121701Z","end":"2026-08-22T11:31:06.231091Z","steps":["trace[1877374336] 'agreement among raft nodes before linearized reading' (duration: 109.220871ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:31:41.758034Z","caller":"traceutil/trace.go:171","msg":"trace[900556777] linearizableReadLoop","detail":"{readStateIndex:27334; appliedIndex:27333; }","duration":"119.92149ms","start":"2026-08-22T11:31:41.638084Z","end":"2026-08-22T11:31:41.758006Z","steps":["trace[900556777] 'read index received' (duration: 119.761356ms)","trace[900556777] 'applied index is now lower than readState.Index' (duration: 158.933µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:31:41.758444Z","caller":"traceutil/trace.go:171","msg":"trace[664008052] transaction","detail":"{read_only:false; response_revision:26477; number_of_response:1; }","duration":"120.540005ms","start":"2026-08-22T11:31:41.637891Z","end":"2026-08-22T11:31:41.758431Z","steps":["trace[664008052] 'process raft request' (duration: 119.877648ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:31:41.758733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.634647ms","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-08-22T11:31:41.758777Z","caller":"traceutil/trace.go:171","msg":"trace[1596602824] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26477; }","duration":"120.704269ms","start":"2026-08-22T11:31:41.638061Z","end":"2026-08-22T11:31:41.758765Z","steps":["trace[1596602824] 'agreement among raft nodes before linearized reading' (duration: 120.563485ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:32:36.21564Z","caller":"traceutil/trace.go:171","msg":"trace[1182241823] transaction","detail":"{read_only:false; response_revision:26973; number_of_response:1; }","duration":"143.418768ms","start":"2026-08-22T11:32:36.072193Z","end":"2026-08-22T11:32:36.215612Z","steps":["trace[1182241823] 'process raft request' (duration: 133.050927ms)","trace[1182241823] 'compare' (duration: 10.264488ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:32:36.243211Z","caller":"traceutil/trace.go:171","msg":"trace[512272421] linearizableReadLoop","detail":"{readStateIndex:27843; appliedIndex:27841; }","duration":"146.876953ms","start":"2026-08-22T11:32:36.096303Z","end":"2026-08-22T11:32:36.24318Z","steps":["trace[512272421] 'read index received' (duration: 108.952325ms)","trace[512272421] 'applied index is now lower than readState.Index' (duration: 37.923608ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:32:36.243361Z","caller":"traceutil/trace.go:171","msg":"trace[874815430] transaction","detail":"{read_only:false; response_revision:26974; number_of_response:1; }","duration":"166.349643ms","start":"2026-08-22T11:32:36.077001Z","end":"2026-08-22T11:32:36.243351Z","steps":["trace[874815430] 'process raft request' (duration: 166.032595ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:32:36.24369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.377194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-08-22T11:32:36.243728Z","caller":"traceutil/trace.go:171","msg":"trace[731224158] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26974; }","duration":"147.465166ms","start":"2026-08-22T11:32:36.096251Z","end":"2026-08-22T11:32:36.243717Z","steps":["trace[731224158] 'agreement among raft nodes before linearized reading' (duration: 147.230461ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:32:36.243922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.937354ms","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-08-22T11:32:36.244016Z","caller":"traceutil/trace.go:171","msg":"trace[700941930] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26974; }","duration":"128.081807ms","start":"2026-08-22T11:32:36.11591Z","end":"2026-08-22T11:32:36.243992Z","steps":["trace[700941930] 'agreement among raft nodes before linearized reading' (duration: 127.79265ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:32:36.244097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.911863ms","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-08-22T11:32:36.244204Z","caller":"traceutil/trace.go:171","msg":"trace[1955767571] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26974; }","duration":"104.034095ms","start":"2026-08-22T11:32:36.140147Z","end":"2026-08-22T11:32:36.244182Z","steps":["trace[1955767571] 'agreement among raft nodes before linearized reading' (duration: 103.607215ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:32:36.756428Z","caller":"traceutil/trace.go:171","msg":"trace[877542242] transaction","detail":"{read_only:false; response_revision:26977; number_of_response:1; }","duration":"508.019825ms","start":"2026-08-22T11:32:36.248386Z","end":"2026-08-22T11:32:36.756406Z","steps":["trace[877542242] 'process raft request' (duration: 507.974794ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:32:36.756555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:32:36.248365Z","time spent":"508.127138ms","remote":"127.0.0.1:38840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T11:32:36.756676Z","caller":"traceutil/trace.go:171","msg":"trace[883744964] transaction","detail":"{read_only:false; response_revision:26976; number_of_response:1; }","duration":"508.419354ms","start":"2026-08-22T11:32:36.248221Z","end":"2026-08-22T11:32:36.75664Z","steps":["trace[883744964] 'process raft request' (duration: 508.038435ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:32:36.756845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:32:36.248192Z","time spent":"508.562308ms","remote":"127.0.0.1:38840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T11:32:37.073789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.606617ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-22T11:32:37.074064Z","caller":"traceutil/trace.go:171","msg":"trace[1507234910] linearizableReadLoop","detail":"{readStateIndex:27848; appliedIndex:27846; }","duration":"604.850216ms","start":"2026-08-22T11:32:36.469163Z","end":"2026-08-22T11:32:37.074013Z","steps":["trace[1507234910] 'read index received' (duration: 287.600294ms)","trace[1507234910] 'applied index is now lower than readState.Index' (duration: 317.248622ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:32:37.07417Z","caller":"traceutil/trace.go:171","msg":"trace[738806724] transaction","detail":"{read_only:false; response_revision:26978; number_of_response:1; }","duration":"752.827205ms","start":"2026-08-22T11:32:36.321284Z","end":"2026-08-22T11:32:37.074112Z","steps":["trace[738806724] 'process raft request' (duration: 619.815668ms)","trace[738806724] 'compare' (duration: 132.480244ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:32:37.074323Z","caller":"traceutil/trace.go:171","msg":"trace[870574731] transaction","detail":"{read_only:false; response_revision:26979; number_of_response:1; }","duration":"690.780196ms","start":"2026-08-22T11:32:36.383511Z","end":"2026-08-22T11:32:37.074291Z","steps":["trace[870574731] 'process raft request' (duration: 690.384325ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:32:37.074366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:32:36.321264Z","time spent":"753.016011ms","remote":"127.0.0.1:38840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T11:32:37.074498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:32:36.383485Z","time spent":"690.916998ms","remote":"127.0.0.1:38840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":628,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T11:32:37.074464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"605.270657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T11:32:37.074566Z","caller":"traceutil/trace.go:171","msg":"trace[1940268661] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-user; range_end:; response_count:0; response_revision:26979; }","duration":"605.423721ms","start":"2026-08-22T11:32:36.46912Z","end":"2026-08-22T11:32:37.074543Z","steps":["trace[1940268661] 'agreement among raft nodes before linearized reading' (duration: 605.198095ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:32:37.074628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:32:36.469102Z","time spent":"605.513884ms","remote":"127.0.0.1:38806","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":0,"response size":30,"request content":"key:\"/registry/jobs/openstack/manila-ks-user\" "} {"level":"warn","ts":"2026-08-22T11:32:37.074736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"605.422811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-22T11:32:37.074791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.153455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T11:32:37.074889Z","caller":"traceutil/trace.go:171","msg":"trace[2032727087] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26979; }","duration":"268.299349ms","start":"2026-08-22T11:32:36.80657Z","end":"2026-08-22T11:32:37.07487Z","steps":["trace[2032727087] 'agreement among raft nodes before linearized reading' (duration: 268.137615ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:32:37.074975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"581.537474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" ","response":"range_response_count:1 size:21431"} {"level":"info","ts":"2026-08-22T11:32:37.07501Z","caller":"traceutil/trace.go:171","msg":"trace[1756458215] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-endpoints; range_end:; response_count:1; response_revision:26979; }","duration":"581.613596ms","start":"2026-08-22T11:32:36.493388Z","end":"2026-08-22T11:32:37.075001Z","steps":["trace[1756458215] 'agreement among raft nodes before linearized reading' (duration: 581.475152ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:32:37.07505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:32:36.493366Z","time spent":"581.676867ms","remote":"127.0.0.1:38806","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":21455,"request content":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" "} {"level":"warn","ts":"2026-08-22T11:32:37.075097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"587.039377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" ","response":"range_response_count:1 size:21431"} {"level":"info","ts":"2026-08-22T11:32:37.075144Z","caller":"traceutil/trace.go:171","msg":"trace[944110226] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-endpoints; range_end:; response_count:1; response_revision:26979; }","duration":"587.115859ms","start":"2026-08-22T11:32:36.488017Z","end":"2026-08-22T11:32:37.075133Z","steps":["trace[944110226] 'agreement among raft nodes before linearized reading' (duration: 586.967735ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:32:37.075181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:32:36.488Z","time spent":"587.1732ms","remote":"127.0.0.1:38806","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":21455,"request content":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" "} {"level":"info","ts":"2026-08-22T11:32:37.07479Z","caller":"traceutil/trace.go:171","msg":"trace[1553508820] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-user; range_end:; response_count:0; response_revision:26979; }","duration":"605.490502ms","start":"2026-08-22T11:32:36.469285Z","end":"2026-08-22T11:32:37.074776Z","steps":["trace[1553508820] 'agreement among raft nodes before linearized reading' (duration: 605.37062ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:32:37.075335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:32:36.469281Z","time spent":"606.011875ms","remote":"127.0.0.1:38806","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":0,"response size":30,"request content":"key:\"/registry/jobs/openstack/manila-ks-user\" "} {"level":"info","ts":"2026-08-22T11:35:29.183782Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25764} {"level":"info","ts":"2026-08-22T11:35:29.334464Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25764,"took":"146.929944ms","hash":4063822045,"current-db-size-bytes":59387904,"current-db-size":"59 MB","current-db-size-in-use-bytes":36282368,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-08-22T11:35:29.334562Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4063822045,"revision":25764,"compact-revision":22311} {"level":"info","ts":"2026-08-22T11:36:28.321223Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"dbd0ffa4f8c99812","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-22T11:36:28.327848Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-08-22T11:36:28.327957Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-08-22T11:40:29.190875Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28640} {"level":"info","ts":"2026-08-22T11:40:29.332815Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28640,"took":"137.575367ms","hash":3372826166,"current-db-size-bytes":59387904,"current-db-size":"59 MB","current-db-size-in-use-bytes":31133696,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-22T11:40:29.332875Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3372826166,"revision":28640,"compact-revision":25764} {"level":"info","ts":"2026-08-22T11:45:29.19853Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30868} {"level":"info","ts":"2026-08-22T11:45:29.331941Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30868,"took":"128.583304ms","hash":1776453811,"current-db-size-bytes":59387904,"current-db-size":"59 MB","current-db-size-in-use-bytes":30433280,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-08-22T11:45:29.332016Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1776453811,"revision":30868,"compact-revision":28640} {"level":"info","ts":"2026-08-22T11:50:29.205343Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33125} {"level":"info","ts":"2026-08-22T11:50:29.343047Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33125,"took":"134.26927ms","hash":3999339523,"current-db-size-bytes":60432384,"current-db-size":"60 MB","current-db-size-in-use-bytes":30789632,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-22T11:50:29.343112Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3999339523,"revision":33125,"compact-revision":30868} {"level":"info","ts":"2026-08-22T11:55:29.212169Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35417} {"level":"info","ts":"2026-08-22T11:55:29.355239Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35417,"took":"139.437447ms","hash":3206812861,"current-db-size-bytes":60432384,"current-db-size":"60 MB","current-db-size-in-use-bytes":31612928,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-22T11:55:29.355321Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3206812861,"revision":35417,"compact-revision":33125} {"level":"info","ts":"2026-08-22T11:58:00.06945Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"dbd0ffa4f8c99812","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-22T11:58:00.081674Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-08-22T11:58:00.081818Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-08-22T12:00:29.218791Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37650} {"level":"info","ts":"2026-08-22T12:00:29.33113Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37650,"took":"109.001117ms","hash":3748998436,"current-db-size-bytes":61706240,"current-db-size":"62 MB","current-db-size-in-use-bytes":33157120,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-08-22T12:00:29.331185Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3748998436,"revision":37650,"compact-revision":35417}