{"level":"warn","ts":"2026-08-22T10:37:44.7832Z","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:37:44.783349Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.177: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.177:2380","--initial-cluster=instance=https://199.19.213.177:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.177:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.177: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:37:44.783494Z","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:37:44.783528Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.177:2380"]} {"level":"info","ts":"2026-08-22T10:37:44.783574Z","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:37:44.784896Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.177:2379"]} {"level":"info","ts":"2026-08-22T10:37:44.785107Z","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.177:2380"],"listen-peer-urls":["https://199.19.213.177:2380"],"advertise-client-urls":["https://199.19.213.177:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.177:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.177: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:37:44.78929Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.683474ms"} {"level":"info","ts":"2026-08-22T10:37:44.800396Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f577bc5ae95d61e1","cluster-id":"735860b212d0f0b6"} {"level":"info","ts":"2026-08-22T10:37:44.800491Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f577bc5ae95d61e1 switched to configuration voters=()"} {"level":"info","ts":"2026-08-22T10:37:44.800543Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f577bc5ae95d61e1 became follower at term 0"} {"level":"info","ts":"2026-08-22T10:37:44.800571Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f577bc5ae95d61e1 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-22T10:37:44.800584Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f577bc5ae95d61e1 became follower at term 1"} {"level":"info","ts":"2026-08-22T10:37:44.800672Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f577bc5ae95d61e1 switched to configuration voters=(17687813160169202145)"} {"level":"warn","ts":"2026-08-22T10:37:44.805786Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-22T10:37:44.807245Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-22T10:37:44.808188Z","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:37:44.810708Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f577bc5ae95d61e1","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-22T10:37:44.810844Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f577bc5ae95d61e1","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-22T10:37:44.810894Z","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:37:44.810945Z","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:37:44.810977Z","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:37:44.81185Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f577bc5ae95d61e1 switched to configuration voters=(17687813160169202145)"} {"level":"info","ts":"2026-08-22T10:37:44.812793Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"735860b212d0f0b6","local-member-id":"f577bc5ae95d61e1","added-peer-id":"f577bc5ae95d61e1","added-peer-peer-urls":["https://199.19.213.177:2380"]} {"level":"info","ts":"2026-08-22T10:37:44.816382Z","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:37:44.816452Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.177:2380"} {"level":"info","ts":"2026-08-22T10:37:44.816515Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.177:2380"} {"level":"info","ts":"2026-08-22T10:37:44.81677Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f577bc5ae95d61e1","initial-advertise-peer-urls":["https://199.19.213.177:2380"],"listen-peer-urls":["https://199.19.213.177:2380"],"advertise-client-urls":["https://199.19.213.177:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.177:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-22T10:37:44.816816Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-22T10:37:45.201208Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f577bc5ae95d61e1 is starting a new election at term 1"} {"level":"info","ts":"2026-08-22T10:37:45.201289Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f577bc5ae95d61e1 became pre-candidate at term 1"} {"level":"info","ts":"2026-08-22T10:37:45.201327Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f577bc5ae95d61e1 received MsgPreVoteResp from f577bc5ae95d61e1 at term 1"} {"level":"info","ts":"2026-08-22T10:37:45.20135Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f577bc5ae95d61e1 became candidate at term 2"} {"level":"info","ts":"2026-08-22T10:37:45.201361Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f577bc5ae95d61e1 received MsgVoteResp from f577bc5ae95d61e1 at term 2"} {"level":"info","ts":"2026-08-22T10:37:45.201376Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f577bc5ae95d61e1 became leader at term 2"} {"level":"info","ts":"2026-08-22T10:37:45.201392Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f577bc5ae95d61e1 elected leader f577bc5ae95d61e1 at term 2"} {"level":"info","ts":"2026-08-22T10:37:45.202792Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f577bc5ae95d61e1","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.177:2379]}","request-path":"/0/members/f577bc5ae95d61e1/attributes","cluster-id":"735860b212d0f0b6","publish-timeout":"7s"} {"level":"info","ts":"2026-08-22T10:37:45.203149Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-22T10:37:45.203992Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-22T10:37:45.20452Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-22T10:37:45.204719Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-22T10:37:45.204742Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-22T10:37:45.204773Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"735860b212d0f0b6","local-member-id":"f577bc5ae95d61e1","cluster-version":"3.5"} {"level":"info","ts":"2026-08-22T10:37:45.204844Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-22T10:37:45.204873Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-22T10:37:45.207517Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.177:2379"} {"level":"info","ts":"2026-08-22T10:37:45.208842Z","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:38:26.654737Z","caller":"traceutil/trace.go:171","msg":"trace[1492698557] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"218.300624ms","start":"2026-08-22T10:38:26.436404Z","end":"2026-08-22T10:38:26.654705Z","steps":["trace[1492698557] 'process raft request' (duration: 217.753739ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:38:26.93101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.809557ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-22T10:38:26.93115Z","caller":"traceutil/trace.go:171","msg":"trace[1295386545] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"217.249435ms","start":"2026-08-22T10:38:26.713883Z","end":"2026-08-22T10:38:26.931132Z","steps":["trace[1295386545] 'process raft request' (duration: 115.774589ms)","trace[1295386545] 'compare' (duration: 100.630722ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T10:38:27.480751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.424388ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-22T10:38:27.48091Z","caller":"traceutil/trace.go:171","msg":"trace[37751237] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"461.570053ms","start":"2026-08-22T10:38:27.019313Z","end":"2026-08-22T10:38:27.480883Z","steps":["trace[37751237] 'process raft request' (duration: 32.699773ms)","trace[37751237] 'compare' (duration: 428.168221ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T10:38:27.481005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:38:27.019301Z","time spent":"461.658607ms","remote":"127.0.0.1:44750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T10:38:27.481054Z","caller":"traceutil/trace.go:171","msg":"trace[694469115] linearizableReadLoop","detail":"{readStateIndex:651; appliedIndex:650; }","duration":"454.530705ms","start":"2026-08-22T10:38:27.026502Z","end":"2026-08-22T10:38:27.481033Z","steps":["trace[694469115] 'read index received' (duration: 25.448619ms)","trace[694469115] 'applied index is now lower than readState.Index' (duration: 429.079206ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T10:38:27.481238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.72058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T10:38:27.481323Z","caller":"traceutil/trace.go:171","msg":"trace[1050664727] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:630; }","duration":"454.844103ms","start":"2026-08-22T10:38:27.026456Z","end":"2026-08-22T10:38:27.481301Z","steps":["trace[1050664727] 'agreement among raft nodes before linearized reading' (duration: 454.71909ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:38:27.481321Z","caller":"traceutil/trace.go:171","msg":"trace[874013077] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"140.051605ms","start":"2026-08-22T10:38:27.341249Z","end":"2026-08-22T10:38:27.481301Z","steps":["trace[874013077] 'process raft request' (duration: 139.635614ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:38:27.481362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:38:27.026431Z","time spent":"454.922256ms","remote":"127.0.0.1:44486","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-22T10:38:27.483921Z","caller":"traceutil/trace.go:171","msg":"trace[605573411] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"137.364909ms","start":"2026-08-22T10:38:27.346539Z","end":"2026-08-22T10:38:27.483904Z","steps":["trace[605573411] 'process raft request' (duration: 135.539947ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:38:27.484371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.538881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-cmsjd\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-08-22T10:38:27.484453Z","caller":"traceutil/trace.go:171","msg":"trace[1308841848] range","detail":"{range_begin:/registry/pods/kube-system/cilium-cmsjd; range_end:; response_count:1; response_revision:631; }","duration":"195.639934ms","start":"2026-08-22T10:38:27.288787Z","end":"2026-08-22T10:38:27.484427Z","steps":["trace[1308841848] 'agreement among raft nodes before linearized reading' (duration: 195.10811ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:39:14.153615Z","caller":"traceutil/trace.go:171","msg":"trace[369807388] transaction","detail":"{read_only:false; response_revision:1200; number_of_response:1; }","duration":"131.041459ms","start":"2026-08-22T10:39:14.022546Z","end":"2026-08-22T10:39:14.153588Z","steps":["trace[369807388] 'process raft request' (duration: 130.775742ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:39:14.175257Z","caller":"traceutil/trace.go:171","msg":"trace[1682935077] linearizableReadLoop","detail":"{readStateIndex:1236; appliedIndex:1234; }","duration":"150.391092ms","start":"2026-08-22T10:39:14.02484Z","end":"2026-08-22T10:39:14.175231Z","steps":["trace[1682935077] 'read index received' (duration: 128.58072ms)","trace[1682935077] 'applied index is now lower than readState.Index' (duration: 21.809452ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T10:39:14.175397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.535836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T10:39:14.175437Z","caller":"traceutil/trace.go:171","msg":"trace[1525858073] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1201; }","duration":"150.611399ms","start":"2026-08-22T10:39:14.024816Z","end":"2026-08-22T10:39:14.175427Z","steps":["trace[1525858073] 'agreement among raft nodes before linearized reading' (duration: 150.512396ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:39:14.175419Z","caller":"traceutil/trace.go:171","msg":"trace[1047176350] transaction","detail":"{read_only:false; response_revision:1201; number_of_response:1; }","duration":"152.523542ms","start":"2026-08-22T10:39:14.022853Z","end":"2026-08-22T10:39:14.175376Z","steps":["trace[1047176350] 'process raft request' (duration: 152.251165ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:39:59.55113Z","caller":"traceutil/trace.go:171","msg":"trace[626690193] linearizableReadLoop","detail":"{readStateIndex:1941; appliedIndex:1940; }","duration":"218.147585ms","start":"2026-08-22T10:39:59.332955Z","end":"2026-08-22T10:39:59.551102Z","steps":["trace[626690193] 'read index received' (duration: 217.939249ms)","trace[626690193] 'applied index is now lower than readState.Index' (duration: 206.986µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:39:59.55122Z","caller":"traceutil/trace.go:171","msg":"trace[890424238] transaction","detail":"{read_only:false; response_revision:1873; number_of_response:1; }","duration":"320.517485ms","start":"2026-08-22T10:39:59.230674Z","end":"2026-08-22T10:39:59.551191Z","steps":["trace[890424238] 'process raft request' (duration: 320.215657ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:39:59.551381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:39:59.23065Z","time spent":"320.645208ms","remote":"127.0.0.1:44750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T10:39:59.551433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.477565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-7ad39bdb-dc0d-4254-aea7-3244e1c8ed7d\" ","response":"range_response_count:1 size:3627"} {"level":"warn","ts":"2026-08-22T10:39:59.551455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.585915ms","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:39:59.551504Z","caller":"traceutil/trace.go:171","msg":"trace[161685911] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1873; }","duration":"107.705339ms","start":"2026-08-22T10:39:59.443785Z","end":"2026-08-22T10:39:59.55149Z","steps":["trace[161685911] 'agreement among raft nodes before linearized reading' (duration: 107.506323ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:39:59.551502Z","caller":"traceutil/trace.go:171","msg":"trace[1242488028] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-7ad39bdb-dc0d-4254-aea7-3244e1c8ed7d; range_end:; response_count:1; response_revision:1873; }","duration":"218.544547ms","start":"2026-08-22T10:39:59.33294Z","end":"2026-08-22T10:39:59.551485Z","steps":["trace[1242488028] 'agreement among raft nodes before linearized reading' (duration: 218.31929ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:40:00.020915Z","caller":"traceutil/trace.go:171","msg":"trace[969882134] transaction","detail":"{read_only:false; response_revision:1875; number_of_response:1; }","duration":"245.072653ms","start":"2026-08-22T10:39:59.775821Z","end":"2026-08-22T10:40:00.020894Z","steps":["trace[969882134] 'process raft request' (duration: 244.921749ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:40:00.023325Z","caller":"traceutil/trace.go:171","msg":"trace[33566210] transaction","detail":"{read_only:false; response_revision:1876; number_of_response:1; }","duration":"181.00589ms","start":"2026-08-22T10:39:59.842297Z","end":"2026-08-22T10:40:00.023303Z","steps":["trace[33566210] 'process raft request' (duration: 180.849676ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:40:12.282837Z","caller":"traceutil/trace.go:171","msg":"trace[626532602] linearizableReadLoop","detail":"{readStateIndex:2048; appliedIndex:2047; }","duration":"142.477008ms","start":"2026-08-22T10:40:12.140332Z","end":"2026-08-22T10:40:12.282809Z","steps":["trace[626532602] 'read index received' (duration: 142.316073ms)","trace[626532602] 'applied index is now lower than readState.Index' (duration: 159.765µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:40:12.282913Z","caller":"traceutil/trace.go:171","msg":"trace[812724299] transaction","detail":"{read_only:false; response_revision:1977; number_of_response:1; }","duration":"163.742735ms","start":"2026-08-22T10:40:12.119122Z","end":"2026-08-22T10:40:12.282865Z","steps":["trace[812724299] 'process raft request' (duration: 163.503619ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:40:12.283143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.736275ms","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:40:12.28323Z","caller":"traceutil/trace.go:171","msg":"trace[1684775287] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1977; }","duration":"142.905609ms","start":"2026-08-22T10:40:12.140278Z","end":"2026-08-22T10:40:12.283184Z","steps":["trace[1684775287] 'agreement among raft nodes before linearized reading' (duration: 142.677763ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:40:12.285616Z","caller":"traceutil/trace.go:171","msg":"trace[1006323096] transaction","detail":"{read_only:false; response_revision:1978; number_of_response:1; }","duration":"110.894539ms","start":"2026-08-22T10:40:12.17469Z","end":"2026-08-22T10:40:12.285584Z","steps":["trace[1006323096] 'process raft request' (duration: 110.708454ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:40:12.285739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.357601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-08-22T10:40:12.285801Z","caller":"traceutil/trace.go:171","msg":"trace[383889547] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1978; }","duration":"122.477524ms","start":"2026-08-22T10:40:12.163305Z","end":"2026-08-22T10:40:12.285783Z","steps":["trace[383889547] 'agreement among raft nodes before linearized reading' (duration: 122.249428ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:40:12.436432Z","caller":"traceutil/trace.go:171","msg":"trace[1972195392] transaction","detail":"{read_only:false; response_revision:1979; number_of_response:1; }","duration":"148.450585ms","start":"2026-08-22T10:40:12.287952Z","end":"2026-08-22T10:40:12.436403Z","steps":["trace[1972195392] 'process raft request' (duration: 72.616872ms)","trace[1972195392] 'compare' (duration: 75.706399ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:40:15.233051Z","caller":"traceutil/trace.go:171","msg":"trace[347971241] transaction","detail":"{read_only:false; response_revision:1998; number_of_response:1; }","duration":"219.171324ms","start":"2026-08-22T10:40:15.013847Z","end":"2026-08-22T10:40:15.233019Z","steps":["trace[347971241] 'process raft request' (duration: 181.777072ms)","trace[347971241] 'compare' (duration: 37.037792ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:40:48.856787Z","caller":"traceutil/trace.go:171","msg":"trace[720085723] transaction","detail":"{read_only:false; response_revision:2191; number_of_response:1; }","duration":"164.990141ms","start":"2026-08-22T10:40:48.691761Z","end":"2026-08-22T10:40:48.856751Z","steps":["trace[720085723] 'process raft request' (duration: 115.973822ms)","trace[720085723] 'compare' (duration: 48.802603ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:40:48.856815Z","caller":"traceutil/trace.go:171","msg":"trace[1985513867] transaction","detail":"{read_only:false; response_revision:2192; number_of_response:1; }","duration":"165.001611ms","start":"2026-08-22T10:40:48.691785Z","end":"2026-08-22T10:40:48.856786Z","steps":["trace[1985513867] 'process raft request' (duration: 164.895498ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:40:55.01253Z","caller":"traceutil/trace.go:171","msg":"trace[555096664] linearizableReadLoop","detail":"{readStateIndex:2303; appliedIndex:2302; }","duration":"135.147602ms","start":"2026-08-22T10:40:54.877356Z","end":"2026-08-22T10:40:55.012504Z","steps":["trace[555096664] 'read index received' (duration: 134.980167ms)","trace[555096664] 'applied index is now lower than readState.Index' (duration: 166.265µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:40:55.012728Z","caller":"traceutil/trace.go:171","msg":"trace[777263096] transaction","detail":"{read_only:false; response_revision:2222; number_of_response:1; }","duration":"152.942132ms","start":"2026-08-22T10:40:54.859756Z","end":"2026-08-22T10:40:55.012698Z","steps":["trace[777263096] 'process raft request' (duration: 152.581532ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:40:55.013016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.645375ms","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:40:55.013057Z","caller":"traceutil/trace.go:171","msg":"trace[408398648] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2222; }","duration":"135.741668ms","start":"2026-08-22T10:40:54.877305Z","end":"2026-08-22T10:40:55.013047Z","steps":["trace[408398648] 'agreement among raft nodes before linearized reading' (duration: 135.582863ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:40:55.343218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.445856ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-22T10:40:55.343418Z","caller":"traceutil/trace.go:171","msg":"trace[352429993] transaction","detail":"{read_only:false; response_revision:2223; number_of_response:1; }","duration":"386.413718ms","start":"2026-08-22T10:40:54.956956Z","end":"2026-08-22T10:40:55.34337Z","steps":["trace[352429993] 'process raft request' (duration: 131.717735ms)","trace[352429993] 'compare' (duration: 254.323773ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:40:55.34348Z","caller":"traceutil/trace.go:171","msg":"trace[1848259897] linearizableReadLoop","detail":"{readStateIndex:2304; appliedIndex:2303; }","duration":"330.846565ms","start":"2026-08-22T10:40:55.012598Z","end":"2026-08-22T10:40:55.343444Z","steps":["trace[1848259897] 'read index received' (duration: 76.188833ms)","trace[1848259897] 'applied index is now lower than readState.Index' (duration: 254.656052ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T10:40:55.343622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:40:54.956934Z","time spent":"386.580062ms","remote":"127.0.0.1:44750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T10:40:55.343705Z","caller":"traceutil/trace.go:171","msg":"trace[586407192] transaction","detail":"{read_only:false; response_revision:2225; number_of_response:1; }","duration":"226.086669ms","start":"2026-08-22T10:40:55.117594Z","end":"2026-08-22T10:40:55.343681Z","steps":["trace[586407192] 'process raft request' (duration: 225.826662ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:40:55.343884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.290866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-08-22T10:40:55.343954Z","caller":"traceutil/trace.go:171","msg":"trace[1730796148] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2225; }","duration":"465.43263ms","start":"2026-08-22T10:40:54.878503Z","end":"2026-08-22T10:40:55.343935Z","steps":["trace[1730796148] 'agreement among raft nodes before linearized reading' (duration: 465.06795ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:40:55.344154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.355052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-22T10:40:55.34414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:40:54.878481Z","time spent":"465.575564ms","remote":"127.0.0.1:44750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-08-22T10:40:55.344209Z","caller":"traceutil/trace.go:171","msg":"trace[164680250] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2225; }","duration":"319.65673ms","start":"2026-08-22T10:40:55.024537Z","end":"2026-08-22T10:40:55.344194Z","steps":["trace[164680250] 'agreement among raft nodes before linearized reading' (duration: 319.347362ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:40:55.34425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:40:55.024505Z","time spent":"319.732642ms","remote":"127.0.0.1:44486","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-22T10:40:55.343944Z","caller":"traceutil/trace.go:171","msg":"trace[1007474164] transaction","detail":"{read_only:false; response_revision:2224; number_of_response:1; }","duration":"326.886004ms","start":"2026-08-22T10:40:55.016987Z","end":"2026-08-22T10:40:55.343873Z","steps":["trace[1007474164] 'process raft request' (duration: 326.347699ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:40:55.344645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:40:55.016959Z","time spent":"327.622474ms","remote":"127.0.0.1:44750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T10:42:12.898624Z","caller":"traceutil/trace.go:171","msg":"trace[622593665] transaction","detail":"{read_only:false; response_revision:2838; number_of_response:1; }","duration":"196.740126ms","start":"2026-08-22T10:42:12.701843Z","end":"2026-08-22T10:42:12.898584Z","steps":["trace[622593665] 'process raft request' (duration: 167.029778ms)","trace[622593665] 'compare' (duration: 29.41013ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T10:42:13.206696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.638972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T10:42:13.206906Z","caller":"traceutil/trace.go:171","msg":"trace[2076362825] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2838; }","duration":"179.942161ms","start":"2026-08-22T10:42:13.026932Z","end":"2026-08-22T10:42:13.206874Z","steps":["trace[2076362825] 'range keys from in-memory index tree' (duration: 179.55126ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:46:29.780282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.841828ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-22T10:46:29.780384Z","caller":"traceutil/trace.go:171","msg":"trace[826340522] linearizableReadLoop","detail":"{readStateIndex:5308; appliedIndex:5307; }","duration":"230.921218ms","start":"2026-08-22T10:46:29.549448Z","end":"2026-08-22T10:46:29.780369Z","steps":["trace[826340522] 'read index received' (duration: 80.803723ms)","trace[826340522] 'applied index is now lower than readState.Index' (duration: 150.116655ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:46:29.780435Z","caller":"traceutil/trace.go:171","msg":"trace[1869488391] transaction","detail":"{read_only:false; response_revision:5116; number_of_response:1; }","duration":"237.154252ms","start":"2026-08-22T10:46:29.54324Z","end":"2026-08-22T10:46:29.780394Z","steps":["trace[1869488391] 'process raft request' (duration: 87.127729ms)","trace[1869488391] 'compare' (duration: 149.743975ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T10:46:29.78063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.144555ms","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:46:29.780734Z","caller":"traceutil/trace.go:171","msg":"trace[386992693] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5116; }","duration":"231.280679ms","start":"2026-08-22T10:46:29.549432Z","end":"2026-08-22T10:46:29.780712Z","steps":["trace[386992693] 'agreement among raft nodes before linearized reading' (duration: 230.975049ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:46:32.044641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.82449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-08-22T10:46:32.04481Z","caller":"traceutil/trace.go:171","msg":"trace[1652595769] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5155; }","duration":"102.036366ms","start":"2026-08-22T10:46:31.942753Z","end":"2026-08-22T10:46:32.04479Z","steps":["trace[1652595769] 'range keys from in-memory index tree' (duration: 101.645125ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:46:43.969693Z","caller":"traceutil/trace.go:171","msg":"trace[1494722346] transaction","detail":"{read_only:false; response_revision:5355; number_of_response:1; }","duration":"121.740864ms","start":"2026-08-22T10:46:43.847882Z","end":"2026-08-22T10:46:43.969623Z","steps":["trace[1494722346] 'process raft request' (duration: 121.480887ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:46:46.00951Z","caller":"traceutil/trace.go:171","msg":"trace[1296163560] transaction","detail":"{read_only:false; response_revision:5402; number_of_response:1; }","duration":"100.726989ms","start":"2026-08-22T10:46:45.908749Z","end":"2026-08-22T10:46:46.009476Z","steps":["trace[1296163560] 'process raft request' (duration: 100.432761ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:46:46.092487Z","caller":"traceutil/trace.go:171","msg":"trace[39662809] transaction","detail":"{read_only:false; response_revision:5404; number_of_response:1; }","duration":"117.108226ms","start":"2026-08-22T10:46:45.975359Z","end":"2026-08-22T10:46:46.092468Z","steps":["trace[39662809] 'process raft request' (duration: 117.077855ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:46:46.092612Z","caller":"traceutil/trace.go:171","msg":"trace[1916171591] transaction","detail":"{read_only:false; response_revision:5403; number_of_response:1; }","duration":"117.213618ms","start":"2026-08-22T10:46:45.975354Z","end":"2026-08-22T10:46:46.092568Z","steps":["trace[1916171591] 'process raft request' (duration: 116.991942ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:46:46.092785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.762031ms","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:46:46.092892Z","caller":"traceutil/trace.go:171","msg":"trace[299896387] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5404; }","duration":"113.896965ms","start":"2026-08-22T10:46:45.978968Z","end":"2026-08-22T10:46:46.092865Z","steps":["trace[299896387] 'agreement among raft nodes before linearized reading' (duration: 113.569846ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:46:54.344916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.07887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:1853"} {"level":"info","ts":"2026-08-22T10:46:54.34501Z","caller":"traceutil/trace.go:171","msg":"trace[1091224718] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5620; }","duration":"133.220594ms","start":"2026-08-22T10:46:54.21177Z","end":"2026-08-22T10:46:54.344991Z","steps":["trace[1091224718] 'range keys from in-memory index tree' (duration: 132.824763ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:46:54.72326Z","caller":"traceutil/trace.go:171","msg":"trace[643360040] transaction","detail":"{read_only:false; response_revision:5622; number_of_response:1; }","duration":"136.61018ms","start":"2026-08-22T10:46:54.586621Z","end":"2026-08-22T10:46:54.723231Z","steps":["trace[643360040] 'process raft request' (duration: 136.394484ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:46:54.738706Z","caller":"traceutil/trace.go:171","msg":"trace[709749266] linearizableReadLoop","detail":"{readStateIndex:5829; appliedIndex:5827; }","duration":"134.580233ms","start":"2026-08-22T10:46:54.604112Z","end":"2026-08-22T10:46:54.738692Z","steps":["trace[709749266] 'read index received' (duration: 118.937176ms)","trace[709749266] 'applied index is now lower than readState.Index' (duration: 15.642306ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:46:54.738782Z","caller":"traceutil/trace.go:171","msg":"trace[2108206181] transaction","detail":"{read_only:false; response_revision:5623; number_of_response:1; }","duration":"149.639113ms","start":"2026-08-22T10:46:54.589133Z","end":"2026-08-22T10:46:54.738772Z","steps":["trace[2108206181] 'process raft request' (duration: 149.488589ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:46:54.738949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.821079ms","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:46:54.738978Z","caller":"traceutil/trace.go:171","msg":"trace[1184092671] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5623; }","duration":"134.906911ms","start":"2026-08-22T10:46:54.604063Z","end":"2026-08-22T10:46:54.73897Z","steps":["trace[1184092671] 'agreement among raft nodes before linearized reading' (duration: 134.789288ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:47:45.241709Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3015} {"level":"info","ts":"2026-08-22T10:47:45.33264Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3015,"took":"89.30032ms","hash":319020131,"current-db-size-bytes":58621952,"current-db-size":"59 MB","current-db-size-in-use-bytes":31313920,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-22T10:47:45.332722Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":319020131,"revision":3015,"compact-revision":-1} {"level":"info","ts":"2026-08-22T10:52:45.250078Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6068} {"level":"info","ts":"2026-08-22T10:52:45.349514Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6068,"took":"97.468385ms","hash":3710491998,"current-db-size-bytes":58621952,"current-db-size":"59 MB","current-db-size-in-use-bytes":21962752,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-08-22T10:52:45.349586Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3710491998,"revision":6068,"compact-revision":3015} {"level":"info","ts":"2026-08-22T10:53:00.521259Z","caller":"traceutil/trace.go:171","msg":"trace[1369313453] transaction","detail":"{read_only:false; response_revision:8398; number_of_response:1; }","duration":"260.821728ms","start":"2026-08-22T10:53:00.260403Z","end":"2026-08-22T10:53:00.521224Z","steps":["trace[1369313453] 'process raft request' (duration: 260.641203ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:53:00.726764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.255585ms","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:53:00.726869Z","caller":"traceutil/trace.go:171","msg":"trace[711201695] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8398; }","duration":"117.388358ms","start":"2026-08-22T10:53:00.609443Z","end":"2026-08-22T10:53:00.726831Z","steps":["trace[711201695] 'range keys from in-memory index tree' (duration: 117.012608ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:53:12.388515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.549596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-08-22T10:53:12.388603Z","caller":"traceutil/trace.go:171","msg":"trace[1621623233] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8459; }","duration":"171.69255ms","start":"2026-08-22T10:53:12.216892Z","end":"2026-08-22T10:53:12.388584Z","steps":["trace[1621623233] 'range keys from in-memory index tree' (duration: 171.32933ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:53:12.53475Z","caller":"traceutil/trace.go:171","msg":"trace[112949590] transaction","detail":"{read_only:false; response_revision:8460; number_of_response:1; }","duration":"141.506235ms","start":"2026-08-22T10:53:12.393219Z","end":"2026-08-22T10:53:12.534726Z","steps":["trace[112949590] 'process raft request' (duration: 141.34171ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:53:14.066895Z","caller":"traceutil/trace.go:171","msg":"trace[293588017] linearizableReadLoop","detail":"{readStateIndex:8766; appliedIndex:8765; }","duration":"122.066699ms","start":"2026-08-22T10:53:13.944801Z","end":"2026-08-22T10:53:14.066868Z","steps":["trace[293588017] 'read index received' (duration: 121.882814ms)","trace[293588017] 'applied index is now lower than readState.Index' (duration: 182.835µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:53:14.067017Z","caller":"traceutil/trace.go:171","msg":"trace[794609461] transaction","detail":"{read_only:false; response_revision:8466; number_of_response:1; }","duration":"141.432003ms","start":"2026-08-22T10:53:13.925574Z","end":"2026-08-22T10:53:14.067006Z","steps":["trace[794609461] 'process raft request' (duration: 141.123065ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:53:14.067195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.367689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T10:53:14.067229Z","caller":"traceutil/trace.go:171","msg":"trace[1139237485] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:8466; }","duration":"122.45072ms","start":"2026-08-22T10:53:13.944768Z","end":"2026-08-22T10:53:14.067218Z","steps":["trace[1139237485] 'agreement among raft nodes before linearized reading' (duration: 122.372448ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:53:41.965185Z","caller":"traceutil/trace.go:171","msg":"trace[1027080797] transaction","detail":"{read_only:false; response_revision:8673; number_of_response:1; }","duration":"336.658753ms","start":"2026-08-22T10:53:41.628502Z","end":"2026-08-22T10:53:41.96516Z","steps":["trace[1027080797] 'process raft request' (duration: 336.505718ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:53:41.965456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:53:41.628476Z","time spent":"336.876759ms","remote":"127.0.0.1:44750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T10:53:41.97795Z","caller":"traceutil/trace.go:171","msg":"trace[955113753] transaction","detail":"{read_only:false; response_revision:8674; number_of_response:1; }","duration":"333.433182ms","start":"2026-08-22T10:53:41.644486Z","end":"2026-08-22T10:53:41.97792Z","steps":["trace[955113753] 'process raft request' (duration: 333.178224ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:53:41.978125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:53:41.64447Z","time spent":"333.566365ms","remote":"127.0.0.1:44750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T10:53:54.666122Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002383.wal"} {"level":"warn","ts":"2026-08-22T10:53:55.56213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.827473ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T10:53:55.562316Z","caller":"traceutil/trace.go:171","msg":"trace[267785519] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:8787; }","duration":"193.039958ms","start":"2026-08-22T10:53:55.369252Z","end":"2026-08-22T10:53:55.562292Z","steps":["trace[267785519] 'range keys from in-memory index tree' (duration: 192.805092ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:53:55.562901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.85195ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-22T10:53:55.563008Z","caller":"traceutil/trace.go:171","msg":"trace[267495081] linearizableReadLoop","detail":"{readStateIndex:9101; appliedIndex:9100; }","duration":"143.716117ms","start":"2026-08-22T10:53:55.419276Z","end":"2026-08-22T10:53:55.562992Z","steps":["trace[267495081] 'read index received' (duration: 35.631µs)","trace[267495081] 'applied index is now lower than readState.Index' (duration: 143.679486ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:53:55.563082Z","caller":"traceutil/trace.go:171","msg":"trace[2113747881] transaction","detail":"{read_only:false; response_revision:8788; number_of_response:1; }","duration":"268.981765ms","start":"2026-08-22T10:53:55.294089Z","end":"2026-08-22T10:53:55.563071Z","steps":["trace[2113747881] 'process raft request' (duration: 68.869568ms)","trace[2113747881] 'compare' (duration: 199.744477ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T10:53:55.563269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.925172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-pd972\" ","response":"range_response_count:1 size:6445"} {"level":"info","ts":"2026-08-22T10:53:55.563338Z","caller":"traceutil/trace.go:171","msg":"trace[1977768493] range","detail":"{range_begin:/registry/pods/openstack/keepalived-pd972; range_end:; response_count:1; response_revision:8788; }","duration":"144.089047ms","start":"2026-08-22T10:53:55.419233Z","end":"2026-08-22T10:53:55.563322Z","steps":["trace[1977768493] 'agreement among raft nodes before linearized reading' (duration: 143.84884ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:53:55.620761Z","caller":"traceutil/trace.go:171","msg":"trace[1500904627] transaction","detail":"{read_only:false; response_revision:8789; number_of_response:1; }","duration":"149.92916ms","start":"2026-08-22T10:53:55.470804Z","end":"2026-08-22T10:53:55.620733Z","steps":["trace[1500904627] 'process raft request' (duration: 149.788016ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:53:55.635796Z","caller":"traceutil/trace.go:171","msg":"trace[2140855250] transaction","detail":"{read_only:false; response_revision:8790; number_of_response:1; }","duration":"156.777962ms","start":"2026-08-22T10:53:55.478992Z","end":"2026-08-22T10:53:55.63577Z","steps":["trace[2140855250] 'process raft request' (duration: 156.556726ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:53:55.635905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.582271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-dtgcg\" ","response":"range_response_count:1 size:4653"} {"level":"info","ts":"2026-08-22T10:53:55.636194Z","caller":"traceutil/trace.go:171","msg":"trace[396959862] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-dtgcg; range_end:; response_count:1; response_revision:8790; }","duration":"164.90835ms","start":"2026-08-22T10:53:55.471259Z","end":"2026-08-22T10:53:55.636167Z","steps":["trace[396959862] 'agreement among raft nodes before linearized reading' (duration: 164.471318ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:53:56.411173Z","caller":"traceutil/trace.go:171","msg":"trace[1534449160] linearizableReadLoop","detail":"{readStateIndex:9106; appliedIndex:9105; }","duration":"111.982697ms","start":"2026-08-22T10:53:56.299161Z","end":"2026-08-22T10:53:56.411144Z","steps":["trace[1534449160] 'read index received' (duration: 111.733931ms)","trace[1534449160] 'applied index is now lower than readState.Index' (duration: 209.185µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:53:56.411209Z","caller":"traceutil/trace.go:171","msg":"trace[1056278010] transaction","detail":"{read_only:false; response_revision:8793; number_of_response:1; }","duration":"180.885707ms","start":"2026-08-22T10:53:56.23029Z","end":"2026-08-22T10:53:56.411175Z","steps":["trace[1056278010] 'process raft request' (duration: 180.61756ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:53:56.411381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.209183ms","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:53:56.411421Z","caller":"traceutil/trace.go:171","msg":"trace[811304736] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8793; }","duration":"112.302116ms","start":"2026-08-22T10:53:56.299107Z","end":"2026-08-22T10:53:56.41141Z","steps":["trace[811304736] 'agreement among raft nodes before linearized reading' (duration: 112.140891ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:53:56.423388Z","caller":"traceutil/trace.go:171","msg":"trace[543866073] transaction","detail":"{read_only:false; response_revision:8794; number_of_response:1; }","duration":"100.714101ms","start":"2026-08-22T10:53:56.322653Z","end":"2026-08-22T10:53:56.423368Z","steps":["trace[543866073] 'process raft request' (duration: 100.583718ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:55:12.425585Z","caller":"traceutil/trace.go:171","msg":"trace[1722971391] transaction","detail":"{read_only:false; response_revision:9543; number_of_response:1; }","duration":"122.668866ms","start":"2026-08-22T10:55:12.302889Z","end":"2026-08-22T10:55:12.425558Z","steps":["trace[1722971391] 'process raft request' (duration: 122.495892ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:55:12.432877Z","caller":"traceutil/trace.go:171","msg":"trace[479654490] transaction","detail":"{read_only:false; response_revision:9544; number_of_response:1; }","duration":"102.195083ms","start":"2026-08-22T10:55:12.330669Z","end":"2026-08-22T10:55:12.432864Z","steps":["trace[479654490] 'process raft request' (duration: 102.138442ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:55:26.48122Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"f577bc5ae95d61e1","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-22T10:55:26.48654Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-08-22T10:55:26.486714Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-08-22T10:57:45.256794Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8173} {"level":"info","ts":"2026-08-22T10:57:45.32035Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8173,"took":"61.711261ms","hash":3491842175,"current-db-size-bytes":58621952,"current-db-size":"59 MB","current-db-size-in-use-bytes":28430336,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-08-22T10:57:45.320432Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3491842175,"revision":8173,"compact-revision":6068} {"level":"warn","ts":"2026-08-22T11:00:55.728484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.095424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-22T11:00:55.728581Z","caller":"traceutil/trace.go:171","msg":"trace[1420555866] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:11998; }","duration":"117.212317ms","start":"2026-08-22T11:00:55.611347Z","end":"2026-08-22T11:00:55.728559Z","steps":["trace[1420555866] 'count revisions from in-memory index tree' (duration: 116.990871ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:00:55.735133Z","caller":"traceutil/trace.go:171","msg":"trace[1794421880] transaction","detail":"{read_only:false; response_revision:11999; number_of_response:1; }","duration":"111.621791ms","start":"2026-08-22T11:00:55.623488Z","end":"2026-08-22T11:00:55.735109Z","steps":["trace[1794421880] 'process raft request' (duration: 111.492268ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:00:55.84838Z","caller":"traceutil/trace.go:171","msg":"trace[1167328347] linearizableReadLoop","detail":"{readStateIndex:12414; appliedIndex:12412; }","duration":"207.965883ms","start":"2026-08-22T11:00:55.64039Z","end":"2026-08-22T11:00:55.848355Z","steps":["trace[1167328347] 'read index received' (duration: 94.564563ms)","trace[1167328347] 'applied index is now lower than readState.Index' (duration: 113.40064ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T11:00:55.848645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.19851ms","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:00:55.848678Z","caller":"traceutil/trace.go:171","msg":"trace[1388904539] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:12000; }","duration":"208.323963ms","start":"2026-08-22T11:00:55.640346Z","end":"2026-08-22T11:00:55.84867Z","steps":["trace[1388904539] 'agreement among raft nodes before linearized reading' (duration: 208.142268ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:00:55.848876Z","caller":"traceutil/trace.go:171","msg":"trace[1740566638] transaction","detail":"{read_only:false; response_revision:12000; number_of_response:1; }","duration":"213.701814ms","start":"2026-08-22T11:00:55.635159Z","end":"2026-08-22T11:00:55.848861Z","steps":["trace[1740566638] 'process raft request' (duration: 196.571344ms)","trace[1740566638] 'compare' (duration: 16.533734ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:02:45.264841Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10552} {"level":"info","ts":"2026-08-22T11:02:45.345017Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10552,"took":"77.737095ms","hash":3672076490,"current-db-size-bytes":58621952,"current-db-size":"59 MB","current-db-size-in-use-bytes":26464256,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-08-22T11:02:45.345115Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3672076490,"revision":10552,"compact-revision":8173} {"level":"info","ts":"2026-08-22T11:07:11.293352Z","caller":"traceutil/trace.go:171","msg":"trace[232069231] linearizableReadLoop","detail":"{readStateIndex:15062; appliedIndex:15061; }","duration":"193.489653ms","start":"2026-08-22T11:07:11.099835Z","end":"2026-08-22T11:07:11.293325Z","steps":["trace[232069231] 'read index received' (duration: 193.36277ms)","trace[232069231] 'applied index is now lower than readState.Index' (duration: 125.683µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:07:11.293404Z","caller":"traceutil/trace.go:171","msg":"trace[1999913675] transaction","detail":"{read_only:false; response_revision:14559; number_of_response:1; }","duration":"197.887996ms","start":"2026-08-22T11:07:11.09548Z","end":"2026-08-22T11:07:11.293368Z","steps":["trace[1999913675] 'process raft request' (duration: 197.656449ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:07:11.293572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.709569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T11:07:11.293642Z","caller":"traceutil/trace.go:171","msg":"trace[725462521] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:14559; }","duration":"193.884104ms","start":"2026-08-22T11:07:11.099744Z","end":"2026-08-22T11:07:11.293628Z","steps":["trace[725462521] 'agreement among raft nodes before linearized reading' (duration: 193.725349ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:07:11.294263Z","caller":"traceutil/trace.go:171","msg":"trace[2014507414] transaction","detail":"{read_only:false; response_revision:14560; number_of_response:1; }","duration":"162.446669ms","start":"2026-08-22T11:07:11.131794Z","end":"2026-08-22T11:07:11.29424Z","steps":["trace[2014507414] 'process raft request' (duration: 162.250723ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:07:11.294299Z","caller":"traceutil/trace.go:171","msg":"trace[1102623460] transaction","detail":"{read_only:false; response_revision:14561; number_of_response:1; }","duration":"101.742277ms","start":"2026-08-22T11:07:11.192538Z","end":"2026-08-22T11:07:11.29428Z","steps":["trace[1102623460] 'process raft request' (duration: 101.632054ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:07:11.294555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.803938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-22T11:07:11.294635Z","caller":"traceutil/trace.go:171","msg":"trace[2088780936] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:14562; }","duration":"181.944552ms","start":"2026-08-22T11:07:11.112673Z","end":"2026-08-22T11:07:11.294617Z","steps":["trace[2088780936] 'agreement among raft nodes before linearized reading' (duration: 181.724406ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:07:28.529763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.53412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/openvswitch-4s75c\" ","response":"range_response_count:1 size:11628"} {"level":"info","ts":"2026-08-22T11:07:28.529839Z","caller":"traceutil/trace.go:171","msg":"trace[1519441261] range","detail":"{range_begin:/registry/pods/openstack/openvswitch-4s75c; range_end:; response_count:1; response_revision:14665; }","duration":"134.668214ms","start":"2026-08-22T11:07:28.395152Z","end":"2026-08-22T11:07:28.529821Z","steps":["trace[1519441261] 'range keys from in-memory index tree' (duration: 134.261392ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:07:29.587916Z","caller":"traceutil/trace.go:171","msg":"trace[1784959646] transaction","detail":"{read_only:false; response_revision:14670; number_of_response:1; }","duration":"217.150434ms","start":"2026-08-22T11:07:29.370742Z","end":"2026-08-22T11:07:29.587893Z","steps":["trace[1784959646] 'process raft request' (duration: 216.914658ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:07:29.587915Z","caller":"traceutil/trace.go:171","msg":"trace[1112900344] transaction","detail":"{read_only:false; response_revision:14669; number_of_response:1; }","duration":"218.579443ms","start":"2026-08-22T11:07:29.369311Z","end":"2026-08-22T11:07:29.58789Z","steps":["trace[1112900344] 'process raft request' (duration: 190.458399ms)","trace[1112900344] 'compare' (duration: 27.786225ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:07:29.588202Z","caller":"traceutil/trace.go:171","msg":"trace[784747692] transaction","detail":"{read_only:false; response_revision:14671; number_of_response:1; }","duration":"215.501957ms","start":"2026-08-22T11:07:29.372685Z","end":"2026-08-22T11:07:29.588187Z","steps":["trace[784747692] 'process raft request' (duration: 215.016024ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:07:29.588549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.69788ms","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-22T11:07:29.588608Z","caller":"traceutil/trace.go:171","msg":"trace[813284671] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:14671; }","duration":"212.786941ms","start":"2026-08-22T11:07:29.375805Z","end":"2026-08-22T11:07:29.588592Z","steps":["trace[813284671] 'agreement among raft nodes before linearized reading' (duration: 212.615917ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:07:29.588675Z","caller":"traceutil/trace.go:171","msg":"trace[436595946] linearizableReadLoop","detail":"{readStateIndex:15179; appliedIndex:15175; }","duration":"211.915367ms","start":"2026-08-22T11:07:29.375829Z","end":"2026-08-22T11:07:29.587744Z","steps":["trace[436595946] 'read index received' (duration: 39.588393ms)","trace[436595946] 'applied index is now lower than readState.Index' (duration: 172.326024ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T11:07:29.588844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.968377ms","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-22T11:07:29.588882Z","caller":"traceutil/trace.go:171","msg":"trace[1401967796] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:14671; }","duration":"213.016388ms","start":"2026-08-22T11:07:29.375854Z","end":"2026-08-22T11:07:29.588871Z","steps":["trace[1401967796] 'agreement among raft nodes before linearized reading' (duration: 212.912055ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:07:39.860666Z","caller":"traceutil/trace.go:171","msg":"trace[1561938161] transaction","detail":"{read_only:false; response_revision:14843; number_of_response:1; }","duration":"239.151057ms","start":"2026-08-22T11:07:39.621474Z","end":"2026-08-22T11:07:39.860625Z","steps":["trace[1561938161] 'process raft request' (duration: 187.205528ms)","trace[1561938161] 'compare' (duration: 51.766824ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:07:39.860871Z","caller":"traceutil/trace.go:171","msg":"trace[559309191] transaction","detail":"{read_only:false; response_revision:14844; number_of_response:1; }","duration":"237.390707ms","start":"2026-08-22T11:07:39.623464Z","end":"2026-08-22T11:07:39.860855Z","steps":["trace[559309191] 'process raft request' (duration: 237.102469ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:07:39.861046Z","caller":"traceutil/trace.go:171","msg":"trace[1035077936] transaction","detail":"{read_only:false; response_revision:14845; number_of_response:1; }","duration":"237.220263ms","start":"2026-08-22T11:07:39.623816Z","end":"2026-08-22T11:07:39.861036Z","steps":["trace[1035077936] 'process raft request' (duration: 236.961596ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:07:39.861105Z","caller":"traceutil/trace.go:171","msg":"trace[1825114686] transaction","detail":"{read_only:false; response_revision:14846; number_of_response:1; }","duration":"116.087465ms","start":"2026-08-22T11:07:39.744997Z","end":"2026-08-22T11:07:39.861085Z","steps":["trace[1825114686] 'process raft request' (duration: 116.001133ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:07:39.861185Z","caller":"traceutil/trace.go:171","msg":"trace[608558698] linearizableReadLoop","detail":"{readStateIndex:15356; appliedIndex:15353; }","duration":"182.640591ms","start":"2026-08-22T11:07:39.678509Z","end":"2026-08-22T11:07:39.861149Z","steps":["trace[608558698] 'read index received' (duration: 130.256641ms)","trace[608558698] 'applied index is now lower than readState.Index' (duration: 52.37895ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T11:07:39.861369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.861227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-08-22T11:07:39.861409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.386034ms","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-22T11:07:39.861424Z","caller":"traceutil/trace.go:171","msg":"trace[1920797945] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:14846; }","duration":"182.96979ms","start":"2026-08-22T11:07:39.67844Z","end":"2026-08-22T11:07:39.86141Z","steps":["trace[1920797945] 'agreement among raft nodes before linearized reading' (duration: 182.805526ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:07:39.861436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.526104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-pd972\" ","response":"range_response_count:1 size:6445"} {"level":"info","ts":"2026-08-22T11:07:39.861441Z","caller":"traceutil/trace.go:171","msg":"trace[295631379] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:14846; }","duration":"182.454166ms","start":"2026-08-22T11:07:39.678978Z","end":"2026-08-22T11:07:39.861432Z","steps":["trace[295631379] 'agreement among raft nodes before linearized reading' (duration: 182.322552ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:07:39.861461Z","caller":"traceutil/trace.go:171","msg":"trace[574885258] range","detail":"{range_begin:/registry/pods/openstack/keepalived-pd972; range_end:; response_count:1; response_revision:14846; }","duration":"146.580526ms","start":"2026-08-22T11:07:39.714873Z","end":"2026-08-22T11:07:39.861453Z","steps":["trace[574885258] 'agreement among raft nodes before linearized reading' (duration: 146.503333ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:07:40.047437Z","caller":"traceutil/trace.go:171","msg":"trace[248465153] transaction","detail":"{read_only:false; response_revision:14847; number_of_response:1; }","duration":"182.507158ms","start":"2026-08-22T11:07:39.864909Z","end":"2026-08-22T11:07:40.047416Z","steps":["trace[248465153] 'process raft request' (duration: 170.648097ms)","trace[248465153] 'compare' (duration: 11.533101ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:07:40.047599Z","caller":"traceutil/trace.go:171","msg":"trace[1678385700] linearizableReadLoop","detail":"{readStateIndex:15360; appliedIndex:15357; }","duration":"182.023153ms","start":"2026-08-22T11:07:39.865558Z","end":"2026-08-22T11:07:40.047581Z","steps":["trace[1678385700] 'read index received' (duration: 170.003638ms)","trace[1678385700] 'applied index is now lower than readState.Index' (duration: 12.018575ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:07:40.047806Z","caller":"traceutil/trace.go:171","msg":"trace[1718793592] transaction","detail":"{read_only:false; response_revision:14848; number_of_response:1; }","duration":"182.738114ms","start":"2026-08-22T11:07:39.865056Z","end":"2026-08-22T11:07:40.047794Z","steps":["trace[1718793592] 'process raft request' (duration: 182.325592ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:07:40.047996Z","caller":"traceutil/trace.go:171","msg":"trace[313883519] transaction","detail":"{read_only:false; response_revision:14849; number_of_response:1; }","duration":"182.9104ms","start":"2026-08-22T11:07:39.865072Z","end":"2026-08-22T11:07:40.047983Z","steps":["trace[313883519] 'process raft request' (duration: 182.455767ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:07:40.048776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.196937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:68 size:632592"} {"level":"info","ts":"2026-08-22T11:07:40.048822Z","caller":"traceutil/trace.go:171","msg":"trace[1357852903] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:68; response_revision:14849; }","duration":"183.268898ms","start":"2026-08-22T11:07:39.865543Z","end":"2026-08-22T11:07:40.048812Z","steps":["trace[1357852903] 'agreement among raft nodes before linearized reading' (duration: 182.575149ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:07:45.271845Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12740} {"level":"info","ts":"2026-08-22T11:07:45.347665Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12740,"took":"73.224232ms","hash":1691023747,"current-db-size-bytes":58621952,"current-db-size":"59 MB","current-db-size-in-use-bytes":28565504,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-08-22T11:07:45.347772Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1691023747,"revision":12740,"compact-revision":10552} {"level":"info","ts":"2026-08-22T11:09:09.500718Z","caller":"traceutil/trace.go:171","msg":"trace[1509421189] transaction","detail":"{read_only:false; response_revision:15882; number_of_response:1; }","duration":"123.906044ms","start":"2026-08-22T11:09:09.376793Z","end":"2026-08-22T11:09:09.500699Z","steps":["trace[1509421189] 'process raft request' (duration: 123.540034ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:09:10.323245Z","caller":"traceutil/trace.go:171","msg":"trace[1153050393] transaction","detail":"{read_only:false; response_revision:15885; number_of_response:1; }","duration":"109.956994ms","start":"2026-08-22T11:09:10.213265Z","end":"2026-08-22T11:09:10.323222Z","steps":["trace[1153050393] 'process raft request' (duration: 109.906093ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:09:10.323375Z","caller":"traceutil/trace.go:171","msg":"trace[1424789688] transaction","detail":"{read_only:false; response_revision:15884; number_of_response:1; }","duration":"110.082158ms","start":"2026-08-22T11:09:10.213264Z","end":"2026-08-22T11:09:10.323346Z","steps":["trace[1424789688] 'process raft request' (duration: 109.603135ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:09:10.611985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.959005ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-22T11:09:10.612388Z","caller":"traceutil/trace.go:171","msg":"trace[1254845913] transaction","detail":"{read_only:false; response_revision:15886; number_of_response:1; }","duration":"377.490923ms","start":"2026-08-22T11:09:10.234867Z","end":"2026-08-22T11:09:10.612358Z","steps":["trace[1254845913] 'process raft request' (duration: 239.065335ms)","trace[1254845913] 'compare' (duration: 137.727899ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T11:09:10.612506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:09:10.234854Z","time spent":"377.599836ms","remote":"127.0.0.1:44750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T11:09:10.71906Z","caller":"traceutil/trace.go:171","msg":"trace[762475671] transaction","detail":"{read_only:false; response_revision:15887; number_of_response:1; }","duration":"101.056017ms","start":"2026-08-22T11:09:10.617978Z","end":"2026-08-22T11:09:10.719034Z","steps":["trace[762475671] 'process raft request' (duration: 100.826541ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:09:10.719559Z","caller":"traceutil/trace.go:171","msg":"trace[1198856324] transaction","detail":"{read_only:false; response_revision:15888; number_of_response:1; }","duration":"101.159879ms","start":"2026-08-22T11:09:10.618387Z","end":"2026-08-22T11:09:10.719547Z","steps":["trace[1198856324] 'process raft request' (duration: 100.920003ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:12:45.277243Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14960} {"level":"info","ts":"2026-08-22T11:12:45.370249Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14960,"took":"90.527298ms","hash":956320523,"current-db-size-bytes":58621952,"current-db-size":"59 MB","current-db-size-in-use-bytes":31006720,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-22T11:12:45.370318Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":956320523,"revision":14960,"compact-revision":12740} {"level":"info","ts":"2026-08-22T11:15:00.612543Z","caller":"traceutil/trace.go:171","msg":"trace[592752938] transaction","detail":"{read_only:false; response_revision:18441; number_of_response:1; }","duration":"116.121515ms","start":"2026-08-22T11:15:00.496387Z","end":"2026-08-22T11:15:00.612509Z","steps":["trace[592752938] 'process raft request' (duration: 108.218584ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:15:01.878746Z","caller":"traceutil/trace.go:171","msg":"trace[1254889639] linearizableReadLoop","detail":"{readStateIndex:19086; appliedIndex:19085; }","duration":"110.350794ms","start":"2026-08-22T11:15:01.768376Z","end":"2026-08-22T11:15:01.878726Z","steps":["trace[1254889639] 'read index received' (duration: 110.18685ms)","trace[1254889639] 'applied index is now lower than readState.Index' (duration: 163.144µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:15:01.878886Z","caller":"traceutil/trace.go:171","msg":"trace[1675398456] transaction","detail":"{read_only:false; response_revision:18455; number_of_response:1; }","duration":"206.698056ms","start":"2026-08-22T11:15:01.672165Z","end":"2026-08-22T11:15:01.878863Z","steps":["trace[1675398456] 'process raft request' (duration: 206.443999ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:15:01.878926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.539779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-dhzsm\" ","response":"range_response_count:1 size:22015"} {"level":"info","ts":"2026-08-22T11:15:01.878954Z","caller":"traceutil/trace.go:171","msg":"trace[1303098932] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-dhzsm; range_end:; response_count:1; response_revision:18455; }","duration":"110.58247ms","start":"2026-08-22T11:15:01.768363Z","end":"2026-08-22T11:15:01.878945Z","steps":["trace[1303098932] 'agreement among raft nodes before linearized reading' (duration: 110.437666ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:16:23.013362Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"f577bc5ae95d61e1","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-22T11:16:23.019333Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-08-22T11:16:23.019474Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-08-22T11:17:45.285678Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17525} {"level":"info","ts":"2026-08-22T11:17:45.382359Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17525,"took":"93.252136ms","hash":1355771933,"current-db-size-bytes":58621952,"current-db-size":"59 MB","current-db-size-in-use-bytes":35241984,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-08-22T11:17:45.382424Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1355771933,"revision":17525,"compact-revision":14960} {"level":"info","ts":"2026-08-22T11:21:10.476791Z","caller":"traceutil/trace.go:171","msg":"trace[798439294] linearizableReadLoop","detail":"{readStateIndex:21985; appliedIndex:21984; }","duration":"127.73334ms","start":"2026-08-22T11:21:10.349035Z","end":"2026-08-22T11:21:10.476768Z","steps":["trace[798439294] 'read index received' (duration: 127.536854ms)","trace[798439294] 'applied index is now lower than readState.Index' (duration: 195.506µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:21:10.476961Z","caller":"traceutil/trace.go:171","msg":"trace[911683150] transaction","detail":"{read_only:false; response_revision:21261; number_of_response:1; }","duration":"137.865313ms","start":"2026-08-22T11:21:10.339031Z","end":"2026-08-22T11:21:10.476896Z","steps":["trace[911683150] 'process raft request' (duration: 137.589326ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:21:10.477153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.94188ms","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:21:10.47721Z","caller":"traceutil/trace.go:171","msg":"trace[918181217] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:21261; }","duration":"126.010942ms","start":"2026-08-22T11:21:10.351186Z","end":"2026-08-22T11:21:10.477197Z","steps":["trace[918181217] 'agreement among raft nodes before linearized reading' (duration: 125.847417ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:21:10.477434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.382837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-08-22T11:21:10.477539Z","caller":"traceutil/trace.go:171","msg":"trace[1858695989] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:21261; }","duration":"128.533821ms","start":"2026-08-22T11:21:10.348982Z","end":"2026-08-22T11:21:10.477516Z","steps":["trace[1858695989] 'agreement among raft nodes before linearized reading' (duration: 128.238603ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:21:10.626001Z","caller":"traceutil/trace.go:171","msg":"trace[345862263] transaction","detail":"{read_only:false; response_revision:21262; number_of_response:1; }","duration":"144.589361ms","start":"2026-08-22T11:21:10.481382Z","end":"2026-08-22T11:21:10.625971Z","steps":["trace[345862263] 'process raft request' (duration: 127.200935ms)","trace[345862263] 'compare' (duration: 17.265452ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:21:10.869868Z","caller":"traceutil/trace.go:171","msg":"trace[1046067436] transaction","detail":"{read_only:false; response_revision:21263; number_of_response:1; }","duration":"387.980334ms","start":"2026-08-22T11:21:10.481856Z","end":"2026-08-22T11:21:10.869837Z","steps":["trace[1046067436] 'process raft request' (duration: 322.873564ms)","trace[1046067436] 'compare' (duration: 64.751059ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T11:21:10.870033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:21:10.481842Z","time spent":"388.105447ms","remote":"127.0.0.1:44750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T11:21:10.870156Z","caller":"traceutil/trace.go:171","msg":"trace[965506647] transaction","detail":"{read_only:false; response_revision:21265; number_of_response:1; }","duration":"387.679576ms","start":"2026-08-22T11:21:10.48245Z","end":"2026-08-22T11:21:10.87013Z","steps":["trace[965506647] 'process raft request' (duration: 387.580553ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:21:10.870293Z","caller":"traceutil/trace.go:171","msg":"trace[424675049] transaction","detail":"{read_only:false; response_revision:21264; number_of_response:1; }","duration":"388.271222ms","start":"2026-08-22T11:21:10.481994Z","end":"2026-08-22T11:21:10.870265Z","steps":["trace[424675049] 'process raft request' (duration: 387.744226ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:21:10.870336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:21:10.482443Z","time spent":"387.806709ms","remote":"127.0.0.1:44750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T11:21:10.870429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:21:10.481969Z","time spent":"388.387745ms","remote":"127.0.0.1:44750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T11:21:11.322806Z","caller":"traceutil/trace.go:171","msg":"trace[1435405993] linearizableReadLoop","detail":"{readStateIndex:21991; appliedIndex:21990; }","duration":"212.985963ms","start":"2026-08-22T11:21:11.109799Z","end":"2026-08-22T11:21:11.322785Z","steps":["trace[1435405993] 'read index received' (duration: 113.351198ms)","trace[1435405993] 'applied index is now lower than readState.Index' (duration: 99.634065ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:21:11.322836Z","caller":"traceutil/trace.go:171","msg":"trace[375890483] transaction","detail":"{read_only:false; response_revision:21267; number_of_response:1; }","duration":"214.024802ms","start":"2026-08-22T11:21:11.108772Z","end":"2026-08-22T11:21:11.322797Z","steps":["trace[375890483] 'process raft request' (duration: 114.376847ms)","trace[375890483] 'compare' (duration: 99.515021ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T11:21:11.322944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.142247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T11:21:11.322968Z","caller":"traceutil/trace.go:171","msg":"trace[1766952370] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21267; }","duration":"213.218679ms","start":"2026-08-22T11:21:11.109742Z","end":"2026-08-22T11:21:11.322961Z","steps":["trace[1766952370] 'agreement among raft nodes before linearized reading' (duration: 213.160438ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:21:11.469865Z","caller":"traceutil/trace.go:171","msg":"trace[1031727093] transaction","detail":"{read_only:false; response_revision:21269; number_of_response:1; }","duration":"208.690913ms","start":"2026-08-22T11:21:11.261124Z","end":"2026-08-22T11:21:11.469815Z","steps":["trace[1031727093] 'process raft request' (duration: 204.997619ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:22:45.292481Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19888} {"level":"info","ts":"2026-08-22T11:22:45.420785Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19888,"took":"124.604132ms","hash":1161528495,"current-db-size-bytes":58621952,"current-db-size":"59 MB","current-db-size-in-use-bytes":30040064,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-08-22T11:22:45.420905Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1161528495,"revision":19888,"compact-revision":17525} {"level":"info","ts":"2026-08-22T11:27:45.299455Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22071} {"level":"info","ts":"2026-08-22T11:27:45.409703Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22071,"took":"106.25525ms","hash":1610464053,"current-db-size-bytes":59064320,"current-db-size":"59 MB","current-db-size-in-use-bytes":44732416,"current-db-size-in-use":"45 MB"} {"level":"info","ts":"2026-08-22T11:27:45.409765Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1610464053,"revision":22071,"compact-revision":19888} {"level":"info","ts":"2026-08-22T11:28:42.501419Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-00000000000067d6.wal"} {"level":"info","ts":"2026-08-22T11:28:52.309711Z","caller":"traceutil/trace.go:171","msg":"trace[1470430356] transaction","detail":"{read_only:false; response_revision:25912; number_of_response:1; }","duration":"124.711625ms","start":"2026-08-22T11:28:52.18495Z","end":"2026-08-22T11:28:52.309661Z","steps":["trace[1470430356] 'process raft request' (duration: 124.450798ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:28:57.831714Z","caller":"traceutil/trace.go:171","msg":"trace[204691616] transaction","detail":"{read_only:false; response_revision:25952; number_of_response:1; }","duration":"188.199648ms","start":"2026-08-22T11:28:57.643496Z","end":"2026-08-22T11:28:57.831696Z","steps":["trace[204691616] 'process raft request' (duration: 188.066865ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:28:58.131646Z","caller":"traceutil/trace.go:171","msg":"trace[907940900] linearizableReadLoop","detail":"{readStateIndex:26816; appliedIndex:26812; }","duration":"486.145344ms","start":"2026-08-22T11:28:57.645479Z","end":"2026-08-22T11:28:58.131624Z","steps":["trace[907940900] 'read index received' (duration: 186.632435ms)","trace[907940900] 'applied index is now lower than readState.Index' (duration: 299.512009ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:28:58.13199Z","caller":"traceutil/trace.go:171","msg":"trace[1464835912] transaction","detail":"{read_only:false; response_revision:25953; number_of_response:1; }","duration":"488.315505ms","start":"2026-08-22T11:28:57.643662Z","end":"2026-08-22T11:28:58.131977Z","steps":["trace[1464835912] 'process raft request' (duration: 487.577264ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:28:58.132107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:28:57.643655Z","time spent":"488.373086ms","remote":"127.0.0.1:44750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T11:28:58.13231Z","caller":"traceutil/trace.go:171","msg":"trace[830321338] transaction","detail":"{read_only:false; response_revision:25954; number_of_response:1; }","duration":"487.815081ms","start":"2026-08-22T11:28:57.644484Z","end":"2026-08-22T11:28:58.1323Z","steps":["trace[830321338] 'process raft request' (duration: 486.953707ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:28:58.132364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:28:57.644475Z","time spent":"487.858232ms","remote":"127.0.0.1:44750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T11:28:58.132514Z","caller":"traceutil/trace.go:171","msg":"trace[442257319] transaction","detail":"{read_only:false; response_revision:25955; number_of_response:1; }","duration":"487.874732ms","start":"2026-08-22T11:28:57.644629Z","end":"2026-08-22T11:28:58.132504Z","steps":["trace[442257319] 'process raft request' (duration: 486.882075ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:28:58.13256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:28:57.644604Z","time spent":"487.931094ms","remote":"127.0.0.1:44750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T11:28:58.132688Z","caller":"traceutil/trace.go:171","msg":"trace[2135111465] transaction","detail":"{read_only:false; response_revision:25956; number_of_response:1; }","duration":"487.714468ms","start":"2026-08-22T11:28:57.644964Z","end":"2026-08-22T11:28:58.132679Z","steps":["trace[2135111465] 'process raft request' (duration: 486.583737ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:28:58.132733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:28:57.644956Z","time spent":"487.75323ms","remote":"127.0.0.1:44750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T11:28:58.132843Z","caller":"traceutil/trace.go:171","msg":"trace[2095453803] transaction","detail":"{read_only:false; response_revision:25957; number_of_response:1; }","duration":"435.663663ms","start":"2026-08-22T11:28:57.697171Z","end":"2026-08-22T11:28:58.132834Z","steps":["trace[2095453803] 'process raft request' (duration: 434.410778ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:28:58.132904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:28:57.697141Z","time spent":"435.735636ms","remote":"127.0.0.1:44750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T11:28:58.133103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"487.621326ms","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:28:58.13314Z","caller":"traceutil/trace.go:171","msg":"trace[323858792] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25957; }","duration":"487.682377ms","start":"2026-08-22T11:28:57.645448Z","end":"2026-08-22T11:28:58.13313Z","steps":["trace[323858792] 'agreement among raft nodes before linearized reading' (duration: 487.565013ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:28:58.133168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:28:57.645438Z","time spent":"487.723688ms","remote":"127.0.0.1:44750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-22T11:28:58.133313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.943136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-08-22T11:28:58.133361Z","caller":"traceutil/trace.go:171","msg":"trace[846811490] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25957; }","duration":"487.008579ms","start":"2026-08-22T11:28:57.646345Z","end":"2026-08-22T11:28:58.133353Z","steps":["trace[846811490] 'agreement among raft nodes before linearized reading' (duration: 486.914396ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:28:58.133389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:28:57.646337Z","time spent":"487.04526ms","remote":"127.0.0.1:44750","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":"warn","ts":"2026-08-22T11:28:58.133407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.705264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T11:28:58.133467Z","caller":"traceutil/trace.go:171","msg":"trace[1289470034] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25957; }","duration":"105.769395ms","start":"2026-08-22T11:28:58.027684Z","end":"2026-08-22T11:28:58.133454Z","steps":["trace[1289470034] 'agreement among raft nodes before linearized reading' (duration: 105.664883ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:28:58.133494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"484.317872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-08-22T11:28:58.133519Z","caller":"traceutil/trace.go:171","msg":"trace[472402519] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25957; }","duration":"484.354134ms","start":"2026-08-22T11:28:57.649157Z","end":"2026-08-22T11:28:58.133511Z","steps":["trace[472402519] 'agreement among raft nodes before linearized reading' (duration: 484.283302ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:28:58.133376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.727985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-08-22T11:28:58.133554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:28:57.649151Z","time spent":"484.394935ms","remote":"127.0.0.1:44750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-08-22T11:28:58.133593Z","caller":"traceutil/trace.go:171","msg":"trace[1513665383] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:25957; }","duration":"310.011753ms","start":"2026-08-22T11:28:57.823554Z","end":"2026-08-22T11:28:58.133566Z","steps":["trace[1513665383] 'agreement among raft nodes before linearized reading' (duration: 309.703854ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:28:58.133605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.246069ms","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":"warn","ts":"2026-08-22T11:28:58.133663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:28:57.823531Z","time spent":"310.113736ms","remote":"127.0.0.1:49328","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":31,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"info","ts":"2026-08-22T11:28:58.133716Z","caller":"traceutil/trace.go:171","msg":"trace[612915069] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25957; }","duration":"427.424454ms","start":"2026-08-22T11:28:57.706266Z","end":"2026-08-22T11:28:58.13369Z","steps":["trace[612915069] 'agreement among raft nodes before linearized reading' (duration: 427.045784ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:28:58.133766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:28:57.706248Z","time spent":"427.508846ms","remote":"127.0.0.1:44750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-08-22T11:28:58.284602Z","caller":"traceutil/trace.go:171","msg":"trace[1271559537] transaction","detail":"{read_only:false; response_revision:25958; number_of_response:1; }","duration":"146.268567ms","start":"2026-08-22T11:28:58.138296Z","end":"2026-08-22T11:28:58.284565Z","steps":["trace[1271559537] 'process raft request' (duration: 133.484069ms)","trace[1271559537] 'compare' (duration: 12.356146ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:28:58.284622Z","caller":"traceutil/trace.go:171","msg":"trace[1040819321] transaction","detail":"{read_only:false; response_revision:25960; number_of_response:1; }","duration":"143.504031ms","start":"2026-08-22T11:28:58.141083Z","end":"2026-08-22T11:28:58.284587Z","steps":["trace[1040819321] 'process raft request' (duration: 143.418068ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:28:58.284667Z","caller":"traceutil/trace.go:171","msg":"trace[291532568] transaction","detail":"{read_only:false; response_revision:25959; number_of_response:1; }","duration":"143.564482ms","start":"2026-08-22T11:28:58.141041Z","end":"2026-08-22T11:28:58.284605Z","steps":["trace[291532568] 'process raft request' (duration: 143.347016ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:28:58.284818Z","caller":"traceutil/trace.go:171","msg":"trace[1035378114] transaction","detail":"{read_only:false; response_revision:25961; number_of_response:1; }","duration":"143.631303ms","start":"2026-08-22T11:28:58.141175Z","end":"2026-08-22T11:28:58.284807Z","steps":["trace[1035378114] 'process raft request' (duration: 143.362125ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:29:02.677902Z","caller":"traceutil/trace.go:171","msg":"trace[572599361] transaction","detail":"{read_only:false; response_revision:25991; number_of_response:1; }","duration":"140.073194ms","start":"2026-08-22T11:29:02.537796Z","end":"2026-08-22T11:29:02.677869Z","steps":["trace[572599361] 'process raft request' (duration: 139.799176ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:29:02.67863Z","caller":"traceutil/trace.go:171","msg":"trace[988966151] linearizableReadLoop","detail":"{readStateIndex:26852; appliedIndex:26852; }","duration":"127.149832ms","start":"2026-08-22T11:29:02.551465Z","end":"2026-08-22T11:29:02.678615Z","steps":["trace[988966151] 'read index received' (duration: 127.143832ms)","trace[988966151] 'applied index is now lower than readState.Index' (duration: 5.1µs)"],"step_count":2} {"level":"warn","ts":"2026-08-22T11:29:02.678815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.332137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-08-22T11:29:02.678847Z","caller":"traceutil/trace.go:171","msg":"trace[1588743691] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25991; }","duration":"127.42588ms","start":"2026-08-22T11:29:02.551411Z","end":"2026-08-22T11:29:02.678837Z","steps":["trace[1588743691] 'agreement among raft nodes before linearized reading' (duration: 127.252405ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:29:02.783539Z","caller":"traceutil/trace.go:171","msg":"trace[1341333210] linearizableReadLoop","detail":"{readStateIndex:26853; appliedIndex:26852; }","duration":"100.493728ms","start":"2026-08-22T11:29:02.68301Z","end":"2026-08-22T11:29:02.783503Z","steps":["trace[1341333210] 'read index received' (duration: 68.3672ms)","trace[1341333210] 'applied index is now lower than readState.Index' (duration: 32.125018ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:29:02.783759Z","caller":"traceutil/trace.go:171","msg":"trace[1853115014] transaction","detail":"{read_only:false; response_revision:25992; number_of_response:1; }","duration":"227.399544ms","start":"2026-08-22T11:29:02.556316Z","end":"2026-08-22T11:29:02.783716Z","steps":["trace[1853115014] 'process raft request' (duration: 195.104491ms)","trace[1853115014] 'compare' (duration: 31.866581ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T11:29:02.783835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.792286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-08-22T11:29:02.78387Z","caller":"traceutil/trace.go:171","msg":"trace[1983033496] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25992; }","duration":"100.92842ms","start":"2026-08-22T11:29:02.682932Z","end":"2026-08-22T11:29:02.78386Z","steps":["trace[1983033496] 'agreement among raft nodes before linearized reading' (duration: 100.710324ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:29:02.965685Z","caller":"traceutil/trace.go:171","msg":"trace[575791888] transaction","detail":"{read_only:false; response_revision:25993; number_of_response:1; }","duration":"150.178977ms","start":"2026-08-22T11:29:02.815469Z","end":"2026-08-22T11:29:02.965648Z","steps":["trace[575791888] 'process raft request' (duration: 92.269739ms)","trace[575791888] 'compare' (duration: 57.408734ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T11:29:03.243139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.97918ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-22T11:29:03.243391Z","caller":"traceutil/trace.go:171","msg":"trace[2093656623] transaction","detail":"{read_only:false; response_revision:25994; number_of_response:1; }","duration":"200.961786ms","start":"2026-08-22T11:29:03.042378Z","end":"2026-08-22T11:29:03.24334Z","steps":["trace[2093656623] 'process raft request' (duration: 59.172434ms)","trace[2093656623] 'compare' (duration: 140.700341ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T11:29:03.938313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.877689ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-08-22T11:29:03.938502Z","caller":"traceutil/trace.go:171","msg":"trace[13103034] linearizableReadLoop","detail":"{readStateIndex:26856; appliedIndex:26855; }","duration":"261.61399ms","start":"2026-08-22T11:29:03.676849Z","end":"2026-08-22T11:29:03.938463Z","steps":["trace[13103034] 'read index received' (duration: 468.923µs)","trace[13103034] 'applied index is now lower than readState.Index' (duration: 261.139167ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:29:03.938564Z","caller":"traceutil/trace.go:171","msg":"trace[1300363790] transaction","detail":"{read_only:false; response_revision:25995; number_of_response:1; }","duration":"233.33195ms","start":"2026-08-22T11:29:03.705218Z","end":"2026-08-22T11:29:03.93855Z","steps":["trace[1300363790] 'process raft request' (duration: 233.187426ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:29:03.938753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.878827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-22T11:29:03.938794Z","caller":"traceutil/trace.go:171","msg":"trace[35475601] range","detail":"{range_begin:/registry/cert-manager.io/certificates/; range_end:/registry/cert-manager.io/certificates0; response_count:0; response_revision:25995; }","duration":"261.99351ms","start":"2026-08-22T11:29:03.676788Z","end":"2026-08-22T11:29:03.938781Z","steps":["trace[35475601] 'agreement among raft nodes before linearized reading' (duration: 261.806975ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:29:03.998013Z","caller":"traceutil/trace.go:171","msg":"trace[233977821] transaction","detail":"{read_only:false; response_revision:25996; number_of_response:1; }","duration":"143.425187ms","start":"2026-08-22T11:29:03.854531Z","end":"2026-08-22T11:29:03.997956Z","steps":["trace[233977821] 'process raft request' (duration: 143.16347ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:29:04.272848Z","caller":"traceutil/trace.go:171","msg":"trace[1282785942] transaction","detail":"{read_only:false; response_revision:25997; number_of_response:1; }","duration":"116.825293ms","start":"2026-08-22T11:29:04.155988Z","end":"2026-08-22T11:29:04.272813Z","steps":["trace[1282785942] 'process raft request' (duration: 116.654109ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:29:04.447409Z","caller":"traceutil/trace.go:171","msg":"trace[1079866885] transaction","detail":"{read_only:false; response_revision:25998; number_of_response:1; }","duration":"288.124901ms","start":"2026-08-22T11:29:04.159236Z","end":"2026-08-22T11:29:04.447361Z","steps":["trace[1079866885] 'process raft request' (duration: 287.491113ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:29:04.44751Z","caller":"traceutil/trace.go:171","msg":"trace[1748024563] transaction","detail":"{read_only:false; response_revision:26000; number_of_response:1; }","duration":"288.172542ms","start":"2026-08-22T11:29:04.159322Z","end":"2026-08-22T11:29:04.447495Z","steps":["trace[1748024563] 'process raft request' (duration: 287.972797ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:29:04.447651Z","caller":"traceutil/trace.go:171","msg":"trace[1357062024] transaction","detail":"{read_only:false; response_revision:25999; number_of_response:1; }","duration":"288.347777ms","start":"2026-08-22T11:29:04.159297Z","end":"2026-08-22T11:29:04.447644Z","steps":["trace[1357062024] 'process raft request' (duration: 287.966577ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:29:04.447861Z","caller":"traceutil/trace.go:171","msg":"trace[1554443177] transaction","detail":"{read_only:false; response_revision:26001; number_of_response:1; }","duration":"283.950795ms","start":"2026-08-22T11:29:04.163899Z","end":"2026-08-22T11:29:04.44785Z","steps":["trace[1554443177] 'process raft request' (duration: 283.42412ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:29:04.447367Z","caller":"traceutil/trace.go:171","msg":"trace[1955943905] linearizableReadLoop","detail":"{readStateIndex:26863; appliedIndex:26858; }","duration":"194.093534ms","start":"2026-08-22T11:29:04.253254Z","end":"2026-08-22T11:29:04.447348Z","steps":["trace[1955943905] 'read index received' (duration: 19.352361ms)","trace[1955943905] 'applied index is now lower than readState.Index' (duration: 174.740423ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T11:29:04.448449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.181205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T11:29:04.448476Z","caller":"traceutil/trace.go:171","msg":"trace[751971920] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:26001; }","duration":"195.260276ms","start":"2026-08-22T11:29:04.253207Z","end":"2026-08-22T11:29:04.448467Z","steps":["trace[751971920] 'agreement among raft nodes before linearized reading' (duration: 195.121332ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:29:04.720434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.553278ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-22T11:29:04.720714Z","caller":"traceutil/trace.go:171","msg":"trace[241100682] transaction","detail":"{read_only:false; response_revision:26003; number_of_response:1; }","duration":"167.194941ms","start":"2026-08-22T11:29:04.553495Z","end":"2026-08-22T11:29:04.720689Z","steps":["trace[241100682] 'process raft request' (duration: 167.070848ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:29:04.720713Z","caller":"traceutil/trace.go:171","msg":"trace[814581347] transaction","detail":"{read_only:false; response_revision:26002; number_of_response:1; }","duration":"300.950479ms","start":"2026-08-22T11:29:04.419721Z","end":"2026-08-22T11:29:04.720671Z","steps":["trace[814581347] 'process raft request' (duration: 160.037102ms)","trace[814581347] 'compare' (duration: 139.548539ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T11:29:04.72087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.285217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-08-22T11:29:04.720694Z","caller":"traceutil/trace.go:171","msg":"trace[1462858782] linearizableReadLoop","detail":"{readStateIndex:26864; appliedIndex:26863; }","duration":"272.389721ms","start":"2026-08-22T11:29:04.448283Z","end":"2026-08-22T11:29:04.720673Z","steps":["trace[1462858782] 'read index received' (duration: 131.382921ms)","trace[1462858782] 'applied index is now lower than readState.Index' (duration: 141.00589ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:29:04.720918Z","caller":"traceutil/trace.go:171","msg":"trace[1536717406] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26003; }","duration":"409.376869ms","start":"2026-08-22T11:29:04.311533Z","end":"2026-08-22T11:29:04.720909Z","steps":["trace[1536717406] 'agreement among raft nodes before linearized reading' (duration: 409.207735ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:29:04.72091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:29:04.419691Z","time spent":"301.115754ms","remote":"127.0.0.1:44750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T11:29:04.720946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:29:04.311475Z","time spent":"409.465501ms","remote":"127.0.0.1:44750","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":"warn","ts":"2026-08-22T11:29:04.721014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.65891ms","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:29:04.721069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.107256ms","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":"warn","ts":"2026-08-22T11:29:04.72106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.601419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/users/\" range_end:\"/registry/rabbitmq.com/users0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T11:29:04.721096Z","caller":"traceutil/trace.go:171","msg":"trace[1386294377] 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:26003; }","duration":"130.172797ms","start":"2026-08-22T11:29:04.590913Z","end":"2026-08-22T11:29:04.721086Z","steps":["trace[1386294377] 'agreement among raft nodes before linearized reading' (duration: 130.130656ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:29:04.721108Z","caller":"traceutil/trace.go:171","msg":"trace[1506107625] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26003; }","duration":"402.812794ms","start":"2026-08-22T11:29:04.318272Z","end":"2026-08-22T11:29:04.721085Z","steps":["trace[1506107625] 'agreement among raft nodes before linearized reading' (duration: 402.442414ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:29:04.721126Z","caller":"traceutil/trace.go:171","msg":"trace[1867873042] range","detail":"{range_begin:/registry/rabbitmq.com/users/; range_end:/registry/rabbitmq.com/users0; response_count:0; response_revision:26003; }","duration":"140.712392ms","start":"2026-08-22T11:29:04.580395Z","end":"2026-08-22T11:29:04.721108Z","steps":["trace[1867873042] 'agreement among raft nodes before linearized reading' (duration: 140.605129ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:29:04.721159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:29:04.31825Z","time spent":"402.901317ms","remote":"127.0.0.1:44750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-22T11:29:04.721119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.008463ms","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:29:04.72113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.022611ms","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:04.721233Z","caller":"traceutil/trace.go:171","msg":"trace[1932182891] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26003; }","duration":"402.186307ms","start":"2026-08-22T11:29:04.319017Z","end":"2026-08-22T11:29:04.721204Z","steps":["trace[1932182891] 'agreement among raft nodes before linearized reading' (duration: 401.780426ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:29:04.721267Z","caller":"traceutil/trace.go:171","msg":"trace[522995789] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26003; }","duration":"408.194375ms","start":"2026-08-22T11:29:04.313036Z","end":"2026-08-22T11:29:04.72123Z","steps":["trace[522995789] 'agreement among raft nodes before linearized reading' (duration: 407.704152ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:29:04.721301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:29:04.318992Z","time spent":"402.299371ms","remote":"127.0.0.1:44750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-22T11:29:04.721332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:29:04.313031Z","time spent":"408.290548ms","remote":"127.0.0.1:44750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-08-22T11:29:05.076319Z","caller":"traceutil/trace.go:171","msg":"trace[134185953] linearizableReadLoop","detail":"{readStateIndex:26868; appliedIndex:26865; }","duration":"347.377856ms","start":"2026-08-22T11:29:04.728907Z","end":"2026-08-22T11:29:05.076285Z","steps":["trace[134185953] 'read index received' (duration: 4.032113ms)","trace[134185953] 'applied index is now lower than readState.Index' (duration: 343.345023ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:29:05.076363Z","caller":"traceutil/trace.go:171","msg":"trace[329448640] transaction","detail":"{read_only:false; response_revision:26005; number_of_response:1; }","duration":"347.822768ms","start":"2026-08-22T11:29:04.728496Z","end":"2026-08-22T11:29:05.076318Z","steps":["trace[329448640] 'process raft request' (duration: 347.53501ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:29:05.076443Z","caller":"traceutil/trace.go:171","msg":"trace[1717148109] transaction","detail":"{read_only:false; response_revision:26007; number_of_response:1; }","duration":"346.972556ms","start":"2026-08-22T11:29:04.729464Z","end":"2026-08-22T11:29:05.076436Z","steps":["trace[1717148109] 'process raft request' (duration: 346.78874ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:29:05.076567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:29:04.72848Z","time spent":"347.975763ms","remote":"127.0.0.1:44750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T11:29:05.076657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.749526ms","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:05.076693Z","caller":"traceutil/trace.go:171","msg":"trace[22752763] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:26007; }","duration":"347.856559ms","start":"2026-08-22T11:29:04.728827Z","end":"2026-08-22T11:29:05.076683Z","steps":["trace[22752763] 'agreement among raft nodes before linearized reading' (duration: 347.777547ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:29:05.076716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:29:04.728816Z","time spent":"347.892961ms","remote":"127.0.0.1:44696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":29,"request content":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" "} {"level":"info","ts":"2026-08-22T11:29:05.076419Z","caller":"traceutil/trace.go:171","msg":"trace[653853906] transaction","detail":"{read_only:false; response_revision:26006; number_of_response:1; }","duration":"347.792358ms","start":"2026-08-22T11:29:04.728614Z","end":"2026-08-22T11:29:05.076407Z","steps":["trace[653853906] 'process raft request' (duration: 347.610263ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:29:05.076582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:29:04.729435Z","time spent":"347.041317ms","remote":"127.0.0.1:44750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T11:29:05.07703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:29:04.728571Z","time spent":"348.413885ms","remote":"127.0.0.1:44750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T11:29:05.097552Z","caller":"traceutil/trace.go:171","msg":"trace[700540819] transaction","detail":"{read_only:false; response_revision:26008; number_of_response:1; }","duration":"127.172344ms","start":"2026-08-22T11:29:04.970343Z","end":"2026-08-22T11:29:05.097515Z","steps":["trace[700540819] 'process raft request' (duration: 126.66668ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:29:05.098011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.624771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/groups/\" range_end:\"/registry/openstack.k-orc.cloud/groups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-22T11:29:05.098015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.845564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddresses/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddresses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T11:29:05.098079Z","caller":"traceutil/trace.go:171","msg":"trace[1362755901] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/groups/; range_end:/registry/openstack.k-orc.cloud/groups0; response_count:0; response_revision:26008; }","duration":"246.745374ms","start":"2026-08-22T11:29:04.851319Z","end":"2026-08-22T11:29:05.098064Z","steps":["trace[1362755901] 'agreement among raft nodes before linearized reading' (duration: 246.58811ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:29:05.098104Z","caller":"traceutil/trace.go:171","msg":"trace[1717536821] range","detail":"{range_begin:/registry/ipam.cluster.x-k8s.io/ipaddresses/; range_end:/registry/ipam.cluster.x-k8s.io/ipaddresses0; response_count:0; response_revision:26008; }","duration":"234.007909ms","start":"2026-08-22T11:29:04.864071Z","end":"2026-08-22T11:29:05.098079Z","steps":["trace[1717536821] 'agreement among raft nodes before linearized reading' (duration: 233.803074ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:29:06.423182Z","caller":"traceutil/trace.go:171","msg":"trace[2017321671] transaction","detail":"{read_only:false; response_revision:26011; number_of_response:1; }","duration":"145.180468ms","start":"2026-08-22T11:29:06.277974Z","end":"2026-08-22T11:29:06.423155Z","steps":["trace[2017321671] 'process raft request' (duration: 144.961502ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:29:06.647935Z","caller":"traceutil/trace.go:171","msg":"trace[1931017936] transaction","detail":"{read_only:false; response_revision:26012; number_of_response:1; }","duration":"194.952087ms","start":"2026-08-22T11:29:06.452964Z","end":"2026-08-22T11:29:06.647916Z","steps":["trace[1931017936] 'process raft request' (duration: 194.832313ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:29:06.679643Z","caller":"traceutil/trace.go:171","msg":"trace[382990484] transaction","detail":"{read_only:false; response_revision:26015; number_of_response:1; }","duration":"225.766618ms","start":"2026-08-22T11:29:06.453852Z","end":"2026-08-22T11:29:06.679619Z","steps":["trace[382990484] 'process raft request' (duration: 225.721337ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:29:06.679732Z","caller":"traceutil/trace.go:171","msg":"trace[166577522] transaction","detail":"{read_only:false; response_revision:26014; number_of_response:1; }","duration":"225.85452ms","start":"2026-08-22T11:29:06.453831Z","end":"2026-08-22T11:29:06.679685Z","steps":["trace[166577522] 'process raft request' (duration: 225.700546ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:29:06.679848Z","caller":"traceutil/trace.go:171","msg":"trace[1328191130] transaction","detail":"{read_only:false; response_revision:26013; number_of_response:1; }","duration":"225.944773ms","start":"2026-08-22T11:29:06.453709Z","end":"2026-08-22T11:29:06.679654Z","steps":["trace[1328191130] 'process raft request' (duration: 225.687986ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:29:06.878008Z","caller":"traceutil/trace.go:171","msg":"trace[195751864] linearizableReadLoop","detail":"{readStateIndex:26878; appliedIndex:26877; }","duration":"141.572455ms","start":"2026-08-22T11:29:06.736414Z","end":"2026-08-22T11:29:06.877987Z","steps":["trace[195751864] 'read index received' (duration: 128.682826ms)","trace[195751864] 'applied index is now lower than readState.Index' (duration: 12.888889ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T11:29:06.878306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.885385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-08-22T11:29:06.878342Z","caller":"traceutil/trace.go:171","msg":"trace[1713514218] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26016; }","duration":"141.966077ms","start":"2026-08-22T11:29:06.736358Z","end":"2026-08-22T11:29:06.878324Z","steps":["trace[1713514218] 'agreement among raft nodes before linearized reading' (duration: 141.702789ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:29:06.878541Z","caller":"traceutil/trace.go:171","msg":"trace[1814563702] transaction","detail":"{read_only:false; response_revision:26016; number_of_response:1; }","duration":"151.169633ms","start":"2026-08-22T11:29:06.727362Z","end":"2026-08-22T11:29:06.878532Z","steps":["trace[1814563702] 'process raft request' (duration: 137.76081ms)","trace[1814563702] 'compare' (duration: 12.383305ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:29:07.079072Z","caller":"traceutil/trace.go:171","msg":"trace[1995842024] transaction","detail":"{read_only:false; response_revision:26017; number_of_response:1; }","duration":"195.859233ms","start":"2026-08-22T11:29:06.883184Z","end":"2026-08-22T11:29:07.079044Z","steps":["trace[1995842024] 'process raft request' (duration: 161.74735ms)","trace[1995842024] 'compare' (duration: 33.630699ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:29:07.234478Z","caller":"traceutil/trace.go:171","msg":"trace[254324554] transaction","detail":"{read_only:false; response_revision:26021; number_of_response:1; }","duration":"126.64755ms","start":"2026-08-22T11:29:07.107802Z","end":"2026-08-22T11:29:07.23445Z","steps":["trace[254324554] 'process raft request' (duration: 122.514504ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:29:07.420808Z","caller":"traceutil/trace.go:171","msg":"trace[1601546368] transaction","detail":"{read_only:false; response_revision:26022; number_of_response:1; }","duration":"142.331247ms","start":"2026-08-22T11:29:07.278448Z","end":"2026-08-22T11:29:07.420779Z","steps":["trace[1601546368] 'process raft request' (duration: 140.367382ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:29:21.096838Z","caller":"traceutil/trace.go:171","msg":"trace[1718752098] transaction","detail":"{read_only:false; response_revision:26119; number_of_response:1; }","duration":"158.282843ms","start":"2026-08-22T11:29:20.938523Z","end":"2026-08-22T11:29:21.096806Z","steps":["trace[1718752098] 'process raft request' (duration: 158.065007ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:29:21.626076Z","caller":"traceutil/trace.go:171","msg":"trace[959633813] linearizableReadLoop","detail":"{readStateIndex:26985; appliedIndex:26984; }","duration":"462.243036ms","start":"2026-08-22T11:29:21.163809Z","end":"2026-08-22T11:29:21.626052Z","steps":["trace[959633813] 'read index received' (duration: 462.043581ms)","trace[959633813] 'applied index is now lower than readState.Index' (duration: 198.415µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:29:21.626232Z","caller":"traceutil/trace.go:171","msg":"trace[1239619968] transaction","detail":"{read_only:false; response_revision:26120; number_of_response:1; }","duration":"464.061377ms","start":"2026-08-22T11:29:21.16213Z","end":"2026-08-22T11:29:21.626191Z","steps":["trace[1239619968] 'process raft request' (duration: 463.759739ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:29:21.626368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:29:21.162107Z","time spent":"464.18239ms","remote":"127.0.0.1:44750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T11:29:21.626402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"462.578215ms","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:21.626481Z","caller":"traceutil/trace.go:171","msg":"trace[503419400] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26120; }","duration":"462.691729ms","start":"2026-08-22T11:29:21.163769Z","end":"2026-08-22T11:29:21.626461Z","steps":["trace[503419400] 'agreement among raft nodes before linearized reading' (duration: 462.463742ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:29:21.626526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:29:21.163756Z","time spent":"462.76257ms","remote":"127.0.0.1:44750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-08-22T11:29:21.6266Z","caller":"traceutil/trace.go:171","msg":"trace[1374435665] transaction","detail":"{read_only:false; response_revision:26121; number_of_response:1; }","duration":"461.601298ms","start":"2026-08-22T11:29:21.164984Z","end":"2026-08-22T11:29:21.626585Z","steps":["trace[1374435665] 'process raft request' (duration: 461.356281ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:29:21.626754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:29:21.164969Z","time spent":"461.707782ms","remote":"127.0.0.1:44750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T11:29:21.626809Z","caller":"traceutil/trace.go:171","msg":"trace[1912322223] transaction","detail":"{read_only:false; response_revision:26122; number_of_response:1; }","duration":"327.764018ms","start":"2026-08-22T11:29:21.299031Z","end":"2026-08-22T11:29:21.626795Z","steps":["trace[1912322223] 'process raft request' (duration: 327.421538ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:29:21.626878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.612503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"warn","ts":"2026-08-22T11:29:21.626909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:29:21.29901Z","time spent":"327.83364ms","remote":"127.0.0.1:44750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T11:29:21.626936Z","caller":"traceutil/trace.go:171","msg":"trace[1650771385] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26122; }","duration":"454.697565ms","start":"2026-08-22T11:29:21.172225Z","end":"2026-08-22T11:29:21.626923Z","steps":["trace[1650771385] 'agreement among raft nodes before linearized reading' (duration: 454.50981ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:29:21.626975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:29:21.172212Z","time spent":"454.755197ms","remote":"127.0.0.1:44750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-22T11:29:21.627138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.263578ms","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:29:21.62784Z","caller":"traceutil/trace.go:171","msg":"trace[1253702105] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26122; }","duration":"121.972338ms","start":"2026-08-22T11:29:21.505825Z","end":"2026-08-22T11:29:21.627797Z","steps":["trace[1253702105] 'agreement among raft nodes before linearized reading' (duration: 121.225567ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:29:21.895739Z","caller":"traceutil/trace.go:171","msg":"trace[2078464396] transaction","detail":"{read_only:false; response_revision:26124; number_of_response:1; }","duration":"264.004236ms","start":"2026-08-22T11:29:21.631709Z","end":"2026-08-22T11:29:21.895713Z","steps":["trace[2078464396] 'process raft request' (duration: 263.797361ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:29:21.895987Z","caller":"traceutil/trace.go:171","msg":"trace[194434862] transaction","detail":"{read_only:false; response_revision:26125; number_of_response:1; }","duration":"263.700658ms","start":"2026-08-22T11:29:21.632268Z","end":"2026-08-22T11:29:21.895969Z","steps":["trace[194434862] 'process raft request' (duration: 263.40593ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:29:22.716299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"688.348455ms","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:22.716386Z","caller":"traceutil/trace.go:171","msg":"trace[1129567508] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26125; }","duration":"688.487868ms","start":"2026-08-22T11:29:22.027878Z","end":"2026-08-22T11:29:22.716366Z","steps":["trace[1129567508] 'range keys from in-memory index tree' (duration: 688.261592ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:29:22.716429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:29:22.027853Z","time spent":"688.566251ms","remote":"127.0.0.1:44496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-22T11:29:22.716973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.158526ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-22T11:29:22.717066Z","caller":"traceutil/trace.go:171","msg":"trace[409701360] linearizableReadLoop","detail":"{readStateIndex:26991; appliedIndex:26990; }","duration":"340.627457ms","start":"2026-08-22T11:29:22.376424Z","end":"2026-08-22T11:29:22.717052Z","steps":["trace[409701360] 'read index received' (duration: 49.239475ms)","trace[409701360] 'applied index is now lower than readState.Index' (duration: 291.386662ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T11:29:22.717125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.701209ms","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:29:22.717106Z","caller":"traceutil/trace.go:171","msg":"trace[1297916634] transaction","detail":"{read_only:false; response_revision:26126; number_of_response:1; }","duration":"588.279018ms","start":"2026-08-22T11:29:22.128789Z","end":"2026-08-22T11:29:22.717068Z","steps":["trace[1297916634] 'process raft request' (duration: 296.952997ms)","trace[1297916634] 'compare' (duration: 290.531819ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:29:22.717144Z","caller":"traceutil/trace.go:171","msg":"trace[1198464888] 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:26126; }","duration":"340.773482ms","start":"2026-08-22T11:29:22.376363Z","end":"2026-08-22T11:29:22.717137Z","steps":["trace[1198464888] 'agreement among raft nodes before linearized reading' (duration: 340.72344ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:29:22.717169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:29:22.376318Z","time spent":"340.844153ms","remote":"127.0.0.1:39874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":128,"response count":0,"response size":29,"request content":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0\" count_only:true "} {"level":"warn","ts":"2026-08-22T11:29:22.717263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:29:22.128759Z","time spent":"588.394751ms","remote":"127.0.0.1:44750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T11:29:22.728008Z","caller":"traceutil/trace.go:171","msg":"trace[1757268624] transaction","detail":"{read_only:false; response_revision:26127; number_of_response:1; }","duration":"248.567116ms","start":"2026-08-22T11:29:22.479429Z","end":"2026-08-22T11:29:22.727996Z","steps":["trace[1757268624] 'process raft request' (duration: 248.451473ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:29:22.72925Z","caller":"traceutil/trace.go:171","msg":"trace[1505724526] transaction","detail":"{read_only:false; response_revision:26128; number_of_response:1; }","duration":"194.872505ms","start":"2026-08-22T11:29:22.534362Z","end":"2026-08-22T11:29:22.729234Z","steps":["trace[1505724526] 'process raft request' (duration: 194.752762ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:29:23.312174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.447087ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-22T11:29:23.312314Z","caller":"traceutil/trace.go:171","msg":"trace[730385769] transaction","detail":"{read_only:false; response_revision:26130; number_of_response:1; }","duration":"574.319298ms","start":"2026-08-22T11:29:22.737967Z","end":"2026-08-22T11:29:23.312287Z","steps":["trace[730385769] 'process raft request' (duration: 400.650085ms)","trace[730385769] 'compare' (duration: 173.218771ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T11:29:23.312397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:29:22.737948Z","time spent":"574.40736ms","remote":"127.0.0.1:44750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T11:29:23.314777Z","caller":"traceutil/trace.go:171","msg":"trace[376324542] transaction","detail":"{read_only:false; response_revision:26131; number_of_response:1; }","duration":"576.659254ms","start":"2026-08-22T11:29:22.738082Z","end":"2026-08-22T11:29:23.314741Z","steps":["trace[376324542] 'process raft request' (duration: 576.394346ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:29:23.314986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:29:22.738076Z","time spent":"576.799427ms","remote":"127.0.0.1:44750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T11:29:23.315071Z","caller":"traceutil/trace.go:171","msg":"trace[978003183] transaction","detail":"{read_only:false; response_revision:26132; number_of_response:1; }","duration":"561.053188ms","start":"2026-08-22T11:29:22.753986Z","end":"2026-08-22T11:29:23.315039Z","steps":["trace[978003183] 'process raft request' (duration: 560.679888ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:29:23.315188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:29:22.753976Z","time spent":"561.15085ms","remote":"127.0.0.1:44750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T11:29:23.315244Z","caller":"traceutil/trace.go:171","msg":"trace[207650958] transaction","detail":"{read_only:false; response_revision:26133; number_of_response:1; }","duration":"332.80134ms","start":"2026-08-22T11:29:22.982405Z","end":"2026-08-22T11:29:23.315206Z","steps":["trace[207650958] 'process raft request' (duration: 332.580193ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:29:23.315411Z","caller":"traceutil/trace.go:171","msg":"trace[542865281] transaction","detail":"{read_only:false; response_revision:26134; number_of_response:1; }","duration":"213.376522ms","start":"2026-08-22T11:29:23.102024Z","end":"2026-08-22T11:29:23.3154Z","steps":["trace[542865281] 'process raft request' (duration: 213.130395ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:29:23.315403Z","caller":"traceutil/trace.go:171","msg":"trace[1216498349] linearizableReadLoop","detail":"{readStateIndex:26997; appliedIndex:26994; }","duration":"468.175442ms","start":"2026-08-22T11:29:22.847188Z","end":"2026-08-22T11:29:23.315363Z","steps":["trace[1216498349] 'read index received' (duration: 291.545596ms)","trace[1216498349] 'applied index is now lower than readState.Index' (duration: 176.626726ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T11:29:23.315501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.324085ms","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:23.315528Z","caller":"traceutil/trace.go:171","msg":"trace[212261279] 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:26134; }","duration":"468.399448ms","start":"2026-08-22T11:29:22.847121Z","end":"2026-08-22T11:29:23.31552Z","steps":["trace[212261279] 'agreement among raft nodes before linearized reading' (duration: 468.347297ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:29:23.315544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:29:22.847083Z","time spent":"468.4575ms","remote":"127.0.0.1:40114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":144,"response count":0,"response size":29,"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:23.315561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:29:22.982368Z","time spent":"333.056417ms","remote":"127.0.0.1:44750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T11:29:23.315624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.18449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-22T11:29:23.315627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.696415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T11:29:23.315659Z","caller":"traceutil/trace.go:171","msg":"trace[219717708] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26134; }","duration":"289.287313ms","start":"2026-08-22T11:29:23.026363Z","end":"2026-08-22T11:29:23.31565Z","steps":["trace[219717708] 'agreement among raft nodes before linearized reading' (duration: 289.18019ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:29:23.315687Z","caller":"traceutil/trace.go:171","msg":"trace[1187221076] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:0; response_revision:26134; }","duration":"265.800177ms","start":"2026-08-22T11:29:23.049868Z","end":"2026-08-22T11:29:23.315668Z","steps":["trace[1187221076] 'agreement among raft nodes before linearized reading' (duration: 265.704925ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:29:23.530993Z","caller":"traceutil/trace.go:171","msg":"trace[2081946961] linearizableReadLoop","detail":"{readStateIndex:27000; appliedIndex:26999; }","duration":"200.812101ms","start":"2026-08-22T11:29:23.330143Z","end":"2026-08-22T11:29:23.530956Z","steps":["trace[2081946961] 'read index received' (duration: 200.465972ms)","trace[2081946961] 'applied index is now lower than readState.Index' (duration: 344.919µs)"],"step_count":2} {"level":"warn","ts":"2026-08-22T11:29:23.53132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.143941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-08-22T11:29:23.531298Z","caller":"traceutil/trace.go:171","msg":"trace[1294794360] transaction","detail":"{read_only:false; response_revision:26135; number_of_response:1; }","duration":"205.323317ms","start":"2026-08-22T11:29:23.325928Z","end":"2026-08-22T11:29:23.531252Z","steps":["trace[1294794360] 'process raft request' (duration: 204.779262ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:29:23.531373Z","caller":"traceutil/trace.go:171","msg":"trace[48792844] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26135; }","duration":"201.257443ms","start":"2026-08-22T11:29:23.330104Z","end":"2026-08-22T11:29:23.531361Z","steps":["trace[48792844] 'agreement among raft nodes before linearized reading' (duration: 200.987096ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:29:23.911281Z","caller":"traceutil/trace.go:171","msg":"trace[714097723] transaction","detail":"{read_only:false; response_revision:26140; number_of_response:1; }","duration":"188.457787ms","start":"2026-08-22T11:29:23.722789Z","end":"2026-08-22T11:29:23.911246Z","steps":["trace[714097723] 'process raft request' (duration: 188.25884ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:29:23.911318Z","caller":"traceutil/trace.go:171","msg":"trace[1642575059] transaction","detail":"{read_only:false; response_revision:26141; number_of_response:1; }","duration":"187.901551ms","start":"2026-08-22T11:29:23.723381Z","end":"2026-08-22T11:29:23.911283Z","steps":["trace[1642575059] 'process raft request' (duration: 187.782157ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:29:23.911468Z","caller":"traceutil/trace.go:171","msg":"trace[720069911] transaction","detail":"{read_only:false; response_revision:26142; number_of_response:1; }","duration":"187.331775ms","start":"2026-08-22T11:29:23.724111Z","end":"2026-08-22T11:29:23.911443Z","steps":["trace[720069911] 'process raft request' (duration: 187.078228ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:29:23.911504Z","caller":"traceutil/trace.go:171","msg":"trace[767108523] linearizableReadLoop","detail":"{readStateIndex:27008; appliedIndex:27004; }","duration":"181.609174ms","start":"2026-08-22T11:29:23.729846Z","end":"2026-08-22T11:29:23.911455Z","steps":["trace[767108523] 'read index received' (duration: 21.010166ms)","trace[767108523] 'applied index is now lower than readState.Index' (duration: 160.595808ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T11:29:23.911713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.842691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5652"} {"level":"info","ts":"2026-08-22T11:29:23.911831Z","caller":"traceutil/trace.go:171","msg":"trace[372117038] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:26142; }","duration":"182.024767ms","start":"2026-08-22T11:29:23.729763Z","end":"2026-08-22T11:29:23.911788Z","steps":["trace[372117038] 'agreement among raft nodes before linearized reading' (duration: 181.752469ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:29:24.198149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.150401ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:42"} {"level":"info","ts":"2026-08-22T11:29:24.198787Z","caller":"traceutil/trace.go:171","msg":"trace[1014348041] transaction","detail":"{read_only:false; response_revision:26144; number_of_response:1; }","duration":"282.250567ms","start":"2026-08-22T11:29:23.916506Z","end":"2026-08-22T11:29:24.198757Z","steps":["trace[1014348041] 'process raft request' (duration: 281.99153ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:29:24.198821Z","caller":"traceutil/trace.go:171","msg":"trace[1941758628] transaction","detail":"{read_only:false; response_revision:26143; number_of_response:1; }","duration":"282.737991ms","start":"2026-08-22T11:29:23.916048Z","end":"2026-08-22T11:29:24.198786Z","steps":["trace[1941758628] 'process raft request' (duration: 282.280118ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:29:24.198864Z","caller":"traceutil/trace.go:171","msg":"trace[723744583] transaction","detail":"{read_only:false; response_revision:26145; number_of_response:1; }","duration":"282.013929ms","start":"2026-08-22T11:29:23.916823Z","end":"2026-08-22T11:29:24.198836Z","steps":["trace[723744583] 'process raft request' (duration: 281.755182ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:29:24.199198Z","caller":"traceutil/trace.go:171","msg":"trace[680096594] transaction","detail":"{read_only:false; response_revision:26146; number_of_response:1; }","duration":"280.975151ms","start":"2026-08-22T11:29:23.918194Z","end":"2026-08-22T11:29:24.199169Z","steps":["trace[680096594] 'process raft request' (duration: 280.470007ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:29:24.19921Z","caller":"traceutil/trace.go:171","msg":"trace[261881102] linearizableReadLoop","detail":"{readStateIndex:27014; appliedIndex:27008; }","duration":"171.346568ms","start":"2026-08-22T11:29:24.027836Z","end":"2026-08-22T11:29:24.199183Z","steps":["trace[261881102] 'read index received' (duration: 32.168259ms)","trace[261881102] 'applied index is now lower than readState.Index' (duration: 139.177009ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T11:29:24.199368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.510823ms","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:24.199451Z","caller":"traceutil/trace.go:171","msg":"trace[1360068013] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26147; }","duration":"171.665316ms","start":"2026-08-22T11:29:24.027768Z","end":"2026-08-22T11:29:24.199433Z","steps":["trace[1360068013] 'agreement among raft nodes before linearized reading' (duration: 171.502632ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:29:24.199404Z","caller":"traceutil/trace.go:171","msg":"trace[960790275] transaction","detail":"{read_only:false; response_revision:26147; number_of_response:1; }","duration":"280.094097ms","start":"2026-08-22T11:29:23.919251Z","end":"2026-08-22T11:29:24.199345Z","steps":["trace[960790275] 'process raft request' (duration: 279.804449ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:29:25.75329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.232137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T11:29:25.753527Z","caller":"traceutil/trace.go:171","msg":"trace[356010622] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:26204; }","duration":"165.516805ms","start":"2026-08-22T11:29:25.587907Z","end":"2026-08-22T11:29:25.753424Z","steps":["trace[356010622] 'count revisions from in-memory index tree' (duration: 165.135334ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:29:27.835175Z","caller":"traceutil/trace.go:171","msg":"trace[1304432316] transaction","detail":"{read_only:false; response_revision:26221; number_of_response:1; }","duration":"100.905449ms","start":"2026-08-22T11:29:27.734242Z","end":"2026-08-22T11:29:27.835147Z","steps":["trace[1304432316] 'process raft request' (duration: 100.754165ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:29:27.952059Z","caller":"traceutil/trace.go:171","msg":"trace[265423123] linearizableReadLoop","detail":"{readStateIndex:27089; appliedIndex:27087; }","duration":"183.852818ms","start":"2026-08-22T11:29:27.768182Z","end":"2026-08-22T11:29:27.952035Z","steps":["trace[265423123] 'read index received' (duration: 66.800507ms)","trace[265423123] 'applied index is now lower than readState.Index' (duration: 117.0513ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:29:27.952233Z","caller":"traceutil/trace.go:171","msg":"trace[398074780] transaction","detail":"{read_only:false; response_revision:26222; number_of_response:1; }","duration":"192.472039ms","start":"2026-08-22T11:29:27.759748Z","end":"2026-08-22T11:29:27.95222Z","steps":["trace[398074780] 'process raft request' (duration: 185.193845ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:29:27.952634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.426883ms","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:27.952721Z","caller":"traceutil/trace.go:171","msg":"trace[294651391] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26222; }","duration":"184.562788ms","start":"2026-08-22T11:29:27.768126Z","end":"2026-08-22T11:29:27.952689Z","steps":["trace[294651391] 'agreement among raft nodes before linearized reading' (duration: 184.323921ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:29:33.647502Z","caller":"traceutil/trace.go:171","msg":"trace[806510795] transaction","detail":"{read_only:false; response_revision:26286; number_of_response:1; }","duration":"292.369479ms","start":"2026-08-22T11:29:33.355085Z","end":"2026-08-22T11:29:33.647454Z","steps":["trace[806510795] 'process raft request' (duration: 291.30509ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:29:33.647214Z","caller":"traceutil/trace.go:171","msg":"trace[52734989] transaction","detail":"{read_only:false; response_revision:26284; number_of_response:1; }","duration":"291.995058ms","start":"2026-08-22T11:29:33.355002Z","end":"2026-08-22T11:29:33.646997Z","steps":["trace[52734989] 'process raft request' (duration: 206.131209ms)","trace[52734989] 'compare' (duration: 83.716989ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:29:33.647731Z","caller":"traceutil/trace.go:171","msg":"trace[1461102801] transaction","detail":"{read_only:false; response_revision:26285; number_of_response:1; }","duration":"292.693738ms","start":"2026-08-22T11:29:33.355025Z","end":"2026-08-22T11:29:33.647719Z","steps":["trace[1461102801] 'process raft request' (duration: 290.96047ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:29:43.838544Z","caller":"traceutil/trace.go:171","msg":"trace[2123542914] transaction","detail":"{read_only:false; response_revision:26370; number_of_response:1; }","duration":"149.404285ms","start":"2026-08-22T11:29:43.689111Z","end":"2026-08-22T11:29:43.838515Z","steps":["trace[2123542914] 'process raft request' (duration: 149.201829ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:29:44.096921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.21116ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-22T11:29:44.097216Z","caller":"traceutil/trace.go:171","msg":"trace[1383611888] transaction","detail":"{read_only:false; response_revision:26371; number_of_response:1; }","duration":"407.826815ms","start":"2026-08-22T11:29:43.68933Z","end":"2026-08-22T11:29:44.097157Z","steps":["trace[1383611888] 'process raft request' (duration: 268.187493ms)","trace[1383611888] 'compare' (duration: 138.966744ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T11:29:44.09746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:29:43.689295Z","time spent":"408.040581ms","remote":"127.0.0.1:44750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T11:29:44.097599Z","caller":"traceutil/trace.go:171","msg":"trace[1851693424] transaction","detail":"{read_only:false; response_revision:26372; number_of_response:1; }","duration":"407.573088ms","start":"2026-08-22T11:29:43.68998Z","end":"2026-08-22T11:29:44.097553Z","steps":["trace[1851693424] 'process raft request' (duration: 407.107526ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:29:44.097758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:29:43.689965Z","time spent":"407.726702ms","remote":"127.0.0.1:44750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T11:29:44.378383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.92515ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-08-22T11:29:44.379599Z","caller":"traceutil/trace.go:171","msg":"trace[439084402] linearizableReadLoop","detail":"{readStateIndex:27246; appliedIndex:27241; }","duration":"343.487358ms","start":"2026-08-22T11:29:44.036078Z","end":"2026-08-22T11:29:44.379565Z","steps":["trace[439084402] 'read index received' (duration: 43.891346ms)","trace[439084402] 'applied index is now lower than readState.Index' (duration: 299.594212ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:29:44.37968Z","caller":"traceutil/trace.go:171","msg":"trace[1195021707] transaction","detail":"{read_only:false; response_revision:26373; number_of_response:1; }","duration":"426.573929ms","start":"2026-08-22T11:29:43.953064Z","end":"2026-08-22T11:29:44.379638Z","steps":["trace[1195021707] 'process raft request' (duration: 426.226899ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:29:44.379737Z","caller":"traceutil/trace.go:171","msg":"trace[1868235069] transaction","detail":"{read_only:false; response_revision:26374; number_of_response:1; }","duration":"371.50146ms","start":"2026-08-22T11:29:44.008222Z","end":"2026-08-22T11:29:44.379724Z","steps":["trace[1868235069] 'process raft request' (duration: 371.286994ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:29:44.379875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:29:43.953034Z","time spent":"426.740093ms","remote":"127.0.0.1:44750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T11:29:44.379913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:29:44.008203Z","time spent":"371.600223ms","remote":"127.0.0.1:44750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T11:29:44.380033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.392589ms","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:44.380103Z","caller":"traceutil/trace.go:171","msg":"trace[1660548885] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26374; }","duration":"341.509402ms","start":"2026-08-22T11:29:44.038572Z","end":"2026-08-22T11:29:44.380082Z","steps":["trace[1660548885] 'agreement among raft nodes before linearized reading' (duration: 341.268985ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:29:44.380163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:29:44.038553Z","time spent":"341.574484ms","remote":"127.0.0.1:44750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-22T11:29:44.38019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.854287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/probes/\" range_end:\"/registry/monitoring.coreos.com/probes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-22T11:29:44.380549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.462605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-08-22T11:29:44.380588Z","caller":"traceutil/trace.go:171","msg":"trace[1767338894] range","detail":"{range_begin:/registry/monitoring.coreos.com/probes/; range_end:/registry/monitoring.coreos.com/probes0; response_count:0; response_revision:26374; }","duration":"199.315479ms","start":"2026-08-22T11:29:44.181244Z","end":"2026-08-22T11:29:44.38056Z","steps":["trace[1767338894] 'agreement among raft nodes before linearized reading' (duration: 198.840746ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:29:44.380604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.350093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-08-22T11:29:44.380606Z","caller":"traceutil/trace.go:171","msg":"trace[345632042] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26374; }","duration":"344.555068ms","start":"2026-08-22T11:29:44.036036Z","end":"2026-08-22T11:29:44.380591Z","steps":["trace[345632042] 'agreement among raft nodes before linearized reading' (duration: 344.405654ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:29:44.380286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.884526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-neutron-server-0\" ","response":"range_response_count:1 size:1864"} {"level":"warn","ts":"2026-08-22T11:29:44.380652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:29:44.036017Z","time spent":"344.626689ms","remote":"127.0.0.1:44750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-22T11:29:44.380417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.700348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"warn","ts":"2026-08-22T11:29:44.380695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.707019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-22T11:29:44.380744Z","caller":"traceutil/trace.go:171","msg":"trace[2138939493] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:26374; }","duration":"263.815541ms","start":"2026-08-22T11:29:44.116921Z","end":"2026-08-22T11:29:44.380737Z","steps":["trace[2138939493] 'agreement among raft nodes before linearized reading' (duration: 263.338878ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:29:44.380732Z","caller":"traceutil/trace.go:171","msg":"trace[1050055568] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-neutron-server-0; range_end:; response_count:1; response_revision:26374; }","duration":"141.334819ms","start":"2026-08-22T11:29:44.239347Z","end":"2026-08-22T11:29:44.380682Z","steps":["trace[1050055568] 'agreement among raft nodes before linearized reading' (duration: 140.797954ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:29:44.380647Z","caller":"traceutil/trace.go:171","msg":"trace[240242317] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26374; }","duration":"338.406215ms","start":"2026-08-22T11:29:44.042231Z","end":"2026-08-22T11:29:44.380637Z","steps":["trace[240242317] 'agreement among raft nodes before linearized reading' (duration: 338.280262ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:29:44.380815Z","caller":"traceutil/trace.go:171","msg":"trace[190354125] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26374; }","duration":"104.18464ms","start":"2026-08-22T11:29:44.276567Z","end":"2026-08-22T11:29:44.380752Z","steps":["trace[190354125] 'agreement among raft nodes before linearized reading' (duration: 103.526882ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:29:44.380889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:29:44.042223Z","time spent":"338.640063ms","remote":"127.0.0.1:44750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-08-22T11:29:44.660515Z","caller":"traceutil/trace.go:171","msg":"trace[1287511207] transaction","detail":"{read_only:false; response_revision:26375; number_of_response:1; }","duration":"272.193155ms","start":"2026-08-22T11:29:44.388293Z","end":"2026-08-22T11:29:44.660486Z","steps":["trace[1287511207] 'process raft request' (duration: 271.977499ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:29:44.821295Z","caller":"traceutil/trace.go:171","msg":"trace[1672823725] linearizableReadLoop","detail":"{readStateIndex:27251; appliedIndex:27247; }","duration":"381.13055ms","start":"2026-08-22T11:29:44.440144Z","end":"2026-08-22T11:29:44.821275Z","steps":["trace[1672823725] 'read index received' (duration: 220.403118ms)","trace[1672823725] 'applied index is now lower than readState.Index' (duration: 160.726571ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:29:44.821389Z","caller":"traceutil/trace.go:171","msg":"trace[1981866403] transaction","detail":"{read_only:false; response_revision:26378; number_of_response:1; }","duration":"431.833746ms","start":"2026-08-22T11:29:44.389546Z","end":"2026-08-22T11:29:44.821379Z","steps":["trace[1981866403] 'process raft request' (duration: 431.655941ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:29:44.821485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:29:44.389537Z","time spent":"431.870637ms","remote":"127.0.0.1:44750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T11:29:44.821414Z","caller":"traceutil/trace.go:171","msg":"trace[1955415995] transaction","detail":"{read_only:false; response_revision:26377; number_of_response:1; }","duration":"431.832426ms","start":"2026-08-22T11:29:44.389536Z","end":"2026-08-22T11:29:44.821368Z","steps":["trace[1955415995] 'process raft request' (duration: 431.632641ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:29:44.82157Z","caller":"traceutil/trace.go:171","msg":"trace[556100006] transaction","detail":"{read_only:false; response_revision:26379; number_of_response:1; }","duration":"430.50337ms","start":"2026-08-22T11:29:44.391032Z","end":"2026-08-22T11:29:44.821535Z","steps":["trace[556100006] 'process raft request' (duration: 430.198981ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:29:44.821701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:29:44.389524Z","time spent":"432.068172ms","remote":"127.0.0.1:44750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T11:29:44.821692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.530621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-22T11:29:44.821767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:29:44.391022Z","time spent":"430.634253ms","remote":"127.0.0.1:44750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T11:29:44.82183Z","caller":"traceutil/trace.go:171","msg":"trace[1906455995] transaction","detail":"{read_only:false; response_revision:26376; number_of_response:1; }","duration":"433.417581ms","start":"2026-08-22T11:29:44.388401Z","end":"2026-08-22T11:29:44.821818Z","steps":["trace[1906455995] 'process raft request' (duration: 407.764274ms)","trace[1906455995] 'compare' (duration: 24.872895ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:29:44.821778Z","caller":"traceutil/trace.go:171","msg":"trace[1239728472] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:26379; }","duration":"381.662685ms","start":"2026-08-22T11:29:44.440092Z","end":"2026-08-22T11:29:44.821754Z","steps":["trace[1239728472] 'agreement among raft nodes before linearized reading' (duration: 381.528281ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:29:44.821918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:29:44.388379Z","time spent":"433.474693ms","remote":"127.0.0.1:44750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T11:29:44.821931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:29:44.440077Z","time spent":"381.844339ms","remote":"127.0.0.1:47726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":29,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"info","ts":"2026-08-22T11:29:45.032275Z","caller":"traceutil/trace.go:171","msg":"trace[647302123] transaction","detail":"{read_only:false; response_revision:26380; number_of_response:1; }","duration":"223.218188ms","start":"2026-08-22T11:29:44.809011Z","end":"2026-08-22T11:29:45.032229Z","steps":["trace[647302123] 'process raft request' (duration: 125.483277ms)","trace[647302123] 'compare' (duration: 97.446593ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:29:45.068149Z","caller":"traceutil/trace.go:171","msg":"trace[312674220] transaction","detail":"{read_only:false; response_revision:26381; number_of_response:1; }","duration":"258.530204ms","start":"2026-08-22T11:29:44.809587Z","end":"2026-08-22T11:29:45.068117Z","steps":["trace[312674220] 'process raft request' (duration: 258.265216ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:29:45.068484Z","caller":"traceutil/trace.go:171","msg":"trace[1903690351] linearizableReadLoop","detail":"{readStateIndex:27254; appliedIndex:27251; }","duration":"247.101805ms","start":"2026-08-22T11:29:44.821346Z","end":"2026-08-22T11:29:45.068448Z","steps":["trace[1903690351] 'read index received' (duration: 113.406249ms)","trace[1903690351] 'applied index is now lower than readState.Index' (duration: 133.693896ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T11:29:45.068826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.608433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-22T11:29:45.068868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.470252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-22T11:29:45.068877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"602.32397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/glance-api-54574d5d64-wngnz.18ce1b817adcec53\" ","response":"range_response_count:1 size:2108"} {"level":"info","ts":"2026-08-22T11:29:45.068918Z","caller":"traceutil/trace.go:171","msg":"trace[431271325] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates0; response_count:0; response_revision:26382; }","duration":"190.586825ms","start":"2026-08-22T11:29:44.878316Z","end":"2026-08-22T11:29:45.068903Z","steps":["trace[431271325] 'agreement among raft nodes before linearized reading' (duration: 190.481152ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:29:45.068914Z","caller":"traceutil/trace.go:171","msg":"trace[1504507415] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:26382; }","duration":"240.746247ms","start":"2026-08-22T11:29:44.828145Z","end":"2026-08-22T11:29:45.068891Z","steps":["trace[1504507415] 'agreement among raft nodes before linearized reading' (duration: 240.580913ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:29:45.068946Z","caller":"traceutil/trace.go:171","msg":"trace[541573700] range","detail":"{range_begin:/registry/events/openstack/glance-api-54574d5d64-wngnz.18ce1b817adcec53; range_end:; response_count:1; response_revision:26382; }","duration":"602.420853ms","start":"2026-08-22T11:29:44.466508Z","end":"2026-08-22T11:29:45.068928Z","steps":["trace[541573700] 'agreement among raft nodes before linearized reading' (duration: 602.194497ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:29:45.068978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:29:44.466497Z","time spent":"602.474294ms","remote":"127.0.0.1:44578","response type":"/etcdserverpb.KV/Range","request count":0,"request size":73,"response count":1,"response size":2131,"request content":"key:\"/registry/events/openstack/glance-api-54574d5d64-wngnz.18ce1b817adcec53\" "} {"level":"info","ts":"2026-08-22T11:29:45.069001Z","caller":"traceutil/trace.go:171","msg":"trace[1949238268] transaction","detail":"{read_only:false; response_revision:26382; number_of_response:1; }","duration":"258.998397ms","start":"2026-08-22T11:29:44.809958Z","end":"2026-08-22T11:29:45.068956Z","steps":["trace[1949238268] 'process raft request' (duration: 258.101092ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:29:45.069168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"507.452989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeatures0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-22T11:29:45.069196Z","caller":"traceutil/trace.go:171","msg":"trace[235207249] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/; range_end:/registry/nfd.k8s-sigs.io/nodefeatures0; response_count:0; response_revision:26382; }","duration":"507.533961ms","start":"2026-08-22T11:29:44.56165Z","end":"2026-08-22T11:29:45.069184Z","steps":["trace[235207249] 'agreement among raft nodes before linearized reading' (duration: 507.48369ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:29:45.06922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:29:44.561621Z","time spent":"507.593823ms","remote":"127.0.0.1:34804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":1,"response size":31,"request content":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeatures0\" count_only:true "} {"level":"warn","ts":"2026-08-22T11:29:50.62503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.547781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T11:29:50.625208Z","caller":"traceutil/trace.go:171","msg":"trace[214004042] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:26420; }","duration":"104.818279ms","start":"2026-08-22T11:29:50.52035Z","end":"2026-08-22T11:29:50.625168Z","steps":["trace[214004042] 'count revisions from in-memory index tree' (duration: 104.437178ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:29:50.805397Z","caller":"traceutil/trace.go:171","msg":"trace[530134807] transaction","detail":"{read_only:false; response_revision:26421; number_of_response:1; }","duration":"109.794458ms","start":"2026-08-22T11:29:50.695569Z","end":"2026-08-22T11:29:50.805364Z","steps":["trace[530134807] 'process raft request' (duration: 109.583732ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:29:50.998305Z","caller":"traceutil/trace.go:171","msg":"trace[1965723628] transaction","detail":"{read_only:false; response_revision:26422; number_of_response:1; }","duration":"123.584453ms","start":"2026-08-22T11:29:50.874694Z","end":"2026-08-22T11:29:50.998279Z","steps":["trace[1965723628] 'process raft request' (duration: 123.232513ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:29:50.998293Z","caller":"traceutil/trace.go:171","msg":"trace[763039507] transaction","detail":"{read_only:false; response_revision:26423; number_of_response:1; }","duration":"123.515131ms","start":"2026-08-22T11:29:50.874709Z","end":"2026-08-22T11:29:50.998224Z","steps":["trace[763039507] 'process raft request' (duration: 123.441719ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:29:51.070454Z","caller":"traceutil/trace.go:171","msg":"trace[1606933118] linearizableReadLoop","detail":"{readStateIndex:27298; appliedIndex:27294; }","duration":"102.148714ms","start":"2026-08-22T11:29:50.968275Z","end":"2026-08-22T11:29:51.070424Z","steps":["trace[1606933118] 'read index received' (duration: 29.632078ms)","trace[1606933118] 'applied index is now lower than readState.Index' (duration: 72.514846ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:29:51.070879Z","caller":"traceutil/trace.go:171","msg":"trace[1687394208] transaction","detail":"{read_only:false; response_revision:26424; number_of_response:1; }","duration":"196.088909ms","start":"2026-08-22T11:29:50.874767Z","end":"2026-08-22T11:29:51.070856Z","steps":["trace[1687394208] 'process raft request' (duration: 195.393489ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:29:51.071165Z","caller":"traceutil/trace.go:171","msg":"trace[383548503] transaction","detail":"{read_only:false; response_revision:26425; number_of_response:1; }","duration":"196.211543ms","start":"2026-08-22T11:29:50.874938Z","end":"2026-08-22T11:29:51.071149Z","steps":["trace[383548503] 'process raft request' (duration: 195.42873ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:29:51.071408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.102801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-22T11:29:51.071467Z","caller":"traceutil/trace.go:171","msg":"trace[502556391] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:26425; }","duration":"103.239885ms","start":"2026-08-22T11:29:50.968211Z","end":"2026-08-22T11:29:51.071451Z","steps":["trace[502556391] 'agreement among raft nodes before linearized reading' (duration: 103.119772ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:29:51.135963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.895553ms","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:51.136054Z","caller":"traceutil/trace.go:171","msg":"trace[987464404] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26426; }","duration":"109.047247ms","start":"2026-08-22T11:29:51.026986Z","end":"2026-08-22T11:29:51.136034Z","steps":["trace[987464404] 'agreement among raft nodes before linearized reading' (duration: 108.894702ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:29:51.202563Z","caller":"traceutil/trace.go:171","msg":"trace[1831439569] transaction","detail":"{read_only:false; response_revision:26428; number_of_response:1; }","duration":"112.403271ms","start":"2026-08-22T11:29:51.090125Z","end":"2026-08-22T11:29:51.202528Z","steps":["trace[1831439569] 'process raft request' (duration: 112.315158ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:29:51.202563Z","caller":"traceutil/trace.go:171","msg":"trace[1253522807] transaction","detail":"{read_only:false; response_revision:26427; number_of_response:1; }","duration":"112.614598ms","start":"2026-08-22T11:29:51.089908Z","end":"2026-08-22T11:29:51.202522Z","steps":["trace[1253522807] 'process raft request' (duration: 112.354211ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:29:51.203771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.305845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:1860"} {"level":"info","ts":"2026-08-22T11:29:51.203848Z","caller":"traceutil/trace.go:171","msg":"trace[414930393] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:26428; }","duration":"128.407368ms","start":"2026-08-22T11:29:51.07542Z","end":"2026-08-22T11:29:51.203827Z","steps":["trace[414930393] 'agreement among raft nodes before linearized reading' (duration: 127.221615ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:29:51.437861Z","caller":"traceutil/trace.go:171","msg":"trace[15967569] linearizableReadLoop","detail":"{readStateIndex:27302; appliedIndex:27301; }","duration":"235.324915ms","start":"2026-08-22T11:29:51.202488Z","end":"2026-08-22T11:29:51.437813Z","steps":["trace[15967569] 'read index received' (duration: 228.861294ms)","trace[15967569] 'applied index is now lower than readState.Index' (duration: 6.461951ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:29:51.438272Z","caller":"traceutil/trace.go:171","msg":"trace[708936804] transaction","detail":"{read_only:false; response_revision:26429; number_of_response:1; }","duration":"287.199835ms","start":"2026-08-22T11:29:51.151057Z","end":"2026-08-22T11:29:51.438257Z","steps":["trace[708936804] 'process raft request' (duration: 280.371804ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:29:51.438556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.324361ms","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:51.438601Z","caller":"traceutil/trace.go:171","msg":"trace[838579380] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26429; }","duration":"300.433455ms","start":"2026-08-22T11:29:51.138157Z","end":"2026-08-22T11:29:51.43859Z","steps":["trace[838579380] 'agreement among raft nodes before linearized reading' (duration: 300.339202ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:29:51.438634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:29:51.138135Z","time spent":"300.490656ms","remote":"127.0.0.1:44496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-22T11:29:51.438891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.574201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-e9227021-246a-44fa-86a4-73db5858ebf3\" ","response":"range_response_count:1 size:1322"} {"level":"info","ts":"2026-08-22T11:29:51.438938Z","caller":"traceutil/trace.go:171","msg":"trace[413718781] range","detail":"{range_begin:/registry/persistentvolumes/pvc-e9227021-246a-44fa-86a4-73db5858ebf3; range_end:; response_count:1; response_revision:26429; }","duration":"231.628783ms","start":"2026-08-22T11:29:51.207291Z","end":"2026-08-22T11:29:51.43892Z","steps":["trace[413718781] 'agreement among raft nodes before linearized reading' (duration: 231.492419ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:29:51.692424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.654363ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-22T11:29:51.692579Z","caller":"traceutil/trace.go:171","msg":"trace[394896419] transaction","detail":"{read_only:false; response_revision:26430; number_of_response:1; }","duration":"241.297844ms","start":"2026-08-22T11:29:51.451255Z","end":"2026-08-22T11:29:51.692553Z","steps":["trace[394896419] 'process raft request' (duration: 107.391782ms)","trace[394896419] 'compare' (duration: 133.480709ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:29:51.762246Z","caller":"traceutil/trace.go:171","msg":"trace[1286437509] transaction","detail":"{read_only:false; response_revision:26431; number_of_response:1; }","duration":"310.812955ms","start":"2026-08-22T11:29:51.451372Z","end":"2026-08-22T11:29:51.762185Z","steps":["trace[1286437509] 'process raft request' (duration: 310.521877ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:29:51.762456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:29:51.451365Z","time spent":"310.9755ms","remote":"127.0.0.1:44766","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1415,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T11:29:51.762453Z","caller":"traceutil/trace.go:171","msg":"trace[1725713223] transaction","detail":"{read_only:false; response_revision:26432; number_of_response:1; }","duration":"310.974179ms","start":"2026-08-22T11:29:51.451455Z","end":"2026-08-22T11:29:51.762429Z","steps":["trace[1725713223] 'process raft request' (duration: 310.613709ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:29:51.762564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:29:51.451434Z","time spent":"311.081394ms","remote":"127.0.0.1:44766","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1490,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T11:29:51.763029Z","caller":"traceutil/trace.go:171","msg":"trace[1930430411] transaction","detail":"{read_only:false; response_revision:26434; number_of_response:1; }","duration":"294.036986ms","start":"2026-08-22T11:29:51.468963Z","end":"2026-08-22T11:29:51.763Z","steps":["trace[1930430411] 'process raft request' (duration: 293.936613ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:29:51.763061Z","caller":"traceutil/trace.go:171","msg":"trace[37312674] transaction","detail":"{read_only:false; response_revision:26433; number_of_response:1; }","duration":"305.019723ms","start":"2026-08-22T11:29:51.457995Z","end":"2026-08-22T11:29:51.763014Z","steps":["trace[37312674] 'process raft request' (duration: 304.123688ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:29:51.764466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T11:29:51.457965Z","time spent":"306.394511ms","remote":"127.0.0.1:47730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9632,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T11:29:52.2847Z","caller":"traceutil/trace.go:171","msg":"trace[774073642] transaction","detail":"{read_only:false; response_revision:26441; number_of_response:1; }","duration":"161.107832ms","start":"2026-08-22T11:29:52.123553Z","end":"2026-08-22T11:29:52.284661Z","steps":["trace[774073642] 'process raft request' (duration: 97.854714ms)","trace[774073642] 'compare' (duration: 63.081433ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:29:53.980261Z","caller":"traceutil/trace.go:171","msg":"trace[1661086246] transaction","detail":"{read_only:false; response_revision:26458; number_of_response:1; }","duration":"109.495849ms","start":"2026-08-22T11:29:53.870741Z","end":"2026-08-22T11:29:53.980237Z","steps":["trace[1661086246] 'process raft request' (duration: 52.086076ms)","trace[1661086246] 'compare' (duration: 57.2955ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T11:30:01.219716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.294188ms","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:01.219824Z","caller":"traceutil/trace.go:171","msg":"trace[44761309] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26519; }","duration":"112.486273ms","start":"2026-08-22T11:30:01.107314Z","end":"2026-08-22T11:30:01.2198Z","steps":["trace[44761309] 'range keys from in-memory index tree' (duration: 112.195705ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:30:04.524244Z","caller":"traceutil/trace.go:171","msg":"trace[257363595] transaction","detail":"{read_only:false; response_revision:26552; number_of_response:1; }","duration":"116.738482ms","start":"2026-08-22T11:30:04.40747Z","end":"2026-08-22T11:30:04.524209Z","steps":["trace[257363595] 'process raft request' (duration: 116.479475ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:30:05.298828Z","caller":"traceutil/trace.go:171","msg":"trace[2115126700] transaction","detail":"{read_only:false; response_revision:26558; number_of_response:1; }","duration":"224.475903ms","start":"2026-08-22T11:30:05.074307Z","end":"2026-08-22T11:30:05.298783Z","steps":["trace[2115126700] 'process raft request' (duration: 167.356486ms)","trace[2115126700] 'compare' (duration: 56.5373ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:30:10.593933Z","caller":"traceutil/trace.go:171","msg":"trace[184031569] transaction","detail":"{read_only:false; response_revision:26609; number_of_response:1; }","duration":"110.050346ms","start":"2026-08-22T11:30:10.483851Z","end":"2026-08-22T11:30:10.593901Z","steps":["trace[184031569] 'process raft request' (duration: 109.851831ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:30:12.41799Z","caller":"traceutil/trace.go:171","msg":"trace[612769522] transaction","detail":"{read_only:false; response_revision:26621; number_of_response:1; }","duration":"146.872445ms","start":"2026-08-22T11:30:12.271064Z","end":"2026-08-22T11:30:12.417936Z","steps":["trace[612769522] 'process raft request' (duration: 92.878406ms)","trace[612769522] 'compare' (duration: 53.877726ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:30:12.503605Z","caller":"traceutil/trace.go:171","msg":"trace[1960165633] transaction","detail":"{read_only:false; response_revision:26622; number_of_response:1; }","duration":"213.266599ms","start":"2026-08-22T11:30:12.290283Z","end":"2026-08-22T11:30:12.50355Z","steps":["trace[1960165633] 'process raft request' (duration: 212.989831ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:30:14.132476Z","caller":"traceutil/trace.go:171","msg":"trace[1489498420] linearizableReadLoop","detail":"{readStateIndex:27512; appliedIndex:27511; }","duration":"105.95087ms","start":"2026-08-22T11:30:14.026497Z","end":"2026-08-22T11:30:14.132448Z","steps":["trace[1489498420] 'read index received' (duration: 105.680863ms)","trace[1489498420] 'applied index is now lower than readState.Index' (duration: 268.847µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:30:14.132553Z","caller":"traceutil/trace.go:171","msg":"trace[823745616] transaction","detail":"{read_only:false; response_revision:26634; number_of_response:1; }","duration":"137.389088ms","start":"2026-08-22T11:30:13.995127Z","end":"2026-08-22T11:30:14.132516Z","steps":["trace[823745616] 'process raft request' (duration: 137.118761ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T11:30:14.13265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.128595ms","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:14.132713Z","caller":"traceutil/trace.go:171","msg":"trace[88005321] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26634; }","duration":"106.230478ms","start":"2026-08-22T11:30:14.026459Z","end":"2026-08-22T11:30:14.132689Z","steps":["trace[88005321] 'agreement among raft nodes before linearized reading' (duration: 106.124015ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:30:18.540105Z","caller":"traceutil/trace.go:171","msg":"trace[656485462] transaction","detail":"{read_only:false; response_revision:26665; number_of_response:1; }","duration":"170.214347ms","start":"2026-08-22T11:30:18.369865Z","end":"2026-08-22T11:30:18.540079Z","steps":["trace[656485462] 'process raft request' (duration: 90.759237ms)","trace[656485462] 'compare' (duration: 79.206143ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T11:30:18.570262Z","caller":"traceutil/trace.go:171","msg":"trace[53581481] transaction","detail":"{read_only:false; response_revision:26667; number_of_response:1; }","duration":"134.249061ms","start":"2026-08-22T11:30:18.435937Z","end":"2026-08-22T11:30:18.570186Z","steps":["trace[53581481] 'process raft request' (duration: 134.171369ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:30:18.570297Z","caller":"traceutil/trace.go:171","msg":"trace[742673698] transaction","detail":"{read_only:false; response_revision:26666; number_of_response:1; }","duration":"138.350515ms","start":"2026-08-22T11:30:18.431903Z","end":"2026-08-22T11:30:18.570253Z","steps":["trace[742673698] 'process raft request' (duration: 138.012306ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T11:32:45.307027Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25251} {"level":"info","ts":"2026-08-22T11:32:45.472201Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25251,"took":"154.225624ms","hash":566154496,"current-db-size-bytes":59064320,"current-db-size":"59 MB","current-db-size-in-use-bytes":37859328,"current-db-size-in-use":"38 MB"} {"level":"info","ts":"2026-08-22T11:32:45.472266Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":566154496,"revision":25251,"compact-revision":22071} {"level":"info","ts":"2026-08-22T11:34:18.687061Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"f577bc5ae95d61e1","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-22T11:34:18.692899Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-08-22T11:34:18.693096Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-08-22T11:37:45.312795Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28106} {"level":"info","ts":"2026-08-22T11:37:45.450059Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28106,"took":"133.277182ms","hash":940769292,"current-db-size-bytes":59064320,"current-db-size":"59 MB","current-db-size-in-use-bytes":33378304,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-08-22T11:37:45.450112Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":940769292,"revision":28106,"compact-revision":25251} {"level":"info","ts":"2026-08-22T11:42:45.320877Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30621} {"level":"info","ts":"2026-08-22T11:42:45.458188Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30621,"took":"132.678906ms","hash":1225519876,"current-db-size-bytes":59064320,"current-db-size":"59 MB","current-db-size-in-use-bytes":30728192,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-22T11:42:45.458283Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1225519876,"revision":30621,"compact-revision":28106} {"level":"info","ts":"2026-08-22T11:47:45.327298Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32880} {"level":"info","ts":"2026-08-22T11:47:45.480601Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32880,"took":"148.456545ms","hash":682792484,"current-db-size-bytes":60108800,"current-db-size":"60 MB","current-db-size-in-use-bytes":30384128,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-08-22T11:47:45.48071Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":682792484,"revision":32880,"compact-revision":30621} {"level":"info","ts":"2026-08-22T11:52:45.340154Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35112} {"level":"info","ts":"2026-08-22T11:52:45.485055Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35112,"took":"140.350599ms","hash":133305524,"current-db-size-bytes":60579840,"current-db-size":"61 MB","current-db-size-in-use-bytes":31334400,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-22T11:52:45.485131Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":133305524,"revision":35112,"compact-revision":32880} {"level":"info","ts":"2026-08-22T11:55:44.13918Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"f577bc5ae95d61e1","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-22T11:55:44.14393Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-08-22T11:55:44.144077Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-08-22T11:57:45.346098Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37404} {"level":"info","ts":"2026-08-22T11:57:45.470167Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37404,"took":"119.341134ms","hash":1593801688,"current-db-size-bytes":60579840,"current-db-size":"61 MB","current-db-size-in-use-bytes":32579584,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-08-22T11:57:45.470264Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1593801688,"revision":37404,"compact-revision":35112} {"level":"info","ts":"2026-08-22T12:02:45.353203Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":39677} {"level":"info","ts":"2026-08-22T12:02:45.477983Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":39677,"took":"121.056422ms","hash":2294881978,"current-db-size-bytes":60579840,"current-db-size":"61 MB","current-db-size-in-use-bytes":31875072,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-22T12:02:45.478061Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2294881978,"revision":39677,"compact-revision":37404} {"level":"warn","ts":"2026-08-22T12:03:02.741252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.677161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/manila-ssh-keys\" ","response":"range_response_count:1 size:4425"} {"level":"info","ts":"2026-08-22T12:03:02.741336Z","caller":"traceutil/trace.go:171","msg":"trace[2101004058] range","detail":"{range_begin:/registry/secrets/openstack/manila-ssh-keys; range_end:; response_count:1; response_revision:42142; }","duration":"129.806265ms","start":"2026-08-22T12:03:02.611516Z","end":"2026-08-22T12:03:02.741322Z","steps":["trace[2101004058] 'range keys from in-memory index tree' (duration: 129.544847ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T12:03:02.741345Z","caller":"traceutil/trace.go:171","msg":"trace[2065370058] transaction","detail":"{read_only:false; response_revision:42143; number_of_response:1; }","duration":"128.744186ms","start":"2026-08-22T12:03:02.612573Z","end":"2026-08-22T12:03:02.741317Z","steps":["trace[2065370058] 'process raft request' (duration: 127.190513ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T12:03:02.743013Z","caller":"traceutil/trace.go:171","msg":"trace[309133051] linearizableReadLoop","detail":"{readStateIndex:43448; appliedIndex:43446; }","duration":"100.856341ms","start":"2026-08-22T12:03:02.642132Z","end":"2026-08-22T12:03:02.742988Z","steps":["trace[309133051] 'read index received' (duration: 97.646673ms)","trace[309133051] 'applied index is now lower than readState.Index' (duration: 3.208368ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T12:03:02.743237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.112468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/manila-rabbitmq-admin\" ","response":"range_response_count:1 size:754"} {"level":"info","ts":"2026-08-22T12:03:02.743269Z","caller":"traceutil/trace.go:171","msg":"trace[1629198010] range","detail":"{range_begin:/registry/secrets/openstack/manila-rabbitmq-admin; range_end:; response_count:1; response_revision:42144; }","duration":"101.18066ms","start":"2026-08-22T12:03:02.64208Z","end":"2026-08-22T12:03:02.743261Z","steps":["trace[1629198010] 'agreement among raft nodes before linearized reading' (duration: 101.066897ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T12:03:02.743453Z","caller":"traceutil/trace.go:171","msg":"trace[1192577982] transaction","detail":"{read_only:false; response_revision:42144; number_of_response:1; }","duration":"130.741121ms","start":"2026-08-22T12:03:02.612697Z","end":"2026-08-22T12:03:02.743438Z","steps":["trace[1192577982] 'process raft request' (duration: 130.160735ms)"],"step_count":1}