{"level":"warn","ts":"2026-03-23T05:22:38.855065Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-23T05:22:38.855263Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.121: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.121:2380","--initial-cluster=instance=https://199.19.213.121:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.121:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.121: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-03-23T05:22:38.855381Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-23T05:22:38.855396Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.121:2380"]} {"level":"info","ts":"2026-03-23T05:22:38.855424Z","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-03-23T05:22:38.856477Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.121:2379"]} {"level":"info","ts":"2026-03-23T05:22:38.856597Z","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.121:2380"],"listen-peer-urls":["https://199.19.213.121:2380"],"advertise-client-urls":["https://199.19.213.121:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.121:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.121: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-03-23T05:22:38.860879Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.969202ms"} {"level":"info","ts":"2026-03-23T05:22:38.866998Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"31541ae44470b70","cluster-id":"8ae2d89c48d1447d"} {"level":"info","ts":"2026-03-23T05:22:38.867094Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"31541ae44470b70 switched to configuration voters=()"} {"level":"info","ts":"2026-03-23T05:22:38.867118Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"31541ae44470b70 became follower at term 0"} {"level":"info","ts":"2026-03-23T05:22:38.867134Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 31541ae44470b70 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-23T05:22:38.867146Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"31541ae44470b70 became follower at term 1"} {"level":"info","ts":"2026-03-23T05:22:38.867203Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"31541ae44470b70 switched to configuration voters=(222155973350329200)"} {"level":"warn","ts":"2026-03-23T05:22:38.872113Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-23T05:22:38.874467Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-23T05:22:38.875682Z","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-03-23T05:22:38.878808Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"31541ae44470b70","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-23T05:22:38.878981Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"31541ae44470b70","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-23T05:22:38.879116Z","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-03-23T05:22:38.879312Z","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-03-23T05:22:38.879363Z","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-03-23T05:22:38.881811Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"31541ae44470b70 switched to configuration voters=(222155973350329200)"} {"level":"info","ts":"2026-03-23T05:22:38.882026Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"8ae2d89c48d1447d","local-member-id":"31541ae44470b70","added-peer-id":"31541ae44470b70","added-peer-peer-urls":["https://199.19.213.121:2380"]} {"level":"info","ts":"2026-03-23T05:22:38.883269Z","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-03-23T05:22:38.883311Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.121:2380"} {"level":"info","ts":"2026-03-23T05:22:38.88337Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.121:2380"} {"level":"info","ts":"2026-03-23T05:22:38.8836Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"31541ae44470b70","initial-advertise-peer-urls":["https://199.19.213.121:2380"],"listen-peer-urls":["https://199.19.213.121:2380"],"advertise-client-urls":["https://199.19.213.121:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.121:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-23T05:22:38.883657Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-23T05:22:39.867636Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"31541ae44470b70 is starting a new election at term 1"} {"level":"info","ts":"2026-03-23T05:22:39.867749Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"31541ae44470b70 became pre-candidate at term 1"} {"level":"info","ts":"2026-03-23T05:22:39.867775Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"31541ae44470b70 received MsgPreVoteResp from 31541ae44470b70 at term 1"} {"level":"info","ts":"2026-03-23T05:22:39.867791Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"31541ae44470b70 became candidate at term 2"} {"level":"info","ts":"2026-03-23T05:22:39.867798Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"31541ae44470b70 received MsgVoteResp from 31541ae44470b70 at term 2"} {"level":"info","ts":"2026-03-23T05:22:39.86781Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"31541ae44470b70 became leader at term 2"} {"level":"info","ts":"2026-03-23T05:22:39.86782Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 31541ae44470b70 elected leader 31541ae44470b70 at term 2"} {"level":"info","ts":"2026-03-23T05:22:39.869086Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-23T05:22:39.870068Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-23T05:22:39.870058Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"31541ae44470b70","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.121:2379]}","request-path":"/0/members/31541ae44470b70/attributes","cluster-id":"8ae2d89c48d1447d","publish-timeout":"7s"} {"level":"info","ts":"2026-03-23T05:22:39.870099Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-23T05:22:39.870498Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"8ae2d89c48d1447d","local-member-id":"31541ae44470b70","cluster-version":"3.5"} {"level":"info","ts":"2026-03-23T05:22:39.870613Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-23T05:22:39.870666Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-23T05:22:39.870735Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-23T05:22:39.870768Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-23T05:22:39.87369Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-23T05:22:39.873801Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.121:2379"} {"level":"info","ts":"2026-03-23T05:23:16.357216Z","caller":"traceutil/trace.go:171","msg":"trace[892738463] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"154.342769ms","start":"2026-03-23T05:23:16.202843Z","end":"2026-03-23T05:23:16.357185Z","steps":["trace[892738463] 'process raft request' (duration: 154.168205ms)"],"step_count":1} {"level":"warn","ts":"2026-03-23T05:23:23.321361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.7816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.121\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-23T05:23:23.32147Z","caller":"traceutil/trace.go:171","msg":"trace[1954415389] range","detail":"{range_begin:/registry/masterleases/199.19.213.121; range_end:; response_count:1; response_revision:565; }","duration":"250.986114ms","start":"2026-03-23T05:23:23.070466Z","end":"2026-03-23T05:23:23.321452Z","steps":["trace[1954415389] 'range keys from in-memory index tree' (duration: 250.649687ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:23:23.627302Z","caller":"traceutil/trace.go:171","msg":"trace[945510824] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"235.870364ms","start":"2026-03-23T05:23:23.391386Z","end":"2026-03-23T05:23:23.627256Z","steps":["trace[945510824] 'process raft request' (duration: 226.441935ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:23:43.52188Z","caller":"traceutil/trace.go:171","msg":"trace[651850254] transaction","detail":"{read_only:false; response_revision:939; number_of_response:1; }","duration":"306.467495ms","start":"2026-03-23T05:23:43.215377Z","end":"2026-03-23T05:23:43.521845Z","steps":["trace[651850254] 'process raft request' (duration: 288.249092ms)","trace[651850254] 'compare' (duration: 18.067549ms)"],"step_count":2} {"level":"warn","ts":"2026-03-23T05:23:43.522663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-23T05:23:43.215346Z","time spent":"306.639279ms","remote":"127.0.0.1:43132","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":542,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-23T05:23:43.53391Z","caller":"traceutil/trace.go:171","msg":"trace[258866636] transaction","detail":"{read_only:false; response_revision:940; number_of_response:1; }","duration":"317.839609ms","start":"2026-03-23T05:23:43.216038Z","end":"2026-03-23T05:23:43.533878Z","steps":["trace[258866636] 'process raft request' (duration: 317.630665ms)"],"step_count":1} {"level":"warn","ts":"2026-03-23T05:23:43.534353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-23T05:23:43.216013Z","time spent":"317.981483ms","remote":"127.0.0.1:43330","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3875,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-23T05:24:33.607324Z","caller":"traceutil/trace.go:171","msg":"trace[1068508364] linearizableReadLoop","detail":"{readStateIndex:1674; appliedIndex:1673; }","duration":"115.852147ms","start":"2026-03-23T05:24:33.491447Z","end":"2026-03-23T05:24:33.607299Z","steps":["trace[1068508364] 'read index received' (duration: 115.618711ms)","trace[1068508364] 'applied index is now lower than readState.Index' (duration: 232.596µs)"],"step_count":2} {"level":"info","ts":"2026-03-23T05:24:33.607459Z","caller":"traceutil/trace.go:171","msg":"trace[626843265] transaction","detail":"{read_only:false; response_revision:1610; number_of_response:1; }","duration":"223.192184ms","start":"2026-03-23T05:24:33.384207Z","end":"2026-03-23T05:24:33.607399Z","steps":["trace[626843265] 'process raft request' (duration: 222.918898ms)"],"step_count":1} {"level":"warn","ts":"2026-03-23T05:24:33.607532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.081852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/datadir-percona-xtradb-pxc-0.189f61096a551be6\" ","response":"range_response_count:1 size:982"} {"level":"info","ts":"2026-03-23T05:24:33.607569Z","caller":"traceutil/trace.go:171","msg":"trace[503289827] range","detail":"{range_begin:/registry/events/openstack/datadir-percona-xtradb-pxc-0.189f61096a551be6; range_end:; response_count:1; response_revision:1610; }","duration":"116.205415ms","start":"2026-03-23T05:24:33.491351Z","end":"2026-03-23T05:24:33.607556Z","steps":["trace[503289827] 'agreement among raft nodes before linearized reading' (duration: 116.067862ms)"],"step_count":1} {"level":"warn","ts":"2026-03-23T05:24:34.05145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.956754ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-23T05:24:34.051823Z","caller":"traceutil/trace.go:171","msg":"trace[2023894288] transaction","detail":"{read_only:false; response_revision:1613; number_of_response:1; }","duration":"436.565264ms","start":"2026-03-23T05:24:33.615238Z","end":"2026-03-23T05:24:34.051803Z","steps":["trace[2023894288] 'process raft request' (duration: 436.349409ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:24:34.051798Z","caller":"traceutil/trace.go:171","msg":"trace[131478951] transaction","detail":"{read_only:false; response_revision:1612; number_of_response:1; }","duration":"437.21704ms","start":"2026-03-23T05:24:33.614502Z","end":"2026-03-23T05:24:34.051719Z","steps":["trace[131478951] 'process raft request' (duration: 180.688941ms)","trace[131478951] 'compare' (duration: 255.78965ms)"],"step_count":2} {"level":"info","ts":"2026-03-23T05:24:34.051895Z","caller":"traceutil/trace.go:171","msg":"trace[17206550] transaction","detail":"{read_only:false; response_revision:1615; number_of_response:1; }","duration":"436.199385ms","start":"2026-03-23T05:24:33.615687Z","end":"2026-03-23T05:24:34.051886Z","steps":["trace[17206550] 'process raft request' (duration: 436.079782ms)"],"step_count":1} {"level":"warn","ts":"2026-03-23T05:24:34.051915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-23T05:24:33.615226Z","time spent":"436.653157ms","remote":"127.0.0.1:43144","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1471,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-23T05:24:34.051826Z","caller":"traceutil/trace.go:171","msg":"trace[481609042] transaction","detail":"{read_only:false; response_revision:1614; number_of_response:1; }","duration":"436.133984ms","start":"2026-03-23T05:24:33.615676Z","end":"2026-03-23T05:24:34.05181Z","steps":["trace[481609042] 'process raft request' (duration: 436.037132ms)"],"step_count":1} {"level":"warn","ts":"2026-03-23T05:24:34.05196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-23T05:24:33.615663Z","time spent":"436.259797ms","remote":"127.0.0.1:43026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":977,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-23T05:24:34.052093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-23T05:24:33.614465Z","time spent":"437.462196ms","remote":"127.0.0.1:43026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":893,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-23T05:24:34.052095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-23T05:24:33.615666Z","time spent":"436.339389ms","remote":"127.0.0.1:43144","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1529,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-23T05:24:34.120443Z","caller":"traceutil/trace.go:171","msg":"trace[193400546] transaction","detail":"{read_only:false; response_revision:1616; number_of_response:1; }","duration":"449.894904ms","start":"2026-03-23T05:24:33.670518Z","end":"2026-03-23T05:24:34.120413Z","steps":["trace[193400546] 'process raft request' (duration: 449.73154ms)"],"step_count":1} {"level":"warn","ts":"2026-03-23T05:24:34.120639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-23T05:24:33.670489Z","time spent":"450.048858ms","remote":"127.0.0.1:43044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3729,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-23T05:24:34.222281Z","caller":"traceutil/trace.go:171","msg":"trace[633463573] linearizableReadLoop","detail":"{readStateIndex:1681; appliedIndex:1679; }","duration":"243.947969ms","start":"2026-03-23T05:24:33.978301Z","end":"2026-03-23T05:24:34.222249Z","steps":["trace[633463573] 'read index received' (duration: 142.004895ms)","trace[633463573] 'applied index is now lower than readState.Index' (duration: 101.941964ms)"],"step_count":2} {"level":"info","ts":"2026-03-23T05:24:34.222413Z","caller":"traceutil/trace.go:171","msg":"trace[1893011137] transaction","detail":"{read_only:false; response_revision:1617; number_of_response:1; }","duration":"284.657145ms","start":"2026-03-23T05:24:33.937731Z","end":"2026-03-23T05:24:34.222388Z","steps":["trace[1893011137] 'process raft request' (duration: 235.371475ms)","trace[1893011137] 'compare' (duration: 48.992092ms)"],"step_count":2} {"level":"warn","ts":"2026-03-23T05:24:34.222686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.122314ms","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-03-23T05:24:34.222727Z","caller":"traceutil/trace.go:171","msg":"trace[288131493] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1617; }","duration":"200.224536ms","start":"2026-03-23T05:24:34.022492Z","end":"2026-03-23T05:24:34.222717Z","steps":["trace[288131493] 'agreement among raft nodes before linearized reading' (duration: 199.97909ms)"],"step_count":1} {"level":"warn","ts":"2026-03-23T05:24:34.223118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.82386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-23T05:24:34.223194Z","caller":"traceutil/trace.go:171","msg":"trace[840947100] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:1617; }","duration":"244.914792ms","start":"2026-03-23T05:24:33.978233Z","end":"2026-03-23T05:24:34.223147Z","steps":["trace[840947100] 'agreement among raft nodes before linearized reading' (duration: 244.761338ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:24:34.543212Z","caller":"traceutil/trace.go:171","msg":"trace[352829001] linearizableReadLoop","detail":"{readStateIndex:1683; appliedIndex:1682; }","duration":"204.975183ms","start":"2026-03-23T05:24:34.338207Z","end":"2026-03-23T05:24:34.543182Z","steps":["trace[352829001] 'read index received' (duration: 204.755788ms)","trace[352829001] 'applied index is now lower than readState.Index' (duration: 217.795µs)"],"step_count":2} {"level":"info","ts":"2026-03-23T05:24:34.543439Z","caller":"traceutil/trace.go:171","msg":"trace[271240926] transaction","detail":"{read_only:false; response_revision:1619; number_of_response:1; }","duration":"315.287932ms","start":"2026-03-23T05:24:34.228096Z","end":"2026-03-23T05:24:34.543384Z","steps":["trace[271240926] 'process raft request' (duration: 314.902863ms)"],"step_count":1} {"level":"warn","ts":"2026-03-23T05:24:34.54354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.329651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-9925aded-e37a-4017-9d76-1292424f59fc\" ","response":"range_response_count:1 size:3744"} {"level":"info","ts":"2026-03-23T05:24:34.543593Z","caller":"traceutil/trace.go:171","msg":"trace[68978173] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-9925aded-e37a-4017-9d76-1292424f59fc; range_end:; response_count:1; response_revision:1619; }","duration":"205.485785ms","start":"2026-03-23T05:24:34.338091Z","end":"2026-03-23T05:24:34.543577Z","steps":["trace[68978173] 'agreement among raft nodes before linearized reading' (duration: 205.29445ms)"],"step_count":1} {"level":"warn","ts":"2026-03-23T05:24:34.543711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-23T05:24:34.22806Z","time spent":"315.510318ms","remote":"127.0.0.1:43132","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-23T05:24:34.54379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.456563ms","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-03-23T05:24:34.543867Z","caller":"traceutil/trace.go:171","msg":"trace[1203985191] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1619; }","duration":"133.590146ms","start":"2026-03-23T05:24:34.410263Z","end":"2026-03-23T05:24:34.543853Z","steps":["trace[1203985191] 'agreement among raft nodes before linearized reading' (duration: 133.249557ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:24:46.908378Z","caller":"traceutil/trace.go:171","msg":"trace[1392060514] transaction","detail":"{read_only:false; response_revision:1713; number_of_response:1; }","duration":"209.330391ms","start":"2026-03-23T05:24:46.699011Z","end":"2026-03-23T05:24:46.908341Z","steps":["trace[1392060514] 'process raft request' (duration: 208.672525ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:24:46.923747Z","caller":"traceutil/trace.go:171","msg":"trace[887165374] transaction","detail":"{read_only:false; response_revision:1714; number_of_response:1; }","duration":"164.369369ms","start":"2026-03-23T05:24:46.759348Z","end":"2026-03-23T05:24:46.923717Z","steps":["trace[887165374] 'process raft request' (duration: 164.087341ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:25:19.199709Z","caller":"traceutil/trace.go:171","msg":"trace[1016027739] transaction","detail":"{read_only:false; response_revision:1889; number_of_response:1; }","duration":"161.589459ms","start":"2026-03-23T05:25:19.038092Z","end":"2026-03-23T05:25:19.199682Z","steps":["trace[1016027739] 'process raft request' (duration: 161.452086ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:25:19.212571Z","caller":"traceutil/trace.go:171","msg":"trace[641450247] linearizableReadLoop","detail":"{readStateIndex:1964; appliedIndex:1962; }","duration":"146.87933ms","start":"2026-03-23T05:25:19.065659Z","end":"2026-03-23T05:25:19.212539Z","steps":["trace[641450247] 'read index received' (duration: 133.884892ms)","trace[641450247] 'applied index is now lower than readState.Index' (duration: 12.992638ms)"],"step_count":2} {"level":"warn","ts":"2026-03-23T05:25:19.212853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.169417ms","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-03-23T05:25:19.212853Z","caller":"traceutil/trace.go:171","msg":"trace[68821881] transaction","detail":"{read_only:false; response_revision:1890; number_of_response:1; }","duration":"170.274924ms","start":"2026-03-23T05:25:19.042541Z","end":"2026-03-23T05:25:19.212816Z","steps":["trace[68821881] 'process raft request' (duration: 169.819854ms)"],"step_count":1} {"level":"warn","ts":"2026-03-23T05:25:19.212995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.838648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-03-23T05:25:19.213044Z","caller":"traceutil/trace.go:171","msg":"trace[1985826770] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1891; }","duration":"135.94769ms","start":"2026-03-23T05:25:19.077084Z","end":"2026-03-23T05:25:19.213032Z","steps":["trace[1985826770] 'agreement among raft nodes before linearized reading' (duration: 135.845008ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:25:19.212898Z","caller":"traceutil/trace.go:171","msg":"trace[91489559] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1891; }","duration":"147.2802ms","start":"2026-03-23T05:25:19.065604Z","end":"2026-03-23T05:25:19.212884Z","steps":["trace[91489559] 'agreement among raft nodes before linearized reading' (duration: 147.080455ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:25:25.141665Z","caller":"traceutil/trace.go:171","msg":"trace[1172369922] transaction","detail":"{read_only:false; response_revision:1915; number_of_response:1; }","duration":"252.85569ms","start":"2026-03-23T05:25:24.888778Z","end":"2026-03-23T05:25:25.141633Z","steps":["trace[1172369922] 'process raft request' (duration: 252.666665ms)"],"step_count":1} {"level":"warn","ts":"2026-03-23T05:25:25.782281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.367776ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-23T05:25:25.782403Z","caller":"traceutil/trace.go:171","msg":"trace[391623910] linearizableReadLoop","detail":"{readStateIndex:1996; appliedIndex:1995; }","duration":"183.235212ms","start":"2026-03-23T05:25:25.599149Z","end":"2026-03-23T05:25:25.782384Z","steps":["trace[391623910] 'read index received' (duration: 31.257042ms)","trace[391623910] 'applied index is now lower than readState.Index' (duration: 151.97678ms)"],"step_count":2} {"level":"warn","ts":"2026-03-23T05:25:25.782701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.535068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-23T05:25:25.782836Z","caller":"traceutil/trace.go:171","msg":"trace[1010501997] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1921; }","duration":"183.722322ms","start":"2026-03-23T05:25:25.599076Z","end":"2026-03-23T05:25:25.782798Z","steps":["trace[1010501997] 'agreement among raft nodes before linearized reading' (duration: 183.457376ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:25:25.782734Z","caller":"traceutil/trace.go:171","msg":"trace[74007641] transaction","detail":"{read_only:false; response_revision:1921; number_of_response:1; }","duration":"329.260321ms","start":"2026-03-23T05:25:25.453384Z","end":"2026-03-23T05:25:25.782645Z","steps":["trace[74007641] 'process raft request' (duration: 177.134077ms)","trace[74007641] 'compare' (duration: 151.210932ms)"],"step_count":2} {"level":"warn","ts":"2026-03-23T05:25:25.783539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.662135ms","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-03-23T05:25:25.783617Z","caller":"traceutil/trace.go:171","msg":"trace[1253578574] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1921; }","duration":"157.799058ms","start":"2026-03-23T05:25:25.625798Z","end":"2026-03-23T05:25:25.783597Z","steps":["trace[1253578574] 'agreement among raft nodes before linearized reading' (duration: 156.752164ms)"],"step_count":1} {"level":"warn","ts":"2026-03-23T05:25:25.783632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-23T05:25:25.453353Z","time spent":"329.559577ms","remote":"127.0.0.1:43132","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-23T05:26:43.925937Z","caller":"traceutil/trace.go:171","msg":"trace[1292413630] transaction","detail":"{read_only:false; response_revision:2499; number_of_response:1; }","duration":"158.072875ms","start":"2026-03-23T05:26:43.767837Z","end":"2026-03-23T05:26:43.92591Z","steps":["trace[1292413630] 'process raft request' (duration: 157.8997ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:26:43.930841Z","caller":"traceutil/trace.go:171","msg":"trace[505164941] transaction","detail":"{read_only:false; response_revision:2500; number_of_response:1; }","duration":"161.365026ms","start":"2026-03-23T05:26:43.769441Z","end":"2026-03-23T05:26:43.930806Z","steps":["trace[505164941] 'process raft request' (duration: 161.185972ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:26:49.764578Z","caller":"traceutil/trace.go:171","msg":"trace[2125979478] transaction","detail":"{read_only:false; response_revision:2523; number_of_response:1; }","duration":"149.435221ms","start":"2026-03-23T05:26:49.615104Z","end":"2026-03-23T05:26:49.76454Z","steps":["trace[2125979478] 'process raft request' (duration: 92.266371ms)","trace[2125979478] 'compare' (duration: 56.960775ms)"],"step_count":2} {"level":"info","ts":"2026-03-23T05:26:49.764863Z","caller":"traceutil/trace.go:171","msg":"trace[1471618722] transaction","detail":"{read_only:false; response_revision:2524; number_of_response:1; }","duration":"141.216977ms","start":"2026-03-23T05:26:49.623595Z","end":"2026-03-23T05:26:49.764812Z","steps":["trace[1471618722] 'process raft request' (duration: 140.897189ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:26:49.764976Z","caller":"traceutil/trace.go:171","msg":"trace[239753257] linearizableReadLoop","detail":"{readStateIndex:2624; appliedIndex:2622; }","duration":"139.219496ms","start":"2026-03-23T05:26:49.62574Z","end":"2026-03-23T05:26:49.76496Z","steps":["trace[239753257] 'read index received' (duration: 81.658637ms)","trace[239753257] 'applied index is now lower than readState.Index' (duration: 57.558769ms)"],"step_count":2} {"level":"info","ts":"2026-03-23T05:26:49.764982Z","caller":"traceutil/trace.go:171","msg":"trace[1420891957] transaction","detail":"{read_only:false; response_revision:2525; number_of_response:1; }","duration":"134.209803ms","start":"2026-03-23T05:26:49.630726Z","end":"2026-03-23T05:26:49.764936Z","steps":["trace[1420891957] 'process raft request' (duration: 134.062809ms)"],"step_count":1} {"level":"warn","ts":"2026-03-23T05:26:49.765121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.70171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-23T05:26:49.765173Z","caller":"traceutil/trace.go:171","msg":"trace[1163607555] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2525; }","duration":"166.811062ms","start":"2026-03-23T05:26:49.598348Z","end":"2026-03-23T05:26:49.765159Z","steps":["trace[1163607555] 'agreement among raft nodes before linearized reading' (duration: 166.664819ms)"],"step_count":1} {"level":"warn","ts":"2026-03-23T05:26:50.134293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.241985ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-23T05:26:50.134441Z","caller":"traceutil/trace.go:171","msg":"trace[959198566] linearizableReadLoop","detail":"{readStateIndex:2626; appliedIndex:2625; }","duration":"212.882766ms","start":"2026-03-23T05:26:49.921539Z","end":"2026-03-23T05:26:50.134422Z","steps":["trace[959198566] 'read index received' (duration: 57.287172ms)","trace[959198566] 'applied index is now lower than readState.Index' (duration: 155.594144ms)"],"step_count":2} {"level":"info","ts":"2026-03-23T05:26:50.134616Z","caller":"traceutil/trace.go:171","msg":"trace[1981249696] transaction","detail":"{read_only:false; response_revision:2526; number_of_response:1; }","duration":"225.978802ms","start":"2026-03-23T05:26:49.908541Z","end":"2026-03-23T05:26:50.134519Z","steps":["trace[1981249696] 'process raft request' (duration: 70.403428ms)","trace[1981249696] 'compare' (duration: 154.909337ms)"],"step_count":2} {"level":"warn","ts":"2026-03-23T05:26:50.134835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.216194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-23T05:26:50.134959Z","caller":"traceutil/trace.go:171","msg":"trace[211075236] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:2526; }","duration":"213.43593ms","start":"2026-03-23T05:26:49.921498Z","end":"2026-03-23T05:26:50.134934Z","steps":["trace[211075236] 'agreement among raft nodes before linearized reading' (duration: 213.163103ms)"],"step_count":1} {"level":"warn","ts":"2026-03-23T05:26:50.135199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.075377ms","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-03-23T05:26:50.135281Z","caller":"traceutil/trace.go:171","msg":"trace[2032382565] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2526; }","duration":"177.20847ms","start":"2026-03-23T05:26:49.958055Z","end":"2026-03-23T05:26:50.135264Z","steps":["trace[2032382565] 'agreement among raft nodes before linearized reading' (duration: 176.835971ms)"],"step_count":1} {"level":"warn","ts":"2026-03-23T05:26:50.135174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.975964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-03-23T05:26:50.135379Z","caller":"traceutil/trace.go:171","msg":"trace[775690483] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2526; }","duration":"177.19884ms","start":"2026-03-23T05:26:49.958162Z","end":"2026-03-23T05:26:50.135361Z","steps":["trace[775690483] 'agreement among raft nodes before linearized reading' (duration: 176.761239ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:29:31.167437Z","caller":"traceutil/trace.go:171","msg":"trace[1791301627] transaction","detail":"{read_only:false; response_revision:3494; number_of_response:1; }","duration":"116.013358ms","start":"2026-03-23T05:29:31.051385Z","end":"2026-03-23T05:29:31.167399Z","steps":["trace[1791301627] 'process raft request' (duration: 113.659322ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:30:52.823291Z","caller":"traceutil/trace.go:171","msg":"trace[934838346] transaction","detail":"{read_only:false; response_revision:4431; number_of_response:1; }","duration":"100.731739ms","start":"2026-03-23T05:30:52.722307Z","end":"2026-03-23T05:30:52.823038Z","steps":["trace[934838346] 'process raft request' (duration: 49.245607ms)","trace[934838346] 'compare' (duration: 51.168234ms)"],"step_count":2} {"level":"info","ts":"2026-03-23T05:30:56.956457Z","caller":"traceutil/trace.go:171","msg":"trace[20585295] linearizableReadLoop","detail":"{readStateIndex:4797; appliedIndex:4795; }","duration":"100.675588ms","start":"2026-03-23T05:30:56.855754Z","end":"2026-03-23T05:30:56.956429Z","steps":["trace[20585295] 'read index received' (duration: 4.61002ms)","trace[20585295] 'applied index is now lower than readState.Index' (duration: 96.064408ms)"],"step_count":2} {"level":"warn","ts":"2026-03-23T05:30:56.956687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.920494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0\" ","response":"range_response_count:1 size:1633"} {"level":"info","ts":"2026-03-23T05:30:56.956649Z","caller":"traceutil/trace.go:171","msg":"trace[1268739934] transaction","detail":"{read_only:false; response_revision:4614; number_of_response:1; }","duration":"117.837185ms","start":"2026-03-23T05:30:56.838763Z","end":"2026-03-23T05:30:56.9566Z","steps":["trace[1268739934] 'process raft request' (duration: 117.543748ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:30:56.956726Z","caller":"traceutil/trace.go:171","msg":"trace[1432254542] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0; range_end:; response_count:1; response_revision:4614; }","duration":"101.010955ms","start":"2026-03-23T05:30:56.855701Z","end":"2026-03-23T05:30:56.956712Z","steps":["trace[1432254542] 'agreement among raft nodes before linearized reading' (duration: 100.834131ms)"],"step_count":1} {"level":"warn","ts":"2026-03-23T05:30:57.140884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.50169ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-23T05:30:57.141046Z","caller":"traceutil/trace.go:171","msg":"trace[1845010634] linearizableReadLoop","detail":"{readStateIndex:4798; appliedIndex:4797; }","duration":"179.960967ms","start":"2026-03-23T05:30:56.96106Z","end":"2026-03-23T05:30:57.141021Z","steps":["trace[1845010634] 'read index received' (duration: 72.168511ms)","trace[1845010634] 'applied index is now lower than readState.Index' (duration: 107.790386ms)"],"step_count":2} {"level":"info","ts":"2026-03-23T05:30:57.141176Z","caller":"traceutil/trace.go:171","msg":"trace[468551270] transaction","detail":"{read_only:false; response_revision:4615; number_of_response:1; }","duration":"253.02134ms","start":"2026-03-23T05:30:56.888114Z","end":"2026-03-23T05:30:57.141136Z","steps":["trace[468551270] 'process raft request' (duration: 145.165463ms)","trace[468551270] 'compare' (duration: 107.365736ms)"],"step_count":2} {"level":"warn","ts":"2026-03-23T05:30:57.141281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.219254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-aa1774d2-d293-4cc4-a4c8-6a86f617a4d8\" ","response":"range_response_count:1 size:1293"} {"level":"info","ts":"2026-03-23T05:30:57.141332Z","caller":"traceutil/trace.go:171","msg":"trace[1428123627] range","detail":"{range_begin:/registry/persistentvolumes/pvc-aa1774d2-d293-4cc4-a4c8-6a86f617a4d8; range_end:; response_count:1; response_revision:4615; }","duration":"180.320856ms","start":"2026-03-23T05:30:56.960998Z","end":"2026-03-23T05:30:57.141318Z","steps":["trace[1428123627] 'agreement among raft nodes before linearized reading' (duration: 180.094991ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:30:57.167229Z","caller":"traceutil/trace.go:171","msg":"trace[911344117] transaction","detail":"{read_only:false; response_revision:4616; number_of_response:1; }","duration":"107.823857ms","start":"2026-03-23T05:30:57.059374Z","end":"2026-03-23T05:30:57.167198Z","steps":["trace[911344117] 'process raft request' (duration: 107.349856ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:30:58.763579Z","caller":"traceutil/trace.go:171","msg":"trace[677112735] transaction","detail":"{read_only:false; response_revision:4652; number_of_response:1; }","duration":"131.440047ms","start":"2026-03-23T05:30:58.632092Z","end":"2026-03-23T05:30:58.763532Z","steps":["trace[677112735] 'process raft request' (duration: 130.614988ms)"],"step_count":1} {"level":"warn","ts":"2026-03-23T05:30:58.763504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.834408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-3bd79275-8a8c-4b3e-9e1a-294ff5ba7d2a\" ","response":"range_response_count:1 size:3894"} {"level":"info","ts":"2026-03-23T05:30:58.763819Z","caller":"traceutil/trace.go:171","msg":"trace[1382953463] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-3bd79275-8a8c-4b3e-9e1a-294ff5ba7d2a; range_end:; response_count:1; response_revision:4652; }","duration":"103.226048ms","start":"2026-03-23T05:30:58.660573Z","end":"2026-03-23T05:30:58.7638Z","steps":["trace[1382953463] 'agreement among raft nodes before linearized reading' (duration: 102.393949ms)"],"step_count":1} {"level":"warn","ts":"2026-03-23T05:31:09.194243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.224052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/memcached-metrics\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-23T05:31:09.194367Z","caller":"traceutil/trace.go:171","msg":"trace[1992690228] range","detail":"{range_begin:/registry/services/specs/openstack/memcached-metrics; range_end:; response_count:0; response_revision:4840; }","duration":"139.401156ms","start":"2026-03-23T05:31:09.054938Z","end":"2026-03-23T05:31:09.194339Z","steps":["trace[1992690228] 'range keys from in-memory index tree' (duration: 139.13596ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:31:09.343122Z","caller":"traceutil/trace.go:171","msg":"trace[1524328743] linearizableReadLoop","detail":"{readStateIndex:5033; appliedIndex:5032; }","duration":"100.054233ms","start":"2026-03-23T05:31:09.243033Z","end":"2026-03-23T05:31:09.343087Z","steps":["trace[1524328743] 'read index received' (duration: 99.544481ms)","trace[1524328743] 'applied index is now lower than readState.Index' (duration: 508.482µs)"],"step_count":2} {"level":"info","ts":"2026-03-23T05:31:09.34321Z","caller":"traceutil/trace.go:171","msg":"trace[1683142546] transaction","detail":"{read_only:false; response_revision:4841; number_of_response:1; }","duration":"137.925361ms","start":"2026-03-23T05:31:09.20523Z","end":"2026-03-23T05:31:09.343156Z","steps":["trace[1683142546] 'process raft request' (duration: 137.573912ms)"],"step_count":1} {"level":"warn","ts":"2026-03-23T05:31:09.343413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.35547ms","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-03-23T05:31:09.343464Z","caller":"traceutil/trace.go:171","msg":"trace[62765907] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4841; }","duration":"100.470752ms","start":"2026-03-23T05:31:09.242975Z","end":"2026-03-23T05:31:09.343446Z","steps":["trace[62765907] 'agreement among raft nodes before linearized reading' (duration: 100.251397ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:31:16.803419Z","caller":"traceutil/trace.go:171","msg":"trace[1967962742] transaction","detail":"{read_only:false; response_revision:4941; number_of_response:1; }","duration":"105.495261ms","start":"2026-03-23T05:31:16.697896Z","end":"2026-03-23T05:31:16.803391Z","steps":["trace[1967962742] 'process raft request' (duration: 85.148319ms)","trace[1967962742] 'compare' (duration: 20.22839ms)"],"step_count":2} {"level":"info","ts":"2026-03-23T05:31:16.806973Z","caller":"traceutil/trace.go:171","msg":"trace[1203765206] transaction","detail":"{read_only:false; response_revision:4942; number_of_response:1; }","duration":"107.463228ms","start":"2026-03-23T05:31:16.699499Z","end":"2026-03-23T05:31:16.806962Z","steps":["trace[1203765206] 'process raft request' (duration: 107.339795ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:31:16.80743Z","caller":"traceutil/trace.go:171","msg":"trace[1962820821] transaction","detail":"{read_only:false; response_revision:4944; number_of_response:1; }","duration":"104.425776ms","start":"2026-03-23T05:31:16.702956Z","end":"2026-03-23T05:31:16.807381Z","steps":["trace[1962820821] 'process raft request' (duration: 104.132299ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:31:16.807547Z","caller":"traceutil/trace.go:171","msg":"trace[1506732988] transaction","detail":"{read_only:false; response_revision:4946; number_of_response:1; }","duration":"104.291083ms","start":"2026-03-23T05:31:16.703235Z","end":"2026-03-23T05:31:16.807526Z","steps":["trace[1506732988] 'process raft request' (duration: 104.15438ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:31:16.807538Z","caller":"traceutil/trace.go:171","msg":"trace[1719023218] transaction","detail":"{read_only:false; response_revision:4945; number_of_response:1; }","duration":"104.439606ms","start":"2026-03-23T05:31:16.703055Z","end":"2026-03-23T05:31:16.807494Z","steps":["trace[1719023218] 'process raft request' (duration: 104.257062ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:31:16.807733Z","caller":"traceutil/trace.go:171","msg":"trace[1585746774] transaction","detail":"{read_only:false; response_revision:4947; number_of_response:1; }","duration":"102.307086ms","start":"2026-03-23T05:31:16.70541Z","end":"2026-03-23T05:31:16.807717Z","steps":["trace[1585746774] 'process raft request' (duration: 102.04931ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:31:16.807325Z","caller":"traceutil/trace.go:171","msg":"trace[637252248] transaction","detail":"{read_only:false; response_revision:4943; number_of_response:1; }","duration":"106.212698ms","start":"2026-03-23T05:31:16.701073Z","end":"2026-03-23T05:31:16.807285Z","steps":["trace[637252248] 'process raft request' (duration: 105.84407ms)"],"step_count":1} {"level":"warn","ts":"2026-03-23T05:31:18.787034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.053188ms","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-03-23T05:31:18.787139Z","caller":"traceutil/trace.go:171","msg":"trace[1554073997] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:5021; }","duration":"123.229722ms","start":"2026-03-23T05:31:18.66389Z","end":"2026-03-23T05:31:18.78712Z","steps":["trace[1554073997] 'range keys from in-memory index tree' (duration: 122.881495ms)"],"step_count":1} {"level":"warn","ts":"2026-03-23T05:31:18.787249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.212214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-7f0264e8-a891-48bc-9c2e-1b538593114c\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-03-23T05:31:18.787392Z","caller":"traceutil/trace.go:171","msg":"trace[2000756048] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-7f0264e8-a891-48bc-9c2e-1b538593114c; range_end:; response_count:1; response_revision:5021; }","duration":"113.43251ms","start":"2026-03-23T05:31:18.673937Z","end":"2026-03-23T05:31:18.787369Z","steps":["trace[2000756048] 'range keys from in-memory index tree' (duration: 112.893147ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:31:18.897361Z","caller":"traceutil/trace.go:171","msg":"trace[1314712885] linearizableReadLoop","detail":"{readStateIndex:5216; appliedIndex:5215; }","duration":"101.559189ms","start":"2026-03-23T05:31:18.795778Z","end":"2026-03-23T05:31:18.897338Z","steps":["trace[1314712885] 'read index received' (duration: 101.360764ms)","trace[1314712885] 'applied index is now lower than readState.Index' (duration: 197.305µs)"],"step_count":2} {"level":"info","ts":"2026-03-23T05:31:18.897475Z","caller":"traceutil/trace.go:171","msg":"trace[911443224] transaction","detail":"{read_only:false; response_revision:5022; number_of_response:1; }","duration":"102.5179ms","start":"2026-03-23T05:31:18.794939Z","end":"2026-03-23T05:31:18.897457Z","steps":["trace[911443224] 'process raft request' (duration: 102.217373ms)"],"step_count":1} {"level":"warn","ts":"2026-03-23T05:31:18.897822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.0028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18707"} {"level":"info","ts":"2026-03-23T05:31:18.897941Z","caller":"traceutil/trace.go:171","msg":"trace[435672114] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:5022; }","duration":"102.152423ms","start":"2026-03-23T05:31:18.795761Z","end":"2026-03-23T05:31:18.897913Z","steps":["trace[435672114] 'agreement among raft nodes before linearized reading' (duration: 101.777263ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:31:18.941285Z","caller":"traceutil/trace.go:171","msg":"trace[650545495] transaction","detail":"{read_only:false; response_revision:5023; number_of_response:1; }","duration":"100.189887ms","start":"2026-03-23T05:31:18.841045Z","end":"2026-03-23T05:31:18.941235Z","steps":["trace[650545495] 'process raft request' (duration: 99.91866ms)"],"step_count":1} {"level":"warn","ts":"2026-03-23T05:31:18.941412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.035147ms","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-03-23T05:31:18.941523Z","caller":"traceutil/trace.go:171","msg":"trace[706597825] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5023; }","duration":"120.214221ms","start":"2026-03-23T05:31:18.821289Z","end":"2026-03-23T05:31:18.941503Z","steps":["trace[706597825] 'agreement among raft nodes before linearized reading' (duration: 119.863273ms)"],"step_count":1} {"level":"warn","ts":"2026-03-23T05:31:18.941579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.322096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-grkf4\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-03-23T05:31:18.941716Z","caller":"traceutil/trace.go:171","msg":"trace[657543181] range","detail":"{range_begin:/registry/pods/openstack/keepalived-grkf4; range_end:; response_count:1; response_revision:5023; }","duration":"118.51745ms","start":"2026-03-23T05:31:18.82317Z","end":"2026-03-23T05:31:18.941687Z","steps":["trace[657543181] 'agreement among raft nodes before linearized reading' (duration: 117.975148ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:32:39.898702Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2771} {"level":"info","ts":"2026-03-23T05:32:39.95056Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2771,"took":"50.240327ms","hash":3669269891,"current-db-size-bytes":41287680,"current-db-size":"41 MB","current-db-size-in-use-bytes":30617600,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-03-23T05:32:39.950635Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3669269891,"revision":2771,"compact-revision":-1} {"level":"info","ts":"2026-03-23T05:37:36.587571Z","caller":"traceutil/trace.go:171","msg":"trace[1060728332] transaction","detail":"{read_only:false; response_revision:7732; number_of_response:1; }","duration":"130.80131ms","start":"2026-03-23T05:37:36.456741Z","end":"2026-03-23T05:37:36.587543Z","steps":["trace[1060728332] 'process raft request' (duration: 130.606945ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:37:36.757133Z","caller":"traceutil/trace.go:171","msg":"trace[1603877508] transaction","detail":"{read_only:false; response_revision:7734; number_of_response:1; }","duration":"216.709897ms","start":"2026-03-23T05:37:36.540399Z","end":"2026-03-23T05:37:36.757109Z","steps":["trace[1603877508] 'process raft request' (duration: 216.670686ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:37:36.757329Z","caller":"traceutil/trace.go:171","msg":"trace[1312755729] transaction","detail":"{read_only:false; response_revision:7733; number_of_response:1; }","duration":"298.65216ms","start":"2026-03-23T05:37:36.458622Z","end":"2026-03-23T05:37:36.757274Z","steps":["trace[1312755729] 'process raft request' (duration: 298.329222ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:37:39.92461Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5707} {"level":"warn","ts":"2026-03-23T05:37:40.107452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.722834ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-23T05:37:40.107586Z","caller":"traceutil/trace.go:171","msg":"trace[1735258237] transaction","detail":"{read_only:false; response_revision:7748; number_of_response:1; }","duration":"139.083577ms","start":"2026-03-23T05:37:39.968471Z","end":"2026-03-23T05:37:40.107555Z","steps":["trace[1735258237] 'process raft request' (duration: 12.148088ms)","trace[1735258237] 'compare' (duration: 16.765458ms)","trace[1735258237] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/leases/kube-system/kube-controller-manager; req_size:487; } (duration: 109.734841ms)"],"step_count":3} {"level":"info","ts":"2026-03-23T05:37:40.215954Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5707,"took":"289.550444ms","hash":3156715956,"current-db-size-bytes":42885120,"current-db-size":"43 MB","current-db-size-in-use-bytes":23478272,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-03-23T05:37:40.21604Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3156715956,"revision":5707,"compact-revision":2771} {"level":"info","ts":"2026-03-23T05:37:47.726964Z","caller":"traceutil/trace.go:171","msg":"trace[30601998] transaction","detail":"{read_only:false; response_revision:7784; number_of_response:1; }","duration":"123.639441ms","start":"2026-03-23T05:37:47.603291Z","end":"2026-03-23T05:37:47.726931Z","steps":["trace[30601998] 'process raft request' (duration: 123.429636ms)"],"step_count":1} {"level":"warn","ts":"2026-03-23T05:37:48.271133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.810987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/sshkeys/\" range_end:\"/registry/secretgen.k14s.io/sshkeys0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-23T05:37:48.271259Z","caller":"traceutil/trace.go:171","msg":"trace[1963051192] range","detail":"{range_begin:/registry/secretgen.k14s.io/sshkeys/; range_end:/registry/secretgen.k14s.io/sshkeys0; response_count:0; response_revision:7785; }","duration":"230.030063ms","start":"2026-03-23T05:37:48.041204Z","end":"2026-03-23T05:37:48.271234Z","steps":["trace[1963051192] 'count revisions from in-memory index tree' (duration: 229.687005ms)"],"step_count":1} {"level":"warn","ts":"2026-03-23T05:37:48.271366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.567867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-03-23T05:37:48.271365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.110708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-03-23T05:37:48.271463Z","caller":"traceutil/trace.go:171","msg":"trace[569381457] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7785; }","duration":"271.713101ms","start":"2026-03-23T05:37:47.999728Z","end":"2026-03-23T05:37:48.271441Z","steps":["trace[569381457] 'range keys from in-memory index tree' (duration: 271.407244ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:37:48.271473Z","caller":"traceutil/trace.go:171","msg":"trace[660231727] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:7785; }","duration":"112.329062ms","start":"2026-03-23T05:37:48.159125Z","end":"2026-03-23T05:37:48.271454Z","steps":["trace[660231727] 'range keys from in-memory index tree' (duration: 111.804431ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:37:49.014914Z","caller":"traceutil/trace.go:171","msg":"trace[884060721] transaction","detail":"{read_only:false; response_revision:7789; number_of_response:1; }","duration":"209.398604ms","start":"2026-03-23T05:37:48.805471Z","end":"2026-03-23T05:37:49.01487Z","steps":["trace[884060721] 'process raft request' (duration: 209.196879ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:37:49.050074Z","caller":"traceutil/trace.go:171","msg":"trace[812780326] transaction","detail":"{read_only:false; response_revision:7790; number_of_response:1; }","duration":"240.417769ms","start":"2026-03-23T05:37:48.809619Z","end":"2026-03-23T05:37:49.050037Z","steps":["trace[812780326] 'process raft request' (duration: 240.267326ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:38:18.154561Z","caller":"traceutil/trace.go:171","msg":"trace[364821709] transaction","detail":"{read_only:false; response_revision:7986; number_of_response:1; }","duration":"229.06233ms","start":"2026-03-23T05:38:17.925448Z","end":"2026-03-23T05:38:18.154511Z","steps":["trace[364821709] 'process raft request' (duration: 228.411664ms)"],"step_count":1} {"level":"warn","ts":"2026-03-23T05:38:21.531986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.385539ms","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-03-23T05:38:21.53209Z","caller":"traceutil/trace.go:171","msg":"trace[1799528071] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8001; }","duration":"162.539973ms","start":"2026-03-23T05:38:21.369527Z","end":"2026-03-23T05:38:21.532067Z","steps":["trace[1799528071] 'range keys from in-memory index tree' (duration: 162.195295ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:38:32.699615Z","caller":"traceutil/trace.go:171","msg":"trace[159513964] linearizableReadLoop","detail":"{readStateIndex:8406; appliedIndex:8404; }","duration":"101.61082ms","start":"2026-03-23T05:38:32.597971Z","end":"2026-03-23T05:38:32.699582Z","steps":["trace[159513964] 'read index received' (duration: 29.173943ms)","trace[159513964] 'applied index is now lower than readState.Index' (duration: 72.435317ms)"],"step_count":2} {"level":"info","ts":"2026-03-23T05:38:32.699754Z","caller":"traceutil/trace.go:171","msg":"trace[1058599368] transaction","detail":"{read_only:false; response_revision:8103; number_of_response:1; }","duration":"122.33149ms","start":"2026-03-23T05:38:32.57739Z","end":"2026-03-23T05:38:32.699721Z","steps":["trace[1058599368] 'process raft request' (duration: 101.874564ms)","trace[1058599368] 'compare' (duration: 20.005066ms)"],"step_count":2} {"level":"warn","ts":"2026-03-23T05:38:32.699803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.814415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-23T05:38:32.700778Z","caller":"traceutil/trace.go:171","msg":"trace[1023129170] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8103; }","duration":"102.846469ms","start":"2026-03-23T05:38:32.597913Z","end":"2026-03-23T05:38:32.70076Z","steps":["trace[1023129170] 'agreement among raft nodes before linearized reading' (duration: 101.830975ms)"],"step_count":1} {"level":"warn","ts":"2026-03-23T05:38:32.904604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.847975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:16 size:111915"} {"level":"info","ts":"2026-03-23T05:38:32.904727Z","caller":"traceutil/trace.go:171","msg":"trace[1805573113] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:16; response_revision:8103; }","duration":"105.01183ms","start":"2026-03-23T05:38:32.79969Z","end":"2026-03-23T05:38:32.904702Z","steps":["trace[1805573113] 'range keys from in-memory index tree' (duration: 104.552859ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:38:33.044297Z","caller":"traceutil/trace.go:171","msg":"trace[1807266988] transaction","detail":"{read_only:false; response_revision:8105; number_of_response:1; }","duration":"124.89123ms","start":"2026-03-23T05:38:32.919376Z","end":"2026-03-23T05:38:33.044267Z","steps":["trace[1807266988] 'process raft request' (duration: 124.680805ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:38:33.103587Z","caller":"traceutil/trace.go:171","msg":"trace[52536148] linearizableReadLoop","detail":"{readStateIndex:8410; appliedIndex:8408; }","duration":"138.77689ms","start":"2026-03-23T05:38:32.964771Z","end":"2026-03-23T05:38:33.103548Z","steps":["trace[52536148] 'read index received' (duration: 79.334901ms)","trace[52536148] 'applied index is now lower than readState.Index' (duration: 59.438269ms)"],"step_count":2} {"level":"info","ts":"2026-03-23T05:38:33.103676Z","caller":"traceutil/trace.go:171","msg":"trace[1926864190] transaction","detail":"{read_only:false; response_revision:8106; number_of_response:1; }","duration":"139.123858ms","start":"2026-03-23T05:38:32.964528Z","end":"2026-03-23T05:38:33.103652Z","steps":["trace[1926864190] 'process raft request' (duration: 138.80734ms)"],"step_count":1} {"level":"warn","ts":"2026-03-23T05:38:33.104026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.223851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19434"} {"level":"info","ts":"2026-03-23T05:38:33.104153Z","caller":"traceutil/trace.go:171","msg":"trace[1012736688] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:8106; }","duration":"139.351654ms","start":"2026-03-23T05:38:32.964736Z","end":"2026-03-23T05:38:33.104087Z","steps":["trace[1012736688] 'agreement among raft nodes before linearized reading' (duration: 138.933884ms)"],"step_count":1} {"level":"info","ts":"2026-03-23T05:42:01.77942Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"31541ae44470b70","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-03-23T05:42:01.7859Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-03-23T05:42:01.786039Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-03-23T05:42:39.932599Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7746} {"level":"info","ts":"2026-03-23T05:42:39.998609Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7746,"took":"62.957627ms","hash":874079498,"current-db-size-bytes":42885120,"current-db-size":"43 MB","current-db-size-in-use-bytes":25554944,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-03-23T05:42:39.99869Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":874079498,"revision":7746,"compact-revision":5707} {"level":"info","ts":"2026-03-23T05:47:39.940532Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":9823} {"level":"info","ts":"2026-03-23T05:47:40.018913Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":9823,"took":"75.872197ms","hash":3902411556,"current-db-size-bytes":42885120,"current-db-size":"43 MB","current-db-size-in-use-bytes":18067456,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-03-23T05:47:40.018991Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3902411556,"revision":9823,"compact-revision":7746} {"level":"warn","ts":"2026-03-23T05:48:11.861991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.782432ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-23T05:48:11.862419Z","caller":"traceutil/trace.go:171","msg":"trace[325139053] transaction","detail":"{read_only:false; response_revision:11542; number_of_response:1; }","duration":"276.561472ms","start":"2026-03-23T05:48:11.58554Z","end":"2026-03-23T05:48:11.862102Z","steps":["trace[325139053] 'process raft request' (duration: 55.561685ms)","trace[325139053] 'compare' (duration: 220.548956ms)"],"step_count":2} {"level":"info","ts":"2026-03-23T05:48:11.863385Z","caller":"traceutil/trace.go:171","msg":"trace[636167459] transaction","detail":"{read_only:false; response_revision:11543; number_of_response:1; }","duration":"276.850088ms","start":"2026-03-23T05:48:11.586515Z","end":"2026-03-23T05:48:11.863365Z","steps":["trace[636167459] 'process raft request' (duration: 276.780357ms)"],"step_count":1}