{"level":"warn","ts":"2026-08-19T20:30:17.827372Z","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-19T20:30:17.827508Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.9: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.9:2380","--initial-cluster=instance=https://199.19.213.9:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.9:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.9: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-19T20:30:17.827629Z","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-19T20:30:17.827652Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.9:2380"]} {"level":"info","ts":"2026-08-19T20:30:17.82768Z","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-19T20:30:17.828755Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.9:2379"]} {"level":"info","ts":"2026-08-19T20:30:17.828949Z","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.9:2380"],"listen-peer-urls":["https://199.19.213.9:2380"],"advertise-client-urls":["https://199.19.213.9:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.9:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.9: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-19T20:30:17.833149Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.796993ms"} {"level":"info","ts":"2026-08-19T20:30:17.847413Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"3a0c9d65f167b31b","cluster-id":"86e523b598166645"} {"level":"info","ts":"2026-08-19T20:30:17.847553Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3a0c9d65f167b31b switched to configuration voters=()"} {"level":"info","ts":"2026-08-19T20:30:17.847597Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3a0c9d65f167b31b became follower at term 0"} {"level":"info","ts":"2026-08-19T20:30:17.847626Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 3a0c9d65f167b31b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-19T20:30:17.847651Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3a0c9d65f167b31b became follower at term 1"} {"level":"info","ts":"2026-08-19T20:30:17.847742Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3a0c9d65f167b31b switched to configuration voters=(4182891215087711003)"} {"level":"warn","ts":"2026-08-19T20:30:17.852925Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-19T20:30:17.854144Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-19T20:30:17.856785Z","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-19T20:30:17.859324Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"3a0c9d65f167b31b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-19T20:30:17.85982Z","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-19T20:30:17.860035Z","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-19T20:30:17.860051Z","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-19T20:30:17.85985Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"3a0c9d65f167b31b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-19T20:30:17.861021Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3a0c9d65f167b31b switched to configuration voters=(4182891215087711003)"} {"level":"info","ts":"2026-08-19T20:30:17.862109Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"86e523b598166645","local-member-id":"3a0c9d65f167b31b","added-peer-id":"3a0c9d65f167b31b","added-peer-peer-urls":["https://199.19.213.9:2380"]} {"level":"info","ts":"2026-08-19T20:30:17.864303Z","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-19T20:30:17.864575Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"3a0c9d65f167b31b","initial-advertise-peer-urls":["https://199.19.213.9:2380"],"listen-peer-urls":["https://199.19.213.9:2380"],"advertise-client-urls":["https://199.19.213.9:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.9:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-19T20:30:17.864631Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-19T20:30:17.865556Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.9:2380"} {"level":"info","ts":"2026-08-19T20:30:17.865611Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.9:2380"} {"level":"info","ts":"2026-08-19T20:30:18.748928Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3a0c9d65f167b31b is starting a new election at term 1"} {"level":"info","ts":"2026-08-19T20:30:18.748997Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3a0c9d65f167b31b became pre-candidate at term 1"} {"level":"info","ts":"2026-08-19T20:30:18.749036Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3a0c9d65f167b31b received MsgPreVoteResp from 3a0c9d65f167b31b at term 1"} {"level":"info","ts":"2026-08-19T20:30:18.749056Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3a0c9d65f167b31b became candidate at term 2"} {"level":"info","ts":"2026-08-19T20:30:18.749067Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3a0c9d65f167b31b received MsgVoteResp from 3a0c9d65f167b31b at term 2"} {"level":"info","ts":"2026-08-19T20:30:18.749082Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3a0c9d65f167b31b became leader at term 2"} {"level":"info","ts":"2026-08-19T20:30:18.749095Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 3a0c9d65f167b31b elected leader 3a0c9d65f167b31b at term 2"} {"level":"info","ts":"2026-08-19T20:30:18.750438Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-19T20:30:18.750396Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-19T20:30:18.750541Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-19T20:30:18.751198Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-19T20:30:18.751273Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-19T20:30:18.751548Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"86e523b598166645","local-member-id":"3a0c9d65f167b31b","cluster-version":"3.5"} {"level":"info","ts":"2026-08-19T20:30:18.751618Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-19T20:30:18.751652Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-19T20:30:18.753823Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-19T20:30:18.75392Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.9:2379"} {"level":"info","ts":"2026-08-19T20:30:18.750393Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"3a0c9d65f167b31b","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.9:2379]}","request-path":"/0/members/3a0c9d65f167b31b/attributes","cluster-id":"86e523b598166645","publish-timeout":"7s"} {"level":"info","ts":"2026-08-19T20:30:32.444806Z","caller":"traceutil/trace.go:171","msg":"trace[372390215] transaction","detail":"{read_only:false; response_revision:412; number_of_response:1; }","duration":"119.110173ms","start":"2026-08-19T20:30:32.325674Z","end":"2026-08-19T20:30:32.444784Z","steps":["trace[372390215] 'process raft request' (duration: 118.99218ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:30:54.828408Z","caller":"traceutil/trace.go:171","msg":"trace[1755642500] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"108.186537ms","start":"2026-08-19T20:30:54.720198Z","end":"2026-08-19T20:30:54.828385Z","steps":["trace[1755642500] 'process raft request' (duration: 108.025023ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:30:54.834214Z","caller":"traceutil/trace.go:171","msg":"trace[266232366] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"109.037719ms","start":"2026-08-19T20:30:54.725147Z","end":"2026-08-19T20:30:54.834184Z","steps":["trace[266232366] 'process raft request' (duration: 108.547607ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:30:54.834247Z","caller":"traceutil/trace.go:171","msg":"trace[1183478484] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"108.32594ms","start":"2026-08-19T20:30:54.725872Z","end":"2026-08-19T20:30:54.834198Z","steps":["trace[1183478484] 'process raft request' (duration: 107.89968ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:30:54.834337Z","caller":"traceutil/trace.go:171","msg":"trace[507364176] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"100.07191ms","start":"2026-08-19T20:30:54.734167Z","end":"2026-08-19T20:30:54.834239Z","steps":["trace[507364176] 'process raft request' (duration: 99.63433ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:30:56.138268Z","caller":"traceutil/trace.go:171","msg":"trace[109177859] linearizableReadLoop","detail":"{readStateIndex:623; appliedIndex:622; }","duration":"165.036909ms","start":"2026-08-19T20:30:55.973212Z","end":"2026-08-19T20:30:56.138249Z","steps":["trace[109177859] 'read index received' (duration: 139.10462ms)","trace[109177859] 'applied index is now lower than readState.Index' (duration: 25.931599ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T20:30:56.138409Z","caller":"traceutil/trace.go:171","msg":"trace[1730030562] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"174.94178ms","start":"2026-08-19T20:30:55.96341Z","end":"2026-08-19T20:30:56.138352Z","steps":["trace[1730030562] 'process raft request' (duration: 148.920928ms)","trace[1730030562] 'compare' (duration: 25.781066ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T20:30:56.138807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.553361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/httproutes.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:151236"} {"level":"info","ts":"2026-08-19T20:30:56.138904Z","caller":"traceutil/trace.go:171","msg":"trace[958030281] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/httproutes.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:602; }","duration":"165.704115ms","start":"2026-08-19T20:30:55.97316Z","end":"2026-08-19T20:30:56.138864Z","steps":["trace[958030281] 'agreement among raft nodes before linearized reading' (duration: 165.161312ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:30:56.143464Z","caller":"traceutil/trace.go:171","msg":"trace[553537659] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"130.582603ms","start":"2026-08-19T20:30:56.012851Z","end":"2026-08-19T20:30:56.143434Z","steps":["trace[553537659] 'process raft request' (duration: 128.998354ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:31:00.891172Z","caller":"traceutil/trace.go:171","msg":"trace[1484608872] linearizableReadLoop","detail":"{readStateIndex:657; appliedIndex:656; }","duration":"117.815092ms","start":"2026-08-19T20:31:00.773327Z","end":"2026-08-19T20:31:00.891142Z","steps":["trace[1484608872] 'read index received' (duration: 116.93222ms)","trace[1484608872] 'applied index is now lower than readState.Index' (duration: 881.912µs)"],"step_count":2} {"level":"info","ts":"2026-08-19T20:31:00.891215Z","caller":"traceutil/trace.go:171","msg":"trace[1520193202] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"126.490632ms","start":"2026-08-19T20:31:00.76468Z","end":"2026-08-19T20:31:00.89117Z","steps":["trace[1520193202] 'process raft request' (duration: 125.919579ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:31:00.89137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.009236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-8ngmp\" ","response":"range_response_count:1 size:16957"} {"level":"info","ts":"2026-08-19T20:31:00.891435Z","caller":"traceutil/trace.go:171","msg":"trace[2131819445] range","detail":"{range_begin:/registry/pods/kube-system/cilium-8ngmp; range_end:; response_count:1; response_revision:635; }","duration":"118.14279ms","start":"2026-08-19T20:31:00.773277Z","end":"2026-08-19T20:31:00.89142Z","steps":["trace[2131819445] 'agreement among raft nodes before linearized reading' (duration: 117.980935ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:31:01.293321Z","caller":"traceutil/trace.go:171","msg":"trace[1824178029] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"380.584895ms","start":"2026-08-19T20:31:00.912711Z","end":"2026-08-19T20:31:01.293296Z","steps":["trace[1824178029] 'process raft request' (duration: 380.419241ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:31:01.293904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:31:00.912657Z","time spent":"380.743348ms","remote":"127.0.0.1:53336","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17126,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-19T20:31:01.346019Z","caller":"traceutil/trace.go:171","msg":"trace[406323900] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"385.920815ms","start":"2026-08-19T20:31:00.960081Z","end":"2026-08-19T20:31:01.346002Z","steps":["trace[406323900] 'process raft request' (duration: 377.656174ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:31:01.345983Z","caller":"traceutil/trace.go:171","msg":"trace[1411186685] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"305.695475ms","start":"2026-08-19T20:31:01.040262Z","end":"2026-08-19T20:31:01.345958Z","steps":["trace[1411186685] 'process raft request' (duration: 305.630314ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:31:01.346148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:31:00.960043Z","time spent":"386.034427ms","remote":"127.0.0.1:53648","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-19T20:31:01.346187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:31:01.040236Z","time spent":"305.88348ms","remote":"127.0.0.1:53414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-19T20:33:19.183886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.917071ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-19T20:33:19.184015Z","caller":"traceutil/trace.go:171","msg":"trace[1299085094] linearizableReadLoop","detail":"{readStateIndex:2257; appliedIndex:2256; }","duration":"215.229345ms","start":"2026-08-19T20:33:18.96876Z","end":"2026-08-19T20:33:19.183989Z","steps":["trace[1299085094] 'read index received' (duration: 45.838512ms)","trace[1299085094] 'applied index is now lower than readState.Index' (duration: 169.388813ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T20:33:19.184016Z","caller":"traceutil/trace.go:171","msg":"trace[1156375443] transaction","detail":"{read_only:false; response_revision:2178; number_of_response:1; }","duration":"219.950301ms","start":"2026-08-19T20:33:18.964036Z","end":"2026-08-19T20:33:19.183986Z","steps":["trace[1156375443] 'process raft request' (duration: 50.607249ms)","trace[1156375443] 'compare' (duration: 168.648465ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T20:33:19.184273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.480422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T20:33:19.18439Z","caller":"traceutil/trace.go:171","msg":"trace[1129155540] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:2178; }","duration":"215.608115ms","start":"2026-08-19T20:33:18.968745Z","end":"2026-08-19T20:33:19.184353Z","steps":["trace[1129155540] 'agreement among raft nodes before linearized reading' (duration: 215.299616ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:34:34.541221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.880991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T20:34:34.541345Z","caller":"traceutil/trace.go:171","msg":"trace[1336152037] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2782; }","duration":"162.067317ms","start":"2026-08-19T20:34:34.379259Z","end":"2026-08-19T20:34:34.541326Z","steps":["trace[1336152037] 'range keys from in-memory index tree' (duration: 161.797059ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:38:22.405502Z","caller":"traceutil/trace.go:171","msg":"trace[394132590] linearizableReadLoop","detail":"{readStateIndex:5103; appliedIndex:5102; }","duration":"101.362359ms","start":"2026-08-19T20:38:22.304118Z","end":"2026-08-19T20:38:22.405481Z","steps":["trace[394132590] 'read index received' (duration: 101.077571ms)","trace[394132590] 'applied index is now lower than readState.Index' (duration: 283.647µs)"],"step_count":2} {"level":"info","ts":"2026-08-19T20:38:22.40565Z","caller":"traceutil/trace.go:171","msg":"trace[1381684982] transaction","detail":"{read_only:false; response_revision:4920; number_of_response:1; }","duration":"172.084825ms","start":"2026-08-19T20:38:22.233529Z","end":"2026-08-19T20:38:22.405614Z","steps":["trace[1381684982] 'process raft request' (duration: 171.765216ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:38:22.405684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.533024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/bindings/\" range_end:\"/registry/rabbitmq.com/bindings0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T20:38:22.405827Z","caller":"traceutil/trace.go:171","msg":"trace[2133570829] range","detail":"{range_begin:/registry/rabbitmq.com/bindings/; range_end:/registry/rabbitmq.com/bindings0; response_count:0; response_revision:4920; }","duration":"101.744749ms","start":"2026-08-19T20:38:22.304072Z","end":"2026-08-19T20:38:22.405816Z","steps":["trace[2133570829] 'agreement among raft nodes before linearized reading' (duration: 101.549494ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:38:43.734153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.825753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-5273577b-2735-493b-83a4-9a2e935b207b\" ","response":"range_response_count:1 size:3644"} {"level":"info","ts":"2026-08-19T20:38:43.734243Z","caller":"traceutil/trace.go:171","msg":"trace[182447926] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-5273577b-2735-493b-83a4-9a2e935b207b; range_end:; response_count:1; response_revision:5379; }","duration":"150.983816ms","start":"2026-08-19T20:38:43.58324Z","end":"2026-08-19T20:38:43.734223Z","steps":["trace[182447926] 'range keys from in-memory index tree' (duration: 150.630918ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:38:46.486375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.642303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T20:38:46.48649Z","caller":"traceutil/trace.go:171","msg":"trace[2015717284] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5412; }","duration":"107.831898ms","start":"2026-08-19T20:38:46.378632Z","end":"2026-08-19T20:38:46.486464Z","steps":["trace[2015717284] 'range keys from in-memory index tree' (duration: 107.54426ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:38:54.71859Z","caller":"traceutil/trace.go:171","msg":"trace[1926739172] transaction","detail":"{read_only:false; response_revision:5504; number_of_response:1; }","duration":"104.805003ms","start":"2026-08-19T20:38:54.613756Z","end":"2026-08-19T20:38:54.718561Z","steps":["trace[1926739172] 'process raft request' (duration: 104.614108ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:40:18.78359Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3024} {"level":"info","ts":"2026-08-19T20:40:18.876392Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3024,"took":"90.212364ms","hash":2161477869,"current-db-size-bytes":59461632,"current-db-size":"60 MB","current-db-size-in-use-bytes":32456704,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-19T20:40:18.876497Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2161477869,"revision":3024,"compact-revision":-1} {"level":"warn","ts":"2026-08-19T20:42:19.947077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.090894ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-19T20:42:19.947186Z","caller":"traceutil/trace.go:171","msg":"trace[749205000] linearizableReadLoop","detail":"{readStateIndex:7214; appliedIndex:7213; }","duration":"138.676948ms","start":"2026-08-19T20:42:19.808495Z","end":"2026-08-19T20:42:19.947172Z","steps":["trace[749205000] 'read index received' (duration: 20.335888ms)","trace[749205000] 'applied index is now lower than readState.Index' (duration: 118.34006ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T20:42:19.947238Z","caller":"traceutil/trace.go:171","msg":"trace[1619171064] transaction","detail":"{read_only:false; response_revision:6968; number_of_response:1; }","duration":"141.15801ms","start":"2026-08-19T20:42:19.806041Z","end":"2026-08-19T20:42:19.947199Z","steps":["trace[1619171064] 'process raft request' (duration: 22.875571ms)","trace[1619171064] 'compare' (duration: 117.978091ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T20:42:19.947363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.872223ms","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-19T20:42:19.947386Z","caller":"traceutil/trace.go:171","msg":"trace[1931826196] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:6968; }","duration":"138.932054ms","start":"2026-08-19T20:42:19.808447Z","end":"2026-08-19T20:42:19.947379Z","steps":["trace[1931826196] 'agreement among raft nodes before linearized reading' (duration: 138.802921ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:42:19.947646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.470679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagerconfigs/\" range_end:\"/registry/monitoring.coreos.com/alertmanagerconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-19T20:42:19.947735Z","caller":"traceutil/trace.go:171","msg":"trace[379874581] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagerconfigs/; range_end:/registry/monitoring.coreos.com/alertmanagerconfigs0; response_count:0; response_revision:6968; }","duration":"117.645523ms","start":"2026-08-19T20:42:19.830069Z","end":"2026-08-19T20:42:19.947714Z","steps":["trace[379874581] 'agreement among raft nodes before linearized reading' (duration: 117.371486ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:44:46.059888Z","caller":"traceutil/trace.go:171","msg":"trace[2000398768] transaction","detail":"{read_only:false; response_revision:8162; number_of_response:1; }","duration":"157.113891ms","start":"2026-08-19T20:44:45.902753Z","end":"2026-08-19T20:44:46.059867Z","steps":["trace[2000398768] 'process raft request' (duration: 157.000669ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:44:46.08946Z","caller":"traceutil/trace.go:171","msg":"trace[1743790627] transaction","detail":"{read_only:false; response_revision:8163; number_of_response:1; }","duration":"180.648547ms","start":"2026-08-19T20:44:45.908778Z","end":"2026-08-19T20:44:46.089427Z","steps":["trace[1743790627] 'process raft request' (duration: 180.34342ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:44:46.84621Z","caller":"traceutil/trace.go:171","msg":"trace[1913701928] transaction","detail":"{read_only:false; response_revision:8164; number_of_response:1; }","duration":"202.610306ms","start":"2026-08-19T20:44:46.643576Z","end":"2026-08-19T20:44:46.846186Z","steps":["trace[1913701928] 'process raft request' (duration: 202.471322ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:44:47.037218Z","caller":"traceutil/trace.go:171","msg":"trace[711996616] linearizableReadLoop","detail":"{readStateIndex:8448; appliedIndex:8446; }","duration":"390.237744ms","start":"2026-08-19T20:44:46.646966Z","end":"2026-08-19T20:44:47.037204Z","steps":["trace[711996616] 'read index received' (duration: 199.053219ms)","trace[711996616] 'applied index is now lower than readState.Index' (duration: 191.183815ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T20:44:47.037277Z","caller":"traceutil/trace.go:171","msg":"trace[242428370] transaction","detail":"{read_only:false; response_revision:8165; number_of_response:1; }","duration":"392.333825ms","start":"2026-08-19T20:44:46.644915Z","end":"2026-08-19T20:44:47.037249Z","steps":["trace[242428370] 'process raft request' (duration: 392.186502ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:44:47.037376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.401999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-08-19T20:44:47.03741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:44:46.644852Z","time spent":"392.4848ms","remote":"127.0.0.1:53414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-19T20:44:47.037422Z","caller":"traceutil/trace.go:171","msg":"trace[2011445216] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8165; }","duration":"390.489901ms","start":"2026-08-19T20:44:46.646921Z","end":"2026-08-19T20:44:47.037411Z","steps":["trace[2011445216] 'agreement among raft nodes before linearized reading' (duration: 390.353767ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:44:47.037455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:44:46.64691Z","time spent":"390.537672ms","remote":"127.0.0.1:53414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-08-19T20:44:48.197767Z","caller":"traceutil/trace.go:171","msg":"trace[191108631] transaction","detail":"{read_only:false; response_revision:8173; number_of_response:1; }","duration":"102.869372ms","start":"2026-08-19T20:44:48.09487Z","end":"2026-08-19T20:44:48.197739Z","steps":["trace[191108631] 'process raft request' (duration: 75.726156ms)","trace[191108631] 'compare' (duration: 27.025463ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T20:45:15.296421Z","caller":"traceutil/trace.go:171","msg":"trace[1755973874] linearizableReadLoop","detail":"{readStateIndex:8663; appliedIndex:8662; }","duration":"128.325245ms","start":"2026-08-19T20:45:15.168072Z","end":"2026-08-19T20:45:15.296398Z","steps":["trace[1755973874] 'read index received' (duration: 128.09694ms)","trace[1755973874] 'applied index is now lower than readState.Index' (duration: 227.415µs)"],"step_count":2} {"level":"info","ts":"2026-08-19T20:45:15.296485Z","caller":"traceutil/trace.go:171","msg":"trace[1368726410] transaction","detail":"{read_only:false; response_revision:8372; number_of_response:1; }","duration":"276.180739ms","start":"2026-08-19T20:45:15.020259Z","end":"2026-08-19T20:45:15.296439Z","steps":["trace[1368726410] 'process raft request' (duration: 275.995525ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:45:15.296957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.862859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-08-19T20:45:15.297064Z","caller":"traceutil/trace.go:171","msg":"trace[229158702] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8372; }","duration":"129.046683ms","start":"2026-08-19T20:45:15.167992Z","end":"2026-08-19T20:45:15.297039Z","steps":["trace[229158702] 'agreement among raft nodes before linearized reading' (duration: 128.535891ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:45:18.79111Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6201} {"level":"info","ts":"2026-08-19T20:45:18.89134Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6201,"took":"98.071224ms","hash":3913947815,"current-db-size-bytes":59461632,"current-db-size":"60 MB","current-db-size-in-use-bytes":23420928,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-08-19T20:45:18.891388Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3913947815,"revision":6201,"compact-revision":3024} {"level":"warn","ts":"2026-08-19T20:45:27.225932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.966754ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-19T20:45:27.226135Z","caller":"traceutil/trace.go:171","msg":"trace[505344635] transaction","detail":"{read_only:false; response_revision:8476; number_of_response:1; }","duration":"252.447028ms","start":"2026-08-19T20:45:26.973666Z","end":"2026-08-19T20:45:27.226113Z","steps":["trace[505344635] 'process raft request' (duration: 91.206426ms)","trace[505344635] 'compare' (duration: 160.624436ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T20:45:27.226276Z","caller":"traceutil/trace.go:171","msg":"trace[1893474744] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8477; }","duration":"251.661328ms","start":"2026-08-19T20:45:26.974597Z","end":"2026-08-19T20:45:27.226258Z","steps":["trace[1893474744] 'process raft request' (duration: 251.448532ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:45:28.689471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.086956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:4724"} {"level":"info","ts":"2026-08-19T20:45:28.68957Z","caller":"traceutil/trace.go:171","msg":"trace[299702167] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:8491; }","duration":"176.233039ms","start":"2026-08-19T20:45:28.513314Z","end":"2026-08-19T20:45:28.689547Z","steps":["trace[299702167] 'range keys from in-memory index tree' (duration: 175.923552ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:45:28.942625Z","caller":"traceutil/trace.go:171","msg":"trace[123413342] transaction","detail":"{read_only:false; response_revision:8492; number_of_response:1; }","duration":"154.511037ms","start":"2026-08-19T20:45:28.78809Z","end":"2026-08-19T20:45:28.942601Z","steps":["trace[123413342] 'process raft request' (duration: 154.351463ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:45:46.423515Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000233a.wal"} {"level":"info","ts":"2026-08-19T20:46:38.693254Z","caller":"traceutil/trace.go:171","msg":"trace[2117771580] linearizableReadLoop","detail":"{readStateIndex:9521; appliedIndex:9520; }","duration":"219.756435ms","start":"2026-08-19T20:46:38.47347Z","end":"2026-08-19T20:46:38.693227Z","steps":["trace[2117771580] 'read index received' (duration: 219.521389ms)","trace[2117771580] 'applied index is now lower than readState.Index' (duration: 234.076µs)"],"step_count":2} {"level":"info","ts":"2026-08-19T20:46:38.693381Z","caller":"traceutil/trace.go:171","msg":"trace[259354098] transaction","detail":"{read_only:false; response_revision:9203; number_of_response:1; }","duration":"220.021183ms","start":"2026-08-19T20:46:38.473314Z","end":"2026-08-19T20:46:38.693335Z","steps":["trace[259354098] 'process raft request' (duration: 219.709885ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:46:38.693469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.971301ms","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:7"} {"level":"info","ts":"2026-08-19T20:46:38.693519Z","caller":"traceutil/trace.go:171","msg":"trace[2025894564] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/; range_end:/registry/nfd.k8s-sigs.io/nodefeatures0; response_count:0; response_revision:9203; }","duration":"220.072703ms","start":"2026-08-19T20:46:38.47343Z","end":"2026-08-19T20:46:38.693503Z","steps":["trace[2025894564] 'agreement among raft nodes before linearized reading' (duration: 219.96275ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:46:38.705127Z","caller":"traceutil/trace.go:171","msg":"trace[1098254205] transaction","detail":"{read_only:false; response_revision:9204; number_of_response:1; }","duration":"128.496539ms","start":"2026-08-19T20:46:38.576616Z","end":"2026-08-19T20:46:38.705112Z","steps":["trace[1098254205] 'process raft request' (duration: 128.371816ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:46:38.705354Z","caller":"traceutil/trace.go:171","msg":"trace[873380092] transaction","detail":"{read_only:false; response_revision:9205; number_of_response:1; }","duration":"123.824964ms","start":"2026-08-19T20:46:38.581494Z","end":"2026-08-19T20:46:38.705318Z","steps":["trace[873380092] 'process raft request' (duration: 123.578988ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:46:38.70554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.165606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-62v88\" ","response":"range_response_count:1 size:6516"} {"level":"info","ts":"2026-08-19T20:46:38.705635Z","caller":"traceutil/trace.go:171","msg":"trace[465267339] range","detail":"{range_begin:/registry/pods/openstack/keepalived-62v88; range_end:; response_count:1; response_revision:9205; }","duration":"211.319779ms","start":"2026-08-19T20:46:38.49429Z","end":"2026-08-19T20:46:38.70561Z","steps":["trace[465267339] 'agreement among raft nodes before linearized reading' (duration: 210.959731ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:47:44.932866Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"3a0c9d65f167b31b","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-19T20:47:44.937852Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-08-19T20:47:44.937957Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"warn","ts":"2026-08-19T20:50:11.026882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.322558ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-19T20:50:11.02701Z","caller":"traceutil/trace.go:171","msg":"trace[1730576525] transaction","detail":"{read_only:false; response_revision:10989; number_of_response:1; }","duration":"184.373814ms","start":"2026-08-19T20:50:10.842616Z","end":"2026-08-19T20:50:11.02699Z","steps":["trace[1730576525] 'process raft request' (duration: 75.850451ms)","trace[1730576525] 'compare' (duration: 108.131714ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T20:50:11.043786Z","caller":"traceutil/trace.go:171","msg":"trace[1791369978] transaction","detail":"{read_only:false; response_revision:10991; number_of_response:1; }","duration":"201.053099ms","start":"2026-08-19T20:50:10.842706Z","end":"2026-08-19T20:50:11.04376Z","steps":["trace[1791369978] 'process raft request' (duration: 200.776763ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:50:11.043807Z","caller":"traceutil/trace.go:171","msg":"trace[2072417028] transaction","detail":"{read_only:false; response_revision:10990; number_of_response:1; }","duration":"201.123471ms","start":"2026-08-19T20:50:10.84264Z","end":"2026-08-19T20:50:11.043763Z","steps":["trace[2072417028] 'process raft request' (duration: 200.713771ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:50:11.043928Z","caller":"traceutil/trace.go:171","msg":"trace[175162417] transaction","detail":"{read_only:false; response_revision:10992; number_of_response:1; }","duration":"201.188533ms","start":"2026-08-19T20:50:10.8427Z","end":"2026-08-19T20:50:11.043888Z","steps":["trace[175162417] 'process raft request' (duration: 200.825374ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:50:11.044121Z","caller":"traceutil/trace.go:171","msg":"trace[1121752824] transaction","detail":"{read_only:false; response_revision:10993; number_of_response:1; }","duration":"201.338786ms","start":"2026-08-19T20:50:10.84276Z","end":"2026-08-19T20:50:11.044099Z","steps":["trace[1121752824] 'process raft request' (duration: 200.800573ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:50:18.798756Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8391} {"level":"info","ts":"2026-08-19T20:50:18.870375Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8391,"took":"69.751163ms","hash":3797695850,"current-db-size-bytes":59461632,"current-db-size":"60 MB","current-db-size-in-use-bytes":26509312,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-08-19T20:50:18.870457Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3797695850,"revision":8391,"compact-revision":6201} {"level":"info","ts":"2026-08-19T20:55:18.805165Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":11057} {"level":"info","ts":"2026-08-19T20:55:18.885093Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":11057,"took":"77.67247ms","hash":924315408,"current-db-size-bytes":59461632,"current-db-size":"60 MB","current-db-size-in-use-bytes":27897856,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-08-19T20:55:18.885148Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":924315408,"revision":11057,"compact-revision":8391} {"level":"info","ts":"2026-08-19T20:56:36.835773Z","caller":"traceutil/trace.go:171","msg":"trace[192077956] linearizableReadLoop","detail":"{readStateIndex:14050; appliedIndex:14049; }","duration":"265.35633ms","start":"2026-08-19T20:56:36.570394Z","end":"2026-08-19T20:56:36.83575Z","steps":["trace[192077956] 'read index received' (duration: 265.305919ms)","trace[192077956] 'applied index is now lower than readState.Index' (duration: 49.291µs)"],"step_count":2} {"level":"info","ts":"2026-08-19T20:56:36.835867Z","caller":"traceutil/trace.go:171","msg":"trace[775409228] transaction","detail":"{read_only:false; response_revision:13587; number_of_response:1; }","duration":"343.86519ms","start":"2026-08-19T20:56:36.491974Z","end":"2026-08-19T20:56:36.835839Z","steps":["trace[775409228] 'process raft request' (duration: 343.641535ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:56:36.835972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.572737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-08-19T20:56:36.836003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:56:36.491949Z","time spent":"343.985004ms","remote":"127.0.0.1:53336","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12705,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-19T20:56:36.836013Z","caller":"traceutil/trace.go:171","msg":"trace[215489708] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:13587; }","duration":"265.688319ms","start":"2026-08-19T20:56:36.570313Z","end":"2026-08-19T20:56:36.836002Z","steps":["trace[215489708] 'agreement among raft nodes before linearized reading' (duration: 265.557335ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:56:37.062415Z","caller":"traceutil/trace.go:171","msg":"trace[1939922771] transaction","detail":"{read_only:false; response_revision:13590; number_of_response:1; }","duration":"222.219536ms","start":"2026-08-19T20:56:36.84018Z","end":"2026-08-19T20:56:37.0624Z","steps":["trace[1939922771] 'process raft request' (duration: 222.190476ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:56:37.06246Z","caller":"traceutil/trace.go:171","msg":"trace[412444793] transaction","detail":"{read_only:false; response_revision:13588; number_of_response:1; }","duration":"323.114048ms","start":"2026-08-19T20:56:36.739304Z","end":"2026-08-19T20:56:37.062418Z","steps":["trace[412444793] 'process raft request' (duration: 280.333943ms)","trace[412444793] 'compare' (duration: 42.59154ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T20:56:37.062514Z","caller":"traceutil/trace.go:171","msg":"trace[1280078770] linearizableReadLoop","detail":"{readStateIndex:14052; appliedIndex:14050; }","duration":"226.644034ms","start":"2026-08-19T20:56:36.835845Z","end":"2026-08-19T20:56:37.062489Z","steps":["trace[1280078770] 'read index received' (duration: 183.840169ms)","trace[1280078770] 'applied index is now lower than readState.Index' (duration: 42.802135ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T20:56:37.062608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:56:36.73928Z","time spent":"323.249381ms","remote":"127.0.0.1:53414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-19T20:56:37.062644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.107754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-19T20:56:37.06268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.827607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-08-19T20:56:37.062725Z","caller":"traceutil/trace.go:171","msg":"trace[800628439] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:13590; }","duration":"365.230447ms","start":"2026-08-19T20:56:36.697472Z","end":"2026-08-19T20:56:37.062702Z","steps":["trace[800628439] 'agreement among raft nodes before linearized reading' (duration: 365.105094ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:56:37.062758Z","caller":"traceutil/trace.go:171","msg":"trace[1779758114] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:13590; }","duration":"233.93535ms","start":"2026-08-19T20:56:36.828799Z","end":"2026-08-19T20:56:37.062735Z","steps":["trace[1779758114] 'agreement among raft nodes before linearized reading' (duration: 233.703405ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:56:37.062772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.658949ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-08-19T20:56:37.062772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:56:36.697453Z","time spent":"365.307929ms","remote":"127.0.0.1:58228","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true "} {"level":"warn","ts":"2026-08-19T20:56:37.06278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.989782ms","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-19T20:56:37.062862Z","caller":"traceutil/trace.go:171","msg":"trace[453040854] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:13590; }","duration":"232.128997ms","start":"2026-08-19T20:56:36.830716Z","end":"2026-08-19T20:56:37.062845Z","steps":["trace[453040854] 'agreement among raft nodes before linearized reading' (duration: 231.835139ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:56:37.062895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.594419ms","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-19T20:56:37.062929Z","caller":"traceutil/trace.go:171","msg":"trace[284593068] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:13590; }","duration":"213.652761ms","start":"2026-08-19T20:56:36.849265Z","end":"2026-08-19T20:56:37.062918Z","steps":["trace[284593068] 'agreement among raft nodes before linearized reading' (duration: 213.571279ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:56:37.062815Z","caller":"traceutil/trace.go:171","msg":"trace[1856967151] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:13590; }","duration":"220.948065ms","start":"2026-08-19T20:56:36.841854Z","end":"2026-08-19T20:56:37.062802Z","steps":["trace[1856967151] 'agreement among raft nodes before linearized reading' (duration: 220.75739ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:56:37.062979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.444234ms","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-19T20:56:37.063016Z","caller":"traceutil/trace.go:171","msg":"trace[448017038] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:13590; }","duration":"220.523276ms","start":"2026-08-19T20:56:36.842481Z","end":"2026-08-19T20:56:37.063004Z","steps":["trace[448017038] 'agreement among raft nodes before linearized reading' (duration: 220.420173ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:56:37.063108Z","caller":"traceutil/trace.go:171","msg":"trace[908441630] transaction","detail":"{read_only:false; response_revision:13589; number_of_response:1; }","duration":"322.276098ms","start":"2026-08-19T20:56:36.740206Z","end":"2026-08-19T20:56:37.062482Z","steps":["trace[908441630] 'process raft request' (duration: 322.123115ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:56:37.063545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:56:36.740185Z","time spent":"323.285533ms","remote":"127.0.0.1:53414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-19T20:56:37.065477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.261666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/\" range_end:\"/registry/secrets/openstack0\" ","response":"range_response_count:86 size:930006"} {"level":"info","ts":"2026-08-19T20:56:37.06562Z","caller":"traceutil/trace.go:171","msg":"trace[187623724] range","detail":"{range_begin:/registry/secrets/openstack/; range_end:/registry/secrets/openstack0; response_count:86; response_revision:13590; }","duration":"331.743266ms","start":"2026-08-19T20:56:36.733845Z","end":"2026-08-19T20:56:37.065588Z","steps":["trace[187623724] 'agreement among raft nodes before linearized reading' (duration: 328.691162ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:56:37.065693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:56:36.733827Z","time spent":"331.84805ms","remote":"127.0.0.1:53242","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":86,"response size":930029,"request content":"key:\"/registry/secrets/openstack/\" range_end:\"/registry/secrets/openstack0\" "} {"level":"info","ts":"2026-08-19T20:58:27.594027Z","caller":"traceutil/trace.go:171","msg":"trace[1713333153] transaction","detail":"{read_only:false; response_revision:14836; number_of_response:1; }","duration":"172.406635ms","start":"2026-08-19T20:58:27.421599Z","end":"2026-08-19T20:58:27.594005Z","steps":["trace[1713333153] 'process raft request' (duration: 172.270751ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:58:27.810743Z","caller":"traceutil/trace.go:171","msg":"trace[1487833142] linearizableReadLoop","detail":"{readStateIndex:15337; appliedIndex:15335; }","duration":"274.843754ms","start":"2026-08-19T20:58:27.535857Z","end":"2026-08-19T20:58:27.810701Z","steps":["trace[1487833142] 'read index received' (duration: 58.014414ms)","trace[1487833142] 'applied index is now lower than readState.Index' (duration: 216.8277ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T20:58:27.810752Z","caller":"traceutil/trace.go:171","msg":"trace[42741935] transaction","detail":"{read_only:false; response_revision:14837; number_of_response:1; }","duration":"369.289162ms","start":"2026-08-19T20:58:27.441427Z","end":"2026-08-19T20:58:27.810717Z","steps":["trace[42741935] 'process raft request' (duration: 328.76628ms)","trace[42741935] 'compare' (duration: 40.228915ms)"],"step_count":2} {"level":"warn","ts":"2026-08-19T20:58:27.810919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T20:58:27.441412Z","time spent":"369.431305ms","remote":"127.0.0.1:53414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-19T20:58:27.810977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.10729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-08-19T20:58:27.811039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.354931ms","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-19T20:58:27.811054Z","caller":"traceutil/trace.go:171","msg":"trace[895084912] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:14837; }","duration":"275.226693ms","start":"2026-08-19T20:58:27.535809Z","end":"2026-08-19T20:58:27.811036Z","steps":["trace[895084912] 'agreement among raft nodes before linearized reading' (duration: 275.039569ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:58:27.811084Z","caller":"traceutil/trace.go:171","msg":"trace[1149764482] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:14837; }","duration":"219.434402ms","start":"2026-08-19T20:58:27.591637Z","end":"2026-08-19T20:58:27.811072Z","steps":["trace[1149764482] 'agreement among raft nodes before linearized reading' (duration: 219.246508ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:58:27.811147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.472523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-pzzcn\" ","response":"range_response_count:1 size:22117"} {"level":"warn","ts":"2026-08-19T20:58:27.811373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.842505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-08-19T20:58:27.811387Z","caller":"traceutil/trace.go:171","msg":"trace[507935483] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-pzzcn; range_end:; response_count:1; response_revision:14837; }","duration":"112.737609ms","start":"2026-08-19T20:58:27.698619Z","end":"2026-08-19T20:58:27.811356Z","steps":["trace[507935483] 'agreement among raft nodes before linearized reading' (duration: 112.316599ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:58:27.811482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.552332ms","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-19T20:58:27.811513Z","caller":"traceutil/trace.go:171","msg":"trace[498026069] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:14837; }","duration":"211.600782ms","start":"2026-08-19T20:58:27.599905Z","end":"2026-08-19T20:58:27.811505Z","steps":["trace[498026069] 'agreement among raft nodes before linearized reading' (duration: 211.524361ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:58:27.811293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.302161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-pzzcn\" ","response":"range_response_count:1 size:22117"} {"level":"warn","ts":"2026-08-19T20:58:27.811718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.3022ms","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-19T20:58:27.811746Z","caller":"traceutil/trace.go:171","msg":"trace[680051726] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:14837; }","duration":"212.351702ms","start":"2026-08-19T20:58:27.599386Z","end":"2026-08-19T20:58:27.811737Z","steps":["trace[680051726] 'agreement among raft nodes before linearized reading' (duration: 212.27403ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:58:27.811744Z","caller":"traceutil/trace.go:171","msg":"trace[140143108] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-pzzcn; range_end:; response_count:1; response_revision:14837; }","duration":"115.773433ms","start":"2026-08-19T20:58:27.695953Z","end":"2026-08-19T20:58:27.811726Z","steps":["trace[140143108] 'agreement among raft nodes before linearized reading' (duration: 115.23926ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:58:27.81182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.766712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-08-19T20:58:27.811872Z","caller":"traceutil/trace.go:171","msg":"trace[1670975927] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:14837; }","duration":"212.862864ms","start":"2026-08-19T20:58:27.598996Z","end":"2026-08-19T20:58:27.811859Z","steps":["trace[1670975927] 'agreement among raft nodes before linearized reading' (duration: 212.753781ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T20:58:27.811408Z","caller":"traceutil/trace.go:171","msg":"trace[1150489729] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:14837; }","duration":"146.909587ms","start":"2026-08-19T20:58:27.66449Z","end":"2026-08-19T20:58:27.811399Z","steps":["trace[1150489729] 'agreement among raft nodes before linearized reading' (duration: 146.824605ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T20:58:27.812046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.834323ms","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-19T20:58:27.812089Z","caller":"traceutil/trace.go:171","msg":"trace[905275960] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:14837; }","duration":"212.890965ms","start":"2026-08-19T20:58:27.599185Z","end":"2026-08-19T20:58:27.812076Z","steps":["trace[905275960] 'agreement among raft nodes before linearized reading' (duration: 212.802613ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T21:00:18.811441Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":13090} {"level":"info","ts":"2026-08-19T21:00:18.882518Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":13090,"took":"68.217211ms","hash":3450869760,"current-db-size-bytes":59461632,"current-db-size":"60 MB","current-db-size-in-use-bytes":31645696,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-19T21:00:18.882582Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3450869760,"revision":13090,"compact-revision":11057} {"level":"info","ts":"2026-08-19T21:04:01.737684Z","caller":"traceutil/trace.go:171","msg":"trace[1419713812] transaction","detail":"{read_only:false; response_revision:17447; number_of_response:1; }","duration":"339.738469ms","start":"2026-08-19T21:04:01.397916Z","end":"2026-08-19T21:04:01.737654Z","steps":["trace[1419713812] 'process raft request' (duration: 339.585645ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T21:04:01.737872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T21:04:01.397895Z","time spent":"339.886482ms","remote":"127.0.0.1:53414","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-19T21:04:01.765614Z","caller":"traceutil/trace.go:171","msg":"trace[1032689445] transaction","detail":"{read_only:false; response_revision:17448; number_of_response:1; }","duration":"367.481341ms","start":"2026-08-19T21:04:01.398114Z","end":"2026-08-19T21:04:01.765595Z","steps":["trace[1032689445] 'process raft request' (duration: 367.358658ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T21:04:01.765712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T21:04:01.398088Z","time spent":"367.576232ms","remote":"127.0.0.1:53414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-19T21:04:01.765933Z","caller":"traceutil/trace.go:171","msg":"trace[1651105079] transaction","detail":"{read_only:false; response_revision:17449; number_of_response:1; }","duration":"367.717716ms","start":"2026-08-19T21:04:01.398183Z","end":"2026-08-19T21:04:01.765901Z","steps":["trace[1651105079] 'process raft request' (duration: 367.374488ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T21:04:01.766103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T21:04:01.398173Z","time spent":"367.840739ms","remote":"127.0.0.1:53414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-19T21:04:01.766381Z","caller":"traceutil/trace.go:171","msg":"trace[2086651756] linearizableReadLoop","detail":"{readStateIndex:18031; appliedIndex:18027; }","duration":"355.679025ms","start":"2026-08-19T21:04:01.410689Z","end":"2026-08-19T21:04:01.766368Z","steps":["trace[2086651756] 'read index received' (duration: 326.815416ms)","trace[2086651756] 'applied index is now lower than readState.Index' (duration: 28.862329ms)"],"step_count":2} {"level":"info","ts":"2026-08-19T21:04:01.766472Z","caller":"traceutil/trace.go:171","msg":"trace[1259357975] transaction","detail":"{read_only:false; response_revision:17450; number_of_response:1; }","duration":"368.284789ms","start":"2026-08-19T21:04:01.398179Z","end":"2026-08-19T21:04:01.766463Z","steps":["trace[1259357975] 'process raft request' (duration: 367.601753ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T21:04:01.766534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T21:04:01.398157Z","time spent":"368.340811ms","remote":"127.0.0.1:53414","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-19T21:04:01.766578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.874359ms","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-19T21:04:01.766654Z","caller":"traceutil/trace.go:171","msg":"trace[1297235174] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:17451; }","duration":"355.970761ms","start":"2026-08-19T21:04:01.410662Z","end":"2026-08-19T21:04:01.766633Z","steps":["trace[1297235174] 'agreement among raft nodes before linearized reading' (duration: 355.853078ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T21:04:01.766709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T21:04:01.410654Z","time spent":"356.039553ms","remote":"127.0.0.1:58172","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true "} {"level":"warn","ts":"2026-08-19T21:04:01.766749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.729902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-19T21:04:01.76679Z","caller":"traceutil/trace.go:171","msg":"trace[1024579921] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:17451; }","duration":"314.773613ms","start":"2026-08-19T21:04:01.452007Z","end":"2026-08-19T21:04:01.766781Z","steps":["trace[1024579921] 'agreement among raft nodes before linearized reading' (duration: 314.702101ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T21:04:01.766821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T21:04:01.451968Z","time spent":"314.845015ms","remote":"127.0.0.1:53354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":29,"request content":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true "} {"level":"info","ts":"2026-08-19T21:04:01.766943Z","caller":"traceutil/trace.go:171","msg":"trace[255460215] transaction","detail":"{read_only:false; response_revision:17451; number_of_response:1; }","duration":"342.926055ms","start":"2026-08-19T21:04:01.423998Z","end":"2026-08-19T21:04:01.766924Z","steps":["trace[255460215] 'process raft request' (duration: 342.30605ms)"],"step_count":1} {"level":"warn","ts":"2026-08-19T21:04:01.767045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-19T21:04:01.423963Z","time spent":"343.028088ms","remote":"127.0.0.1:53414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-19T21:05:18.819459Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15657} {"level":"info","ts":"2026-08-19T21:05:18.916656Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15657,"took":"93.864622ms","hash":608174500,"current-db-size-bytes":59461632,"current-db-size":"60 MB","current-db-size-in-use-bytes":35196928,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-08-19T21:05:18.916709Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":608174500,"revision":15657,"compact-revision":13090} {"level":"info","ts":"2026-08-19T21:07:34.732987Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"3a0c9d65f167b31b","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-19T21:07:34.739477Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-08-19T21:07:34.739626Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-08-19T21:08:41.836092Z","caller":"traceutil/trace.go:171","msg":"trace[394457583] transaction","detail":"{read_only:false; response_revision:19734; number_of_response:1; }","duration":"120.502892ms","start":"2026-08-19T21:08:41.715561Z","end":"2026-08-19T21:08:41.836064Z","steps":["trace[394457583] 'process raft request' (duration: 120.233536ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T21:08:42.048Z","caller":"traceutil/trace.go:171","msg":"trace[1026750929] transaction","detail":"{read_only:false; response_revision:19735; number_of_response:1; }","duration":"189.854042ms","start":"2026-08-19T21:08:41.85812Z","end":"2026-08-19T21:08:42.047974Z","steps":["trace[1026750929] 'process raft request' (duration: 189.678298ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T21:08:42.049429Z","caller":"traceutil/trace.go:171","msg":"trace[149703313] transaction","detail":"{read_only:false; response_revision:19736; number_of_response:1; }","duration":"148.290795ms","start":"2026-08-19T21:08:41.901101Z","end":"2026-08-19T21:08:42.049392Z","steps":["trace[149703313] 'process raft request' (duration: 148.133461ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T21:08:58.010752Z","caller":"traceutil/trace.go:171","msg":"trace[1560859695] transaction","detail":"{read_only:false; response_revision:19815; number_of_response:1; }","duration":"115.333407ms","start":"2026-08-19T21:08:57.895394Z","end":"2026-08-19T21:08:58.010728Z","steps":["trace[1560859695] 'process raft request' (duration: 115.182384ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T21:08:58.929155Z","caller":"traceutil/trace.go:171","msg":"trace[1328408720] transaction","detail":"{read_only:false; response_revision:19817; number_of_response:1; }","duration":"115.999464ms","start":"2026-08-19T21:08:58.813114Z","end":"2026-08-19T21:08:58.929114Z","steps":["trace[1328408720] 'process raft request' (duration: 115.805908ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T21:08:59.129808Z","caller":"traceutil/trace.go:171","msg":"trace[127965234] transaction","detail":"{read_only:false; response_revision:19818; number_of_response:1; }","duration":"196.024683ms","start":"2026-08-19T21:08:58.933758Z","end":"2026-08-19T21:08:59.129783Z","steps":["trace[127965234] 'process raft request' (duration: 195.739477ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T21:08:59.129792Z","caller":"traceutil/trace.go:171","msg":"trace[1064101702] transaction","detail":"{read_only:false; response_revision:19819; number_of_response:1; }","duration":"195.989082ms","start":"2026-08-19T21:08:58.933774Z","end":"2026-08-19T21:08:59.129763Z","steps":["trace[1064101702] 'process raft request' (duration: 195.888359ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T21:10:18.828128Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":18317} {"level":"info","ts":"2026-08-19T21:10:18.944394Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":18317,"took":"112.901707ms","hash":2438080565,"current-db-size-bytes":59461632,"current-db-size":"60 MB","current-db-size-in-use-bytes":30597120,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-19T21:10:18.944469Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2438080565,"revision":18317,"compact-revision":15657} {"level":"warn","ts":"2026-08-19T21:12:22.147589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.830775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-08-19T21:12:22.1477Z","caller":"traceutil/trace.go:171","msg":"trace[1142434234] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:22101; }","duration":"180.957378ms","start":"2026-08-19T21:12:21.966724Z","end":"2026-08-19T21:12:22.147681Z","steps":["trace[1142434234] 'count revisions from in-memory index tree' (duration: 180.750943ms)"],"step_count":1} {"level":"info","ts":"2026-08-19T21:15:18.835252Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20583} {"level":"info","ts":"2026-08-19T21:15:18.939096Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20583,"took":"99.636196ms","hash":174655513,"current-db-size-bytes":59461632,"current-db-size":"60 MB","current-db-size-in-use-bytes":43806720,"current-db-size-in-use":"44 MB"} {"level":"info","ts":"2026-08-19T21:15:18.939168Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":174655513,"revision":20583,"compact-revision":18317} {"level":"info","ts":"2026-08-19T21:20:18.842806Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23727} {"level":"info","ts":"2026-08-19T21:20:18.978989Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23727,"took":"133.106327ms","hash":3864489495,"current-db-size-bytes":59461632,"current-db-size":"60 MB","current-db-size-in-use-bytes":30568448,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-19T21:20:18.979051Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3864489495,"revision":23727,"compact-revision":20583}