{"level":"warn","ts":"2026-08-05T21:42:01.449875Z","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-05T21:42:01.449971Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.3: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.3:2380","--initial-cluster=instance=https://199.19.213.3:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.3:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.3: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-05T21:42:01.450062Z","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-05T21:42:01.450079Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.3:2380"]} {"level":"info","ts":"2026-08-05T21:42:01.450101Z","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-05T21:42:01.450927Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.3:2379"]} {"level":"info","ts":"2026-08-05T21:42:01.45102Z","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.3:2380"],"listen-peer-urls":["https://199.19.213.3:2380"],"advertise-client-urls":["https://199.19.213.3:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.3:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.3: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-05T21:42:01.454242Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.883933ms"} {"level":"info","ts":"2026-08-05T21:42:01.459387Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"3cf904ec39d3e333","cluster-id":"7b1ae51097bedd0e"} {"level":"info","ts":"2026-08-05T21:42:01.459484Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cf904ec39d3e333 switched to configuration voters=()"} {"level":"info","ts":"2026-08-05T21:42:01.459516Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cf904ec39d3e333 became follower at term 0"} {"level":"info","ts":"2026-08-05T21:42:01.459541Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 3cf904ec39d3e333 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-05T21:42:01.459564Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cf904ec39d3e333 became follower at term 1"} {"level":"info","ts":"2026-08-05T21:42:01.459619Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cf904ec39d3e333 switched to configuration voters=(4393548324105610035)"} {"level":"warn","ts":"2026-08-05T21:42:01.465228Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-05T21:42:01.466932Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-05T21:42:01.468176Z","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-05T21:42:01.471037Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"3cf904ec39d3e333","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-05T21:42:01.471236Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"3cf904ec39d3e333","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-05T21:42:01.471348Z","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-05T21:42:01.471497Z","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-05T21:42:01.471535Z","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-05T21:42:01.472694Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cf904ec39d3e333 switched to configuration voters=(4393548324105610035)"} {"level":"info","ts":"2026-08-05T21:42:01.473043Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"7b1ae51097bedd0e","local-member-id":"3cf904ec39d3e333","added-peer-id":"3cf904ec39d3e333","added-peer-peer-urls":["https://199.19.213.3:2380"]} {"level":"info","ts":"2026-08-05T21:42:01.47769Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.3:2380"} {"level":"info","ts":"2026-08-05T21:42:01.477465Z","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-05T21:42:01.477721Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.3:2380"} {"level":"info","ts":"2026-08-05T21:42:01.478078Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"3cf904ec39d3e333","initial-advertise-peer-urls":["https://199.19.213.3:2380"],"listen-peer-urls":["https://199.19.213.3:2380"],"advertise-client-urls":["https://199.19.213.3:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.3:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-05T21:42:01.478179Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-05T21:42:01.859932Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cf904ec39d3e333 is starting a new election at term 1"} {"level":"info","ts":"2026-08-05T21:42:01.860011Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cf904ec39d3e333 became pre-candidate at term 1"} {"level":"info","ts":"2026-08-05T21:42:01.860032Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cf904ec39d3e333 received MsgPreVoteResp from 3cf904ec39d3e333 at term 1"} {"level":"info","ts":"2026-08-05T21:42:01.860048Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cf904ec39d3e333 became candidate at term 2"} {"level":"info","ts":"2026-08-05T21:42:01.860055Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cf904ec39d3e333 received MsgVoteResp from 3cf904ec39d3e333 at term 2"} {"level":"info","ts":"2026-08-05T21:42:01.860067Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cf904ec39d3e333 became leader at term 2"} {"level":"info","ts":"2026-08-05T21:42:01.860076Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 3cf904ec39d3e333 elected leader 3cf904ec39d3e333 at term 2"} {"level":"info","ts":"2026-08-05T21:42:01.860861Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"3cf904ec39d3e333","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.3:2379]}","request-path":"/0/members/3cf904ec39d3e333/attributes","cluster-id":"7b1ae51097bedd0e","publish-timeout":"7s"} {"level":"info","ts":"2026-08-05T21:42:01.860903Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-05T21:42:01.861101Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-05T21:42:01.861177Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-05T21:42:01.861202Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-05T21:42:01.861161Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-05T21:42:01.863889Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-05T21:42:01.86555Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.3:2379"} {"level":"info","ts":"2026-08-05T21:42:01.870415Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"7b1ae51097bedd0e","local-member-id":"3cf904ec39d3e333","cluster-version":"3.5"} {"level":"info","ts":"2026-08-05T21:42:01.871357Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-05T21:42:01.871461Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-05T21:42:18.367424Z","caller":"traceutil/trace.go:171","msg":"trace[1598504143] transaction","detail":"{read_only:false; response_revision:417; number_of_response:1; }","duration":"133.228848ms","start":"2026-08-05T21:42:18.23417Z","end":"2026-08-05T21:42:18.367399Z","steps":["trace[1598504143] 'process raft request' (duration: 133.071004ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:42:38.58751Z","caller":"traceutil/trace.go:171","msg":"trace[726431984] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"100.448507ms","start":"2026-08-05T21:42:38.487041Z","end":"2026-08-05T21:42:38.58749Z","steps":["trace[726431984] 'process raft request' (duration: 100.177821ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:42:38.587706Z","caller":"traceutil/trace.go:171","msg":"trace[554654890] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"100.60926ms","start":"2026-08-05T21:42:38.487073Z","end":"2026-08-05T21:42:38.587683Z","steps":["trace[554654890] 'process raft request' (duration: 100.196061ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:42:38.587831Z","caller":"traceutil/trace.go:171","msg":"trace[244322280] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"100.831566ms","start":"2026-08-05T21:42:38.486991Z","end":"2026-08-05T21:42:38.587822Z","steps":["trace[244322280] 'process raft request' (duration: 100.11885ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:42:44.837279Z","caller":"traceutil/trace.go:171","msg":"trace[439096114] linearizableReadLoop","detail":"{readStateIndex:654; appliedIndex:653; }","duration":"146.496755ms","start":"2026-08-05T21:42:44.690755Z","end":"2026-08-05T21:42:44.837252Z","steps":["trace[439096114] 'read index received' (duration: 145.81111ms)","trace[439096114] 'applied index is now lower than readState.Index' (duration: 684.675µs)"],"step_count":2} {"level":"info","ts":"2026-08-05T21:42:44.837369Z","caller":"traceutil/trace.go:171","msg":"trace[1850069588] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"221.163575ms","start":"2026-08-05T21:42:44.616162Z","end":"2026-08-05T21:42:44.837326Z","steps":["trace[1850069588] 'process raft request' (duration: 220.588222ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:42:44.837482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.709959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-vfvfj\" ","response":"range_response_count:1 size:16957"} {"level":"info","ts":"2026-08-05T21:42:44.837582Z","caller":"traceutil/trace.go:171","msg":"trace[289871604] range","detail":"{range_begin:/registry/pods/kube-system/cilium-vfvfj; range_end:; response_count:1; response_revision:634; }","duration":"146.873513ms","start":"2026-08-05T21:42:44.690694Z","end":"2026-08-05T21:42:44.837568Z","steps":["trace[289871604] 'agreement among raft nodes before linearized reading' (duration: 146.652508ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:42:45.348827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"499.928787ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:39"} {"level":"warn","ts":"2026-08-05T21:42:45.349091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:42:44.838505Z","time spent":"510.571337ms","remote":"127.0.0.1:42018","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-08-05T21:42:45.349646Z","caller":"traceutil/trace.go:171","msg":"trace[138934162] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"450.433164ms","start":"2026-08-05T21:42:44.899187Z","end":"2026-08-05T21:42:45.34962Z","steps":["trace[138934162] 'process raft request' (duration: 449.975675ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:42:45.350043Z","caller":"traceutil/trace.go:171","msg":"trace[586871047] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"498.24645ms","start":"2026-08-05T21:42:44.851173Z","end":"2026-08-05T21:42:45.34942Z","steps":["trace[586871047] 'process raft request' (duration: 497.78998ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:42:45.350154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:42:44.89916Z","time spent":"450.557556ms","remote":"127.0.0.1:42436","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-05T21:42:45.350985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:42:44.851156Z","time spent":"498.982176ms","remote":"127.0.0.1:42180","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17126,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T21:43:00.183871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.28226ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-08-05T21:43:00.183965Z","caller":"traceutil/trace.go:171","msg":"trace[521165218] linearizableReadLoop","detail":"{readStateIndex:825; appliedIndex:824; }","duration":"186.797679ms","start":"2026-08-05T21:42:59.99715Z","end":"2026-08-05T21:43:00.183947Z","steps":["trace[521165218] 'read index received' (duration: 47.333416ms)","trace[521165218] 'applied index is now lower than readState.Index' (duration: 139.463063ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T21:43:00.184195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.020914ms","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-05T21:43:00.184238Z","caller":"traceutil/trace.go:171","msg":"trace[911344502] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:800; }","duration":"187.128286ms","start":"2026-08-05T21:42:59.997099Z","end":"2026-08-05T21:43:00.184227Z","steps":["trace[911344502] 'agreement among raft nodes before linearized reading' (duration: 186.896801ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:44:11.714411Z","caller":"traceutil/trace.go:171","msg":"trace[5634208] transaction","detail":"{read_only:false; response_revision:1848; number_of_response:1; }","duration":"140.075335ms","start":"2026-08-05T21:44:11.574112Z","end":"2026-08-05T21:44:11.714187Z","steps":["trace[5634208] 'process raft request' (duration: 139.908172ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:44:12.157111Z","caller":"traceutil/trace.go:171","msg":"trace[571450739] transaction","detail":"{read_only:false; response_revision:1849; number_of_response:1; }","duration":"125.649013ms","start":"2026-08-05T21:44:12.031431Z","end":"2026-08-05T21:44:12.15708Z","steps":["trace[571450739] 'process raft request' (duration: 125.465729ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:44:57.468405Z","caller":"traceutil/trace.go:171","msg":"trace[30390700] linearizableReadLoop","detail":"{readStateIndex:2227; appliedIndex:2226; }","duration":"317.969753ms","start":"2026-08-05T21:44:57.15041Z","end":"2026-08-05T21:44:57.46838Z","steps":["trace[30390700] 'read index received' (duration: 317.80218ms)","trace[30390700] 'applied index is now lower than readState.Index' (duration: 166.593µs)"],"step_count":2} {"level":"info","ts":"2026-08-05T21:44:57.468457Z","caller":"traceutil/trace.go:171","msg":"trace[1755586472] transaction","detail":"{read_only:false; response_revision:2150; number_of_response:1; }","duration":"328.942288ms","start":"2026-08-05T21:44:57.139473Z","end":"2026-08-05T21:44:57.468415Z","steps":["trace[1755586472] 'process raft request' (duration: 328.748444ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:44:57.468599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:44:57.139452Z","time spent":"329.076041ms","remote":"127.0.0.1:42238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T21:44:57.4686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.177608ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-08-05T21:44:57.468748Z","caller":"traceutil/trace.go:171","msg":"trace[909124080] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2150; }","duration":"318.349992ms","start":"2026-08-05T21:44:57.150357Z","end":"2026-08-05T21:44:57.468707Z","steps":["trace[909124080] 'agreement among raft nodes before linearized reading' (duration: 318.126717ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:44:57.46888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:44:57.150337Z","time spent":"318.496685ms","remote":"127.0.0.1:42238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-08-05T21:44:57.470003Z","caller":"traceutil/trace.go:171","msg":"trace[774831722] transaction","detail":"{read_only:false; response_revision:2151; number_of_response:1; }","duration":"177.659242ms","start":"2026-08-05T21:44:57.292322Z","end":"2026-08-05T21:44:57.469981Z","steps":["trace[774831722] 'process raft request' (duration: 177.434047ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:44:57.470045Z","caller":"traceutil/trace.go:171","msg":"trace[546158887] transaction","detail":"{read_only:false; response_revision:2152; number_of_response:1; }","duration":"158.481346ms","start":"2026-08-05T21:44:57.311547Z","end":"2026-08-05T21:44:57.470028Z","steps":["trace[546158887] 'process raft request' (duration: 158.341703ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:45:51.573719Z","caller":"traceutil/trace.go:171","msg":"trace[748671192] transaction","detail":"{read_only:false; response_revision:2614; number_of_response:1; }","duration":"148.48396ms","start":"2026-08-05T21:45:51.425212Z","end":"2026-08-05T21:45:51.573696Z","steps":["trace[748671192] 'process raft request' (duration: 148.326137ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:46:25.487759Z","caller":"traceutil/trace.go:171","msg":"trace[469352854] transaction","detail":"{read_only:false; response_revision:2821; number_of_response:1; }","duration":"258.272905ms","start":"2026-08-05T21:46:25.229455Z","end":"2026-08-05T21:46:25.487728Z","steps":["trace[469352854] 'process raft request' (duration: 258.0883ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:49:45.809102Z","caller":"traceutil/trace.go:171","msg":"trace[1062971209] transaction","detail":"{read_only:false; number_of_response:0; response_revision:4166; }","duration":"205.755439ms","start":"2026-08-05T21:49:45.603324Z","end":"2026-08-05T21:49:45.80908Z","steps":["trace[1062971209] 'process raft request' (duration: 205.639956ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:50:16.377006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.573704ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-05T21:50:16.377105Z","caller":"traceutil/trace.go:171","msg":"trace[1809160117] linearizableReadLoop","detail":"{readStateIndex:5021; appliedIndex:5020; }","duration":"225.936042ms","start":"2026-08-05T21:50:16.151156Z","end":"2026-08-05T21:50:16.377092Z","steps":["trace[1809160117] 'read index received' (duration: 120.160864ms)","trace[1809160117] 'applied index is now lower than readState.Index' (duration: 105.774328ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T21:50:16.377192Z","caller":"traceutil/trace.go:171","msg":"trace[1487448021] transaction","detail":"{read_only:false; response_revision:4839; number_of_response:1; }","duration":"229.985431ms","start":"2026-08-05T21:50:16.147177Z","end":"2026-08-05T21:50:16.377162Z","steps":["trace[1487448021] 'process raft request' (duration: 124.205023ms)","trace[1487448021] 'compare' (duration: 105.278297ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T21:50:16.377289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.157451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-08-05T21:50:16.377315Z","caller":"traceutil/trace.go:171","msg":"trace[2137889138] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4839; }","duration":"198.219492ms","start":"2026-08-05T21:50:16.179086Z","end":"2026-08-05T21:50:16.377306Z","steps":["trace[2137889138] 'agreement among raft nodes before linearized reading' (duration: 198.093479ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:50:16.377401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.219398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:11451"} {"level":"info","ts":"2026-08-05T21:50:16.377569Z","caller":"traceutil/trace.go:171","msg":"trace[2039643591] range","detail":"{range_begin:/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4839; }","duration":"226.437113ms","start":"2026-08-05T21:50:16.151107Z","end":"2026-08-05T21:50:16.377544Z","steps":["trace[2039643591] 'agreement among raft nodes before linearized reading' (duration: 226.066245ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:50:19.796491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.451667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/vector\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-05T21:50:19.796596Z","caller":"traceutil/trace.go:171","msg":"trace[352080220] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/vector; range_end:; response_count:0; response_revision:4977; }","duration":"123.59418ms","start":"2026-08-05T21:50:19.672984Z","end":"2026-08-05T21:50:19.796578Z","steps":["trace[352080220] 'range keys from in-memory index tree' (duration: 123.357835ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:50:20.096277Z","caller":"traceutil/trace.go:171","msg":"trace[1759707050] transaction","detail":"{read_only:false; response_revision:4985; number_of_response:1; }","duration":"100.714056ms","start":"2026-08-05T21:50:19.995515Z","end":"2026-08-05T21:50:20.096229Z","steps":["trace[1759707050] 'process raft request' (duration: 100.461191ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:50:32.44778Z","caller":"traceutil/trace.go:171","msg":"trace[1146650654] linearizableReadLoop","detail":"{readStateIndex:5437; appliedIndex:5432; }","duration":"168.025477ms","start":"2026-08-05T21:50:32.27973Z","end":"2026-08-05T21:50:32.447756Z","steps":["trace[1146650654] 'read index received' (duration: 713.046µs)","trace[1146650654] 'applied index is now lower than readState.Index' (duration: 167.311181ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T21:50:32.44797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.210102ms","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-08-05T21:50:32.448063Z","caller":"traceutil/trace.go:171","msg":"trace[1957074906] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:5244; }","duration":"168.342436ms","start":"2026-08-05T21:50:32.279706Z","end":"2026-08-05T21:50:32.448049Z","steps":["trace[1957074906] 'agreement among raft nodes before linearized reading' (duration: 168.173691ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:50:32.448512Z","caller":"traceutil/trace.go:171","msg":"trace[72352160] transaction","detail":"{read_only:false; response_revision:5241; number_of_response:1; }","duration":"178.281803ms","start":"2026-08-05T21:50:32.270213Z","end":"2026-08-05T21:50:32.448495Z","steps":["trace[72352160] 'process raft request' (duration: 138.557778ms)","trace[72352160] 'compare' (duration: 38.689352ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T21:50:32.44891Z","caller":"traceutil/trace.go:171","msg":"trace[1411269992] transaction","detail":"{read_only:false; response_revision:5243; number_of_response:1; }","duration":"177.754192ms","start":"2026-08-05T21:50:32.271132Z","end":"2026-08-05T21:50:32.448886Z","steps":["trace[1411269992] 'process raft request' (duration: 176.529894ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:50:32.448892Z","caller":"traceutil/trace.go:171","msg":"trace[1911618206] transaction","detail":"{read_only:false; response_revision:5244; number_of_response:1; }","duration":"173.950048ms","start":"2026-08-05T21:50:32.274901Z","end":"2026-08-05T21:50:32.448851Z","steps":["trace[1911618206] 'process raft request' (duration: 172.800282ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:50:32.449021Z","caller":"traceutil/trace.go:171","msg":"trace[959587981] transaction","detail":"{read_only:false; response_revision:5242; number_of_response:1; }","duration":"178.706633ms","start":"2026-08-05T21:50:32.270284Z","end":"2026-08-05T21:50:32.44899Z","steps":["trace[959587981] 'process raft request' (duration: 177.323702ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:50:32.668072Z","caller":"traceutil/trace.go:171","msg":"trace[1817721651] transaction","detail":"{read_only:false; response_revision:5248; number_of_response:1; }","duration":"131.057094ms","start":"2026-08-05T21:50:32.53699Z","end":"2026-08-05T21:50:32.668047Z","steps":["trace[1817721651] 'process raft request' (duration: 67.874124ms)","trace[1817721651] 'compare' (duration: 63.093048ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T21:50:32.896465Z","caller":"traceutil/trace.go:171","msg":"trace[425184231] transaction","detail":"{read_only:false; response_revision:5249; number_of_response:1; }","duration":"356.343583ms","start":"2026-08-05T21:50:32.540098Z","end":"2026-08-05T21:50:32.896441Z","steps":["trace[425184231] 'process raft request' (duration: 356.1961ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:50:32.896511Z","caller":"traceutil/trace.go:171","msg":"trace[1766011990] linearizableReadLoop","detail":"{readStateIndex:5442; appliedIndex:5442; }","duration":"239.136342ms","start":"2026-08-05T21:50:32.657336Z","end":"2026-08-05T21:50:32.896473Z","steps":["trace[1766011990] 'read index received' (duration: 239.121182ms)","trace[1766011990] 'applied index is now lower than readState.Index' (duration: 11.9µs)"],"step_count":2} {"level":"warn","ts":"2026-08-05T21:50:32.896666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:50:32.540057Z","time spent":"356.511787ms","remote":"127.0.0.1:42180","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4978,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T21:50:32.896769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.417849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-08-05T21:50:32.896805Z","caller":"traceutil/trace.go:171","msg":"trace[1191523089] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5249; }","duration":"239.508861ms","start":"2026-08-05T21:50:32.657283Z","end":"2026-08-05T21:50:32.896792Z","steps":["trace[1191523089] 'agreement among raft nodes before linearized reading' (duration: 239.335908ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:50:32.915747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.838169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-05T21:50:32.915762Z","caller":"traceutil/trace.go:171","msg":"trace[866231514] transaction","detail":"{read_only:false; response_revision:5251; number_of_response:1; }","duration":"250.777687ms","start":"2026-08-05T21:50:32.664962Z","end":"2026-08-05T21:50:32.91574Z","steps":["trace[866231514] 'process raft request' (duration: 250.682445ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:50:32.915832Z","caller":"traceutil/trace.go:171","msg":"trace[1081298162] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5251; }","duration":"140.950752ms","start":"2026-08-05T21:50:32.774838Z","end":"2026-08-05T21:50:32.915789Z","steps":["trace[1081298162] 'agreement among raft nodes before linearized reading' (duration: 140.839539ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:50:32.915744Z","caller":"traceutil/trace.go:171","msg":"trace[864121991] transaction","detail":"{read_only:false; response_revision:5250; number_of_response:1; }","duration":"250.914361ms","start":"2026-08-05T21:50:32.664807Z","end":"2026-08-05T21:50:32.915721Z","steps":["trace[864121991] 'process raft request' (duration: 250.738137ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:50:40.893312Z","caller":"traceutil/trace.go:171","msg":"trace[943953369] transaction","detail":"{read_only:false; response_revision:5430; number_of_response:1; }","duration":"136.868163ms","start":"2026-08-05T21:50:40.756424Z","end":"2026-08-05T21:50:40.893293Z","steps":["trace[943953369] 'process raft request' (duration: 136.75111ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:52:01.898577Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3018} {"level":"info","ts":"2026-08-05T21:52:01.983396Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3018,"took":"83.625271ms","hash":1889255276,"current-db-size-bytes":58691584,"current-db-size":"59 MB","current-db-size-in-use-bytes":32026624,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-05T21:52:01.983467Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1889255276,"revision":3018,"compact-revision":-1} {"level":"info","ts":"2026-08-05T21:55:10.070999Z","caller":"traceutil/trace.go:171","msg":"trace[1435002981] transaction","detail":"{read_only:false; response_revision:7443; number_of_response:1; }","duration":"106.523706ms","start":"2026-08-05T21:55:09.964445Z","end":"2026-08-05T21:55:10.070969Z","steps":["trace[1435002981] 'process raft request' (duration: 106.312741ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:56:38.018493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.803536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-05T21:56:38.01871Z","caller":"traceutil/trace.go:171","msg":"trace[1826244997] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8187; }","duration":"243.458882ms","start":"2026-08-05T21:56:37.775185Z","end":"2026-08-05T21:56:38.018644Z","steps":["trace[1826244997] 'range keys from in-memory index tree' (duration: 242.742324ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:56:50.559286Z","caller":"traceutil/trace.go:171","msg":"trace[1127104177] transaction","detail":"{read_only:false; response_revision:8251; number_of_response:1; }","duration":"121.107397ms","start":"2026-08-05T21:56:50.438152Z","end":"2026-08-05T21:56:50.559259Z","steps":["trace[1127104177] 'process raft request' (duration: 92.049217ms)","trace[1127104177] 'compare' (duration: 28.881696ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T21:56:50.559319Z","caller":"traceutil/trace.go:171","msg":"trace[1206543330] linearizableReadLoop","detail":"{readStateIndex:8538; appliedIndex:8536; }","duration":"115.619345ms","start":"2026-08-05T21:56:50.443658Z","end":"2026-08-05T21:56:50.559277Z","steps":["trace[1206543330] 'read index received' (duration: 3.953046ms)","trace[1206543330] 'applied index is now lower than readState.Index' (duration: 111.661259ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T21:56:50.559576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.850121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-05T21:56:50.559717Z","caller":"traceutil/trace.go:171","msg":"trace[681135346] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:8252; }","duration":"116.057085ms","start":"2026-08-05T21:56:50.443632Z","end":"2026-08-05T21:56:50.559689Z","steps":["trace[681135346] 'agreement among raft nodes before linearized reading' (duration: 115.736338ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:56:50.868168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.127403ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-05T21:56:50.868288Z","caller":"traceutil/trace.go:171","msg":"trace[222824589] linearizableReadLoop","detail":"{readStateIndex:8540; appliedIndex:8539; }","duration":"194.420161ms","start":"2026-08-05T21:56:50.673853Z","end":"2026-08-05T21:56:50.868273Z","steps":["trace[222824589] 'read index received' (duration: 90.094253ms)","trace[222824589] 'applied index is now lower than readState.Index' (duration: 104.324378ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T21:56:50.868363Z","caller":"traceutil/trace.go:171","msg":"trace[1229265733] transaction","detail":"{read_only:false; response_revision:8253; number_of_response:1; }","duration":"304.169977ms","start":"2026-08-05T21:56:50.564184Z","end":"2026-08-05T21:56:50.868354Z","steps":["trace[1229265733] 'process raft request' (duration: 199.773979ms)","trace[1229265733] 'compare' (duration: 103.99568ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T21:56:50.868431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:56:50.564154Z","time spent":"304.243749ms","remote":"127.0.0.1:42238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T21:56:50.868585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.579245ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-05T21:56:50.868672Z","caller":"traceutil/trace.go:171","msg":"trace[1866184070] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:8253; }","duration":"194.845361ms","start":"2026-08-05T21:56:50.673806Z","end":"2026-08-05T21:56:50.868651Z","steps":["trace[1866184070] 'agreement among raft nodes before linearized reading' (duration: 194.560414ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:56:51.421789Z","caller":"traceutil/trace.go:171","msg":"trace[1732254682] linearizableReadLoop","detail":"{readStateIndex:8546; appliedIndex:8545; }","duration":"203.605164ms","start":"2026-08-05T21:56:51.218164Z","end":"2026-08-05T21:56:51.421769Z","steps":["trace[1732254682] 'read index received' (duration: 203.485141ms)","trace[1732254682] 'applied index is now lower than readState.Index' (duration: 119.333µs)"],"step_count":2} {"level":"info","ts":"2026-08-05T21:56:51.421886Z","caller":"traceutil/trace.go:171","msg":"trace[634911556] transaction","detail":"{read_only:false; response_revision:8258; number_of_response:1; }","duration":"204.656947ms","start":"2026-08-05T21:56:51.217186Z","end":"2026-08-05T21:56:51.421843Z","steps":["trace[634911556] 'process raft request' (duration: 204.446943ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:56:51.421966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.796009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-phjj6\" ","response":"range_response_count:1 size:6522"} {"level":"info","ts":"2026-08-05T21:56:51.421986Z","caller":"traceutil/trace.go:171","msg":"trace[1475897876] range","detail":"{range_begin:/registry/pods/openstack/keepalived-phjj6; range_end:; response_count:1; response_revision:8258; }","duration":"203.87215ms","start":"2026-08-05T21:56:51.218108Z","end":"2026-08-05T21:56:51.42198Z","steps":["trace[1475897876] 'agreement among raft nodes before linearized reading' (duration: 203.761118ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:56:51.422138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.289832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1988"} {"level":"info","ts":"2026-08-05T21:56:51.422196Z","caller":"traceutil/trace.go:171","msg":"trace[1163829188] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:8258; }","duration":"102.397385ms","start":"2026-08-05T21:56:51.319783Z","end":"2026-08-05T21:56:51.42218Z","steps":["trace[1163829188] 'agreement among raft nodes before linearized reading' (duration: 102.19758ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:57:01.905365Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6150} {"level":"info","ts":"2026-08-05T21:57:02.002582Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6150,"took":"94.833438ms","hash":2395367227,"current-db-size-bytes":58691584,"current-db-size":"59 MB","current-db-size-in-use-bytes":22798336,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-08-05T21:57:02.002654Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2395367227,"revision":6150,"compact-revision":3018} {"level":"warn","ts":"2026-08-05T21:57:21.270718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.947087ms","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-05T21:57:21.270853Z","caller":"traceutil/trace.go:171","msg":"trace[1367250404] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8470; }","duration":"110.134961ms","start":"2026-08-05T21:57:21.160695Z","end":"2026-08-05T21:57:21.27083Z","steps":["trace[1367250404] 'range keys from in-memory index tree' (duration: 109.740743ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:57:21.271004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.791461ms","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":"warn","ts":"2026-08-05T21:57:21.271117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.050437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12211"} {"level":"info","ts":"2026-08-05T21:57:21.271132Z","caller":"traceutil/trace.go:171","msg":"trace[1776086497] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8470; }","duration":"208.960165ms","start":"2026-08-05T21:57:21.06215Z","end":"2026-08-05T21:57:21.27111Z","steps":["trace[1776086497] 'range keys from in-memory index tree' (duration: 208.556066ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:57:21.271179Z","caller":"traceutil/trace.go:171","msg":"trace[349387458] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8470; }","duration":"309.138308ms","start":"2026-08-05T21:57:20.962026Z","end":"2026-08-05T21:57:21.271164Z","steps":["trace[349387458] 'range keys from in-memory index tree' (duration: 308.846842ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:57:21.271221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:57:20.962011Z","time spent":"309.20134ms","remote":"127.0.0.1:42180","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12233,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-08-05T21:57:21.511333Z","caller":"traceutil/trace.go:171","msg":"trace[1280376861] transaction","detail":"{read_only:false; response_revision:8471; number_of_response:1; }","duration":"236.303113ms","start":"2026-08-05T21:57:21.274964Z","end":"2026-08-05T21:57:21.511267Z","steps":["trace[1280376861] 'process raft request' (duration: 236.144ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:57:21.528537Z","caller":"traceutil/trace.go:171","msg":"trace[615844404] linearizableReadLoop","detail":"{readStateIndex:8770; appliedIndex:8767; }","duration":"252.984669ms","start":"2026-08-05T21:57:21.275535Z","end":"2026-08-05T21:57:21.528519Z","steps":["trace[615844404] 'read index received' (duration: 235.604119ms)","trace[615844404] 'applied index is now lower than readState.Index' (duration: 17.37988ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T21:57:21.528683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.136012ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19382"} {"level":"info","ts":"2026-08-05T21:57:21.528658Z","caller":"traceutil/trace.go:171","msg":"trace[660938691] transaction","detail":"{read_only:false; response_revision:8473; number_of_response:1; }","duration":"253.343596ms","start":"2026-08-05T21:57:21.275285Z","end":"2026-08-05T21:57:21.528629Z","steps":["trace[660938691] 'process raft request' (duration: 253.210913ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:57:21.528709Z","caller":"traceutil/trace.go:171","msg":"trace[1899062158] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:8473; }","duration":"253.188334ms","start":"2026-08-05T21:57:21.275513Z","end":"2026-08-05T21:57:21.528701Z","steps":["trace[1899062158] 'agreement among raft nodes before linearized reading' (duration: 253.072111ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:57:21.529032Z","caller":"traceutil/trace.go:171","msg":"trace[612424071] transaction","detail":"{read_only:false; response_revision:8472; number_of_response:1; }","duration":"253.481729ms","start":"2026-08-05T21:57:21.275102Z","end":"2026-08-05T21:57:21.528584Z","steps":["trace[612424071] 'process raft request' (duration: 253.298845ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:57:33.801412Z","caller":"traceutil/trace.go:171","msg":"trace[1868714841] transaction","detail":"{read_only:false; response_revision:8583; number_of_response:1; }","duration":"125.967248ms","start":"2026-08-05T21:57:33.675395Z","end":"2026-08-05T21:57:33.801363Z","steps":["trace[1868714841] 'process raft request' (duration: 118.528565ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:57:35.380352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.214718ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-05T21:57:35.380516Z","caller":"traceutil/trace.go:171","msg":"trace[2101496068] transaction","detail":"{read_only:false; response_revision:8594; number_of_response:1; }","duration":"388.936876ms","start":"2026-08-05T21:57:34.991531Z","end":"2026-08-05T21:57:35.380467Z","steps":["trace[2101496068] 'process raft request' (duration: 38.505374ms)","trace[2101496068] 'compare' (duration: 349.88219ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T21:57:35.380618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T21:57:34.991506Z","time spent":"389.062788ms","remote":"127.0.0.1:42286","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":886,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T21:57:35.948524Z","caller":"traceutil/trace.go:171","msg":"trace[1150058025] linearizableReadLoop","detail":"{readStateIndex:8902; appliedIndex:8901; }","duration":"133.477783ms","start":"2026-08-05T21:57:35.815021Z","end":"2026-08-05T21:57:35.948499Z","steps":["trace[1150058025] 'read index received' (duration: 133.249108ms)","trace[1150058025] 'applied index is now lower than readState.Index' (duration: 227.175µs)"],"step_count":2} {"level":"info","ts":"2026-08-05T21:57:35.948763Z","caller":"traceutil/trace.go:171","msg":"trace[595789901] transaction","detail":"{read_only:false; response_revision:8599; number_of_response:1; }","duration":"137.634233ms","start":"2026-08-05T21:57:35.810958Z","end":"2026-08-05T21:57:35.948592Z","steps":["trace[595789901] 'process raft request' (duration: 137.341797ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T21:57:35.948884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.82445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-phjj6\" ","response":"range_response_count:1 size:6522"} {"level":"info","ts":"2026-08-05T21:57:35.948965Z","caller":"traceutil/trace.go:171","msg":"trace[363798918] range","detail":"{range_begin:/registry/pods/openstack/keepalived-phjj6; range_end:; response_count:1; response_revision:8599; }","duration":"133.953953ms","start":"2026-08-05T21:57:35.814993Z","end":"2026-08-05T21:57:35.948947Z","steps":["trace[363798918] 'agreement among raft nodes before linearized reading' (duration: 133.642756ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T21:58:22.309258Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000024be.wal"} {"level":"info","ts":"2026-08-05T21:59:32.214097Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"3cf904ec39d3e333","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-05T21:59:32.220039Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-08-05T21:59:32.22033Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-08-05T22:02:01.912196Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8320} {"level":"info","ts":"2026-08-05T22:02:01.990916Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8320,"took":"76.677628ms","hash":777795360,"current-db-size-bytes":58691584,"current-db-size":"59 MB","current-db-size-in-use-bytes":26767360,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-08-05T22:02:01.991012Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":777795360,"revision":8320,"compact-revision":6150} {"level":"warn","ts":"2026-08-05T22:02:25.103281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.630557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-05T22:02:25.103437Z","caller":"traceutil/trace.go:171","msg":"trace[2066714002] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:11168; }","duration":"133.843741ms","start":"2026-08-05T22:02:24.969569Z","end":"2026-08-05T22:02:25.103413Z","steps":["trace[2066714002] 'count revisions from in-memory index tree' (duration: 133.543085ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:02:25.310382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.009688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-05T22:02:25.310469Z","caller":"traceutil/trace.go:171","msg":"trace[123170430] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-user; range_end:; response_count:0; response_revision:11171; }","duration":"107.130751ms","start":"2026-08-05T22:02:25.20332Z","end":"2026-08-05T22:02:25.310451Z","steps":["trace[123170430] 'range keys from in-memory index tree' (duration: 106.955437ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:02:25.310497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.363956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-05T22:02:25.310579Z","caller":"traceutil/trace.go:171","msg":"trace[194915944] range","detail":"{range_begin:/registry/jobs/openstack/cinder-rabbit-init; range_end:; response_count:0; response_revision:11171; }","duration":"107.471908ms","start":"2026-08-05T22:02:25.203089Z","end":"2026-08-05T22:02:25.310561Z","steps":["trace[194915944] 'range keys from in-memory index tree' (duration: 107.315055ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:02:25.310559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.298854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-05T22:02:25.310646Z","caller":"traceutil/trace.go:171","msg":"trace[665303749] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-endpoints; range_end:; response_count:0; response_revision:11171; }","duration":"107.448408ms","start":"2026-08-05T22:02:25.203181Z","end":"2026-08-05T22:02:25.310629Z","steps":["trace[665303749] 'range keys from in-memory index tree' (duration: 107.222003ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:02:25.310745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.399448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-db-sync\" ","response":"range_response_count:1 size:5079"} {"level":"info","ts":"2026-08-05T22:02:25.310849Z","caller":"traceutil/trace.go:171","msg":"trace[193883504] range","detail":"{range_begin:/registry/jobs/openstack/cinder-db-sync; range_end:; response_count:1; response_revision:11171; }","duration":"102.549621ms","start":"2026-08-05T22:02:25.208276Z","end":"2026-08-05T22:02:25.310826Z","steps":["trace[193883504] 'range keys from in-memory index tree' (duration: 102.151572ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:07:01.918293Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":11013} {"level":"info","ts":"2026-08-05T22:07:02.003513Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":11013,"took":"81.201742ms","hash":3137871561,"current-db-size-bytes":58691584,"current-db-size":"59 MB","current-db-size-in-use-bytes":27750400,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-08-05T22:07:02.003573Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3137871561,"revision":11013,"compact-revision":8320} {"level":"info","ts":"2026-08-05T22:08:15.367909Z","caller":"traceutil/trace.go:171","msg":"trace[1584442493] linearizableReadLoop","detail":"{readStateIndex:14059; appliedIndex:14058; }","duration":"102.420218ms","start":"2026-08-05T22:08:15.265464Z","end":"2026-08-05T22:08:15.367884Z","steps":["trace[1584442493] 'read index received' (duration: 102.214034ms)","trace[1584442493] 'applied index is now lower than readState.Index' (duration: 204.914µs)"],"step_count":2} {"level":"info","ts":"2026-08-05T22:08:15.367979Z","caller":"traceutil/trace.go:171","msg":"trace[824343829] transaction","detail":"{read_only:false; response_revision:13597; number_of_response:1; }","duration":"142.217662ms","start":"2026-08-05T22:08:15.225713Z","end":"2026-08-05T22:08:15.36793Z","steps":["trace[824343829] 'process raft request' (duration: 142.000268ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:08:15.368283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.791456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-08-05T22:08:15.368393Z","caller":"traceutil/trace.go:171","msg":"trace[457487205] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:13597; }","duration":"102.96506ms","start":"2026-08-05T22:08:15.26541Z","end":"2026-08-05T22:08:15.368375Z","steps":["trace[457487205] 'agreement among raft nodes before linearized reading' (duration: 102.580512ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:08:35.003015Z","caller":"traceutil/trace.go:171","msg":"trace[1790283715] linearizableReadLoop","detail":"{readStateIndex:14160; appliedIndex:14159; }","duration":"107.288975ms","start":"2026-08-05T22:08:34.895695Z","end":"2026-08-05T22:08:35.002984Z","steps":["trace[1790283715] 'read index received' (duration: 107.103371ms)","trace[1790283715] 'applied index is now lower than readState.Index' (duration: 183.323µs)"],"step_count":2} {"level":"warn","ts":"2026-08-05T22:08:35.003233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.510289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-phjj6\" ","response":"range_response_count:1 size:6522"} {"level":"info","ts":"2026-08-05T22:08:35.00353Z","caller":"traceutil/trace.go:171","msg":"trace[1055153447] range","detail":"{range_begin:/registry/pods/openstack/keepalived-phjj6; range_end:; response_count:1; response_revision:13693; }","duration":"107.840037ms","start":"2026-08-05T22:08:34.895669Z","end":"2026-08-05T22:08:35.003509Z","steps":["trace[1055153447] 'agreement among raft nodes before linearized reading' (duration: 107.423438ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:08:50.000697Z","caller":"traceutil/trace.go:171","msg":"trace[881604764] transaction","detail":"{read_only:false; response_revision:13790; number_of_response:1; }","duration":"210.094902ms","start":"2026-08-05T22:08:49.790555Z","end":"2026-08-05T22:08:50.00065Z","steps":["trace[881604764] 'process raft request' (duration: 209.820816ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:08:50.231215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.531785ms","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-05T22:08:50.231286Z","caller":"traceutil/trace.go:171","msg":"trace[1454602659] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:13790; }","duration":"114.654106ms","start":"2026-08-05T22:08:50.116617Z","end":"2026-08-05T22:08:50.231271Z","steps":["trace[1454602659] 'range keys from in-memory index tree' (duration: 114.34095ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:08:51.369197Z","caller":"traceutil/trace.go:171","msg":"trace[777300192] linearizableReadLoop","detail":"{readStateIndex:14269; appliedIndex:14268; }","duration":"105.060046ms","start":"2026-08-05T22:08:51.264061Z","end":"2026-08-05T22:08:51.369121Z","steps":["trace[777300192] 'read index received' (duration: 104.505514ms)","trace[777300192] 'applied index is now lower than readState.Index' (duration: 552.652µs)"],"step_count":2} {"level":"info","ts":"2026-08-05T22:08:51.369233Z","caller":"traceutil/trace.go:171","msg":"trace[1202533580] transaction","detail":"{read_only:false; response_revision:13798; number_of_response:1; }","duration":"106.499908ms","start":"2026-08-05T22:08:51.262705Z","end":"2026-08-05T22:08:51.369205Z","steps":["trace[1202533580] 'process raft request' (duration: 105.968046ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:08:51.369513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.402763ms","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-05T22:08:51.369601Z","caller":"traceutil/trace.go:171","msg":"trace[25727853] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:13798; }","duration":"105.519686ms","start":"2026-08-05T22:08:51.264057Z","end":"2026-08-05T22:08:51.369577Z","steps":["trace[25727853] 'agreement among raft nodes before linearized reading' (duration: 105.222829ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:10:23.45119Z","caller":"traceutil/trace.go:171","msg":"trace[414231938] linearizableReadLoop","detail":"{readStateIndex:15340; appliedIndex:15339; }","duration":"308.982831ms","start":"2026-08-05T22:10:23.142169Z","end":"2026-08-05T22:10:23.451152Z","steps":["trace[414231938] 'read index received' (duration: 308.687754ms)","trace[414231938] 'applied index is now lower than readState.Index' (duration: 293.257µs)"],"step_count":2} {"level":"info","ts":"2026-08-05T22:10:23.451252Z","caller":"traceutil/trace.go:171","msg":"trace[965794004] transaction","detail":"{read_only:false; response_revision:14835; number_of_response:1; }","duration":"375.445483ms","start":"2026-08-05T22:10:23.075764Z","end":"2026-08-05T22:10:23.45121Z","steps":["trace[965794004] 'process raft request' (duration: 375.177927ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:10:23.451474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T22:10:23.075729Z","time spent":"375.632687ms","remote":"127.0.0.1:42238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T22:10:23.451526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.420886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18783"} {"level":"info","ts":"2026-08-05T22:10:23.451567Z","caller":"traceutil/trace.go:171","msg":"trace[1461511210] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:14835; }","duration":"277.544948ms","start":"2026-08-05T22:10:23.174009Z","end":"2026-08-05T22:10:23.451554Z","steps":["trace[1461511210] 'agreement among raft nodes before linearized reading' (duration: 277.336604ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:10:23.451569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.3697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-08-05T22:10:23.451693Z","caller":"traceutil/trace.go:171","msg":"trace[514183681] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:14835; }","duration":"309.575854ms","start":"2026-08-05T22:10:23.142087Z","end":"2026-08-05T22:10:23.451663Z","steps":["trace[514183681] 'agreement among raft nodes before linearized reading' (duration: 309.238236ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:10:23.451748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T22:10:23.142055Z","time spent":"309.683626ms","remote":"127.0.0.1:42238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":580,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"info","ts":"2026-08-05T22:12:01.92535Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":13085} {"level":"info","ts":"2026-08-05T22:12:02.003284Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":13085,"took":"75.779808ms","hash":2946986931,"current-db-size-bytes":58691584,"current-db-size":"59 MB","current-db-size-in-use-bytes":31199232,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-05T22:12:02.003344Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2946986931,"revision":13085,"compact-revision":11013} {"level":"info","ts":"2026-08-05T22:16:04.777787Z","caller":"traceutil/trace.go:171","msg":"trace[1458946991] transaction","detail":"{read_only:false; response_revision:17385; number_of_response:1; }","duration":"117.128426ms","start":"2026-08-05T22:16:04.66063Z","end":"2026-08-05T22:16:04.777759Z","steps":["trace[1458946991] 'process raft request' (duration: 116.899771ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:16:05.117273Z","caller":"traceutil/trace.go:171","msg":"trace[1305556673] transaction","detail":"{read_only:false; response_revision:17387; number_of_response:1; }","duration":"232.207391ms","start":"2026-08-05T22:16:04.885034Z","end":"2026-08-05T22:16:05.117241Z","steps":["trace[1305556673] 'process raft request' (duration: 231.978665ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:16:05.422075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.899185ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-05T22:16:05.422234Z","caller":"traceutil/trace.go:171","msg":"trace[1858572970] linearizableReadLoop","detail":"{readStateIndex:17977; appliedIndex:17975; }","duration":"521.580658ms","start":"2026-08-05T22:16:04.90059Z","end":"2026-08-05T22:16:05.422171Z","steps":["trace[1858572970] 'read index received' (duration: 216.391412ms)","trace[1858572970] 'applied index is now lower than readState.Index' (duration: 305.187576ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T22:16:05.422524Z","caller":"traceutil/trace.go:171","msg":"trace[1727322909] transaction","detail":"{read_only:false; response_revision:17388; number_of_response:1; }","duration":"534.274127ms","start":"2026-08-05T22:16:04.888236Z","end":"2026-08-05T22:16:05.42251Z","steps":["trace[1727322909] 'process raft request' (duration: 275.81548ms)","trace[1727322909] 'compare' (duration: 257.346333ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T22:16:05.42261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T22:16:04.888223Z","time spent":"534.330419ms","remote":"127.0.0.1:42238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T22:16:05.422856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"522.264314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.3\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-08-05T22:16:05.422888Z","caller":"traceutil/trace.go:171","msg":"trace[440954626] range","detail":"{range_begin:/registry/masterleases/199.19.213.3; range_end:; response_count:1; response_revision:17388; }","duration":"522.301564ms","start":"2026-08-05T22:16:04.900579Z","end":"2026-08-05T22:16:05.42288Z","steps":["trace[440954626] 'agreement among raft nodes before linearized reading' (duration: 522.191042ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:16:05.42291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T22:16:04.900526Z","time spent":"522.379836ms","remote":"127.0.0.1:42018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.19.213.3\" "} {"level":"warn","ts":"2026-08-05T22:16:05.519728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.170453ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-05T22:16:05.51981Z","caller":"traceutil/trace.go:171","msg":"trace[1301633819] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:17389; }","duration":"374.323646ms","start":"2026-08-05T22:16:05.145468Z","end":"2026-08-05T22:16:05.519791Z","steps":["trace[1301633819] 'agreement among raft nodes before linearized reading' (duration: 374.163933ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:16:05.51985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T22:16:05.145442Z","time spent":"374.400518ms","remote":"127.0.0.1:34486","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":30,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-08-05T22:16:05.519899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.784841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-bx5xl\" ","response":"range_response_count:1 size:22125"} {"level":"info","ts":"2026-08-05T22:16:05.519929Z","caller":"traceutil/trace.go:171","msg":"trace[1861331750] transaction","detail":"{read_only:false; response_revision:17389; number_of_response:1; }","duration":"193.720472ms","start":"2026-08-05T22:16:05.326169Z","end":"2026-08-05T22:16:05.51989Z","steps":["trace[1861331750] 'process raft request' (duration: 193.292833ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:16:05.519994Z","caller":"traceutil/trace.go:171","msg":"trace[1682335804] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-bx5xl; range_end:; response_count:1; response_revision:17389; }","duration":"341.957605ms","start":"2026-08-05T22:16:05.178016Z","end":"2026-08-05T22:16:05.519974Z","steps":["trace[1682335804] 'agreement among raft nodes before linearized reading' (duration: 341.613898ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:16:05.520043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T22:16:05.17799Z","time spent":"342.045216ms","remote":"127.0.0.1:42180","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":22147,"request content":"key:\"/registry/pods/openstack/nova-compute-default-bx5xl\" "} {"level":"warn","ts":"2026-08-05T22:16:05.520147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"564.183325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-k4hl4\" ","response":"range_response_count:1 size:9798"} {"level":"warn","ts":"2026-08-05T22:16:05.520202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"598.115672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" ","response":"range_response_count:1 size:7913"} {"level":"info","ts":"2026-08-05T22:16:05.520317Z","caller":"traceutil/trace.go:171","msg":"trace[457520422] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/openstack/ceph; range_end:; response_count:1; response_revision:17389; }","duration":"598.297776ms","start":"2026-08-05T22:16:04.921991Z","end":"2026-08-05T22:16:05.520289Z","steps":["trace[457520422] 'agreement among raft nodes before linearized reading' (duration: 597.916548ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:16:05.520374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T22:16:04.921961Z","time spent":"598.403887ms","remote":"127.0.0.1:49198","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":7935,"request content":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" "} {"level":"info","ts":"2026-08-05T22:16:05.520277Z","caller":"traceutil/trace.go:171","msg":"trace[1515917184] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-k4hl4; range_end:; response_count:1; response_revision:17389; }","duration":"564.41822ms","start":"2026-08-05T22:16:04.955833Z","end":"2026-08-05T22:16:05.520251Z","steps":["trace[1515917184] 'agreement among raft nodes before linearized reading' (duration: 563.821247ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:16:05.520936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T22:16:04.955809Z","time spent":"565.105105ms","remote":"127.0.0.1:42180","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":9820,"request content":"key:\"/registry/pods/openstack/nova-cell-setup-k4hl4\" "} {"level":"warn","ts":"2026-08-05T22:16:05.567062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.658389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-08-05T22:16:05.567219Z","caller":"traceutil/trace.go:171","msg":"trace[1898458840] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:17389; }","duration":"132.847503ms","start":"2026-08-05T22:16:05.434357Z","end":"2026-08-05T22:16:05.567205Z","steps":["trace[1898458840] 'agreement among raft nodes before linearized reading' (duration: 132.571207ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:16:05.567409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.759373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-08-05T22:16:05.567455Z","caller":"traceutil/trace.go:171","msg":"trace[1582549742] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:17389; }","duration":"133.849755ms","start":"2026-08-05T22:16:05.433599Z","end":"2026-08-05T22:16:05.567449Z","steps":["trace[1582549742] 'agreement among raft nodes before linearized reading' (duration: 133.766183ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:17:01.932127Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15469} {"level":"info","ts":"2026-08-05T22:17:02.031038Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15469,"took":"95.498951ms","hash":2953655245,"current-db-size-bytes":58691584,"current-db-size":"59 MB","current-db-size-in-use-bytes":33742848,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-08-05T22:17:02.031105Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2953655245,"revision":15469,"compact-revision":13085} {"level":"info","ts":"2026-08-05T22:20:00.126986Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"3cf904ec39d3e333","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-05T22:20:00.135375Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-08-05T22:20:00.135492Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-08-05T22:21:17.992811Z","caller":"traceutil/trace.go:171","msg":"trace[1679536128] transaction","detail":"{read_only:false; response_revision:19895; number_of_response:1; }","duration":"176.465086ms","start":"2026-08-05T22:21:17.816317Z","end":"2026-08-05T22:21:17.992782Z","steps":["trace[1679536128] 'process raft request' (duration: 176.286132ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:21:23.347653Z","caller":"traceutil/trace.go:171","msg":"trace[30706751] linearizableReadLoop","detail":"{readStateIndex:20594; appliedIndex:20593; }","duration":"131.744814ms","start":"2026-08-05T22:21:23.215883Z","end":"2026-08-05T22:21:23.347628Z","steps":["trace[30706751] 'read index received' (duration: 131.547989ms)","trace[30706751] 'applied index is now lower than readState.Index' (duration: 195.865µs)"],"step_count":2} {"level":"info","ts":"2026-08-05T22:21:23.34772Z","caller":"traceutil/trace.go:171","msg":"trace[764309404] transaction","detail":"{read_only:false; response_revision:19920; number_of_response:1; }","duration":"139.559306ms","start":"2026-08-05T22:21:23.208127Z","end":"2026-08-05T22:21:23.347686Z","steps":["trace[764309404] 'process raft request' (duration: 139.362542ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:21:23.347848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.938968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-08-05T22:21:23.347935Z","caller":"traceutil/trace.go:171","msg":"trace[1579594121] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:19920; }","duration":"132.064821ms","start":"2026-08-05T22:21:23.215856Z","end":"2026-08-05T22:21:23.347921Z","steps":["trace[1579594121] 'agreement among raft nodes before linearized reading' (duration: 131.867767ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:21:23.665306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.919888ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-05T22:21:23.665407Z","caller":"traceutil/trace.go:171","msg":"trace[664286004] linearizableReadLoop","detail":"{readStateIndex:20595; appliedIndex:20594; }","duration":"249.053151ms","start":"2026-08-05T22:21:23.416338Z","end":"2026-08-05T22:21:23.665391Z","steps":["trace[664286004] 'read index received' (duration: 90.898718ms)","trace[664286004] 'applied index is now lower than readState.Index' (duration: 158.153053ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T22:21:23.665589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.245375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-08-05T22:21:23.66563Z","caller":"traceutil/trace.go:171","msg":"trace[1784497040] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:19921; }","duration":"249.316666ms","start":"2026-08-05T22:21:23.416302Z","end":"2026-08-05T22:21:23.665618Z","steps":["trace[1784497040] 'agreement among raft nodes before linearized reading' (duration: 249.175583ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:21:23.66551Z","caller":"traceutil/trace.go:171","msg":"trace[1101816323] transaction","detail":"{read_only:false; response_revision:19921; number_of_response:1; }","duration":"313.504967ms","start":"2026-08-05T22:21:23.351972Z","end":"2026-08-05T22:21:23.665477Z","steps":["trace[1101816323] 'process raft request' (duration: 155.327313ms)","trace[1101816323] 'compare' (duration: 157.637252ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T22:21:23.665879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T22:21:23.351951Z","time spent":"313.845664ms","remote":"127.0.0.1:42238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T22:21:23.973054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.37181ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-05T22:21:23.973189Z","caller":"traceutil/trace.go:171","msg":"trace[1702970476] transaction","detail":"{read_only:false; response_revision:19922; number_of_response:1; }","duration":"303.771603ms","start":"2026-08-05T22:21:23.669398Z","end":"2026-08-05T22:21:23.973169Z","steps":["trace[1702970476] 'process raft request' (duration: 145.200089ms)","trace[1702970476] 'compare' (duration: 158.179646ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T22:21:23.973257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T22:21:23.669375Z","time spent":"303.849465ms","remote":"127.0.0.1:42238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T22:21:24.286755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.700165ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-05T22:21:24.286956Z","caller":"traceutil/trace.go:171","msg":"trace[2072080018] transaction","detail":"{read_only:false; response_revision:19924; number_of_response:1; }","duration":"172.613222ms","start":"2026-08-05T22:21:24.114326Z","end":"2026-08-05T22:21:24.286939Z","steps":["trace[2072080018] 'process raft request' (duration: 172.547921ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:21:24.286991Z","caller":"traceutil/trace.go:171","msg":"trace[1011709978] linearizableReadLoop","detail":"{readStateIndex:20597; appliedIndex:20595; }","duration":"510.893792ms","start":"2026-08-05T22:21:23.77606Z","end":"2026-08-05T22:21:24.286954Z","steps":["trace[1011709978] 'read index received' (duration: 38.499886ms)","trace[1011709978] 'applied index is now lower than readState.Index' (duration: 472.392286ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T22:21:24.287056Z","caller":"traceutil/trace.go:171","msg":"trace[177563146] transaction","detail":"{read_only:false; response_revision:19923; number_of_response:1; }","duration":"616.921081ms","start":"2026-08-05T22:21:23.670058Z","end":"2026-08-05T22:21:24.286979Z","steps":["trace[177563146] 'process raft request' (duration: 422.897519ms)","trace[177563146] 'compare' (duration: 193.547632ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T22:21:24.287256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.646642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:562"} {"level":"warn","ts":"2026-08-05T22:21:24.287238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"511.129327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-05T22:21:24.287303Z","caller":"traceutil/trace.go:171","msg":"trace[1799340277] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:19924; }","duration":"309.738734ms","start":"2026-08-05T22:21:23.97755Z","end":"2026-08-05T22:21:24.287289Z","steps":["trace[1799340277] 'agreement among raft nodes before linearized reading' (duration: 309.56859ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:21:24.287303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.735988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"warn","ts":"2026-08-05T22:21:24.287341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T22:21:23.977534Z","time spent":"309.798966ms","remote":"127.0.0.1:42238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":584,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"info","ts":"2026-08-05T22:21:24.287343Z","caller":"traceutil/trace.go:171","msg":"trace[1763134338] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:19924; }","duration":"511.29949ms","start":"2026-08-05T22:21:23.776025Z","end":"2026-08-05T22:21:24.287324Z","steps":["trace[1763134338] 'agreement among raft nodes before linearized reading' (duration: 511.082926ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:21:24.287384Z","caller":"traceutil/trace.go:171","msg":"trace[198005323] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:19924; }","duration":"270.829079ms","start":"2026-08-05T22:21:24.016527Z","end":"2026-08-05T22:21:24.287356Z","steps":["trace[198005323] 'agreement among raft nodes before linearized reading' (duration: 270.654625ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:21:24.287424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T22:21:23.776005Z","time spent":"511.408783ms","remote":"127.0.0.1:41978","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-05T22:21:24.287389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T22:21:23.670031Z","time spent":"617.165157ms","remote":"127.0.0.1:42238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T22:21:24.560305Z","caller":"traceutil/trace.go:171","msg":"trace[636448279] transaction","detail":"{read_only:false; response_revision:19926; number_of_response:1; }","duration":"267.823594ms","start":"2026-08-05T22:21:24.292454Z","end":"2026-08-05T22:21:24.560278Z","steps":["trace[636448279] 'process raft request' (duration: 186.534807ms)","trace[636448279] 'compare' (duration: 81.184294ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T22:21:24.567665Z","caller":"traceutil/trace.go:171","msg":"trace[343695010] linearizableReadLoop","detail":"{readStateIndex:20601; appliedIndex:20599; }","duration":"105.943047ms","start":"2026-08-05T22:21:24.461705Z","end":"2026-08-05T22:21:24.567648Z","steps":["trace[343695010] 'read index received' (duration: 17.304259ms)","trace[343695010] 'applied index is now lower than readState.Index' (duration: 88.637958ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T22:21:24.567717Z","caller":"traceutil/trace.go:171","msg":"trace[6260630] transaction","detail":"{read_only:false; response_revision:19927; number_of_response:1; }","duration":"166.670281ms","start":"2026-08-05T22:21:24.401016Z","end":"2026-08-05T22:21:24.567686Z","steps":["trace[6260630] 'process raft request' (duration: 166.540118ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:21:24.567855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.134392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-05T22:21:24.567904Z","caller":"traceutil/trace.go:171","msg":"trace[152654157] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:19927; }","duration":"106.227353ms","start":"2026-08-05T22:21:24.461666Z","end":"2026-08-05T22:21:24.567893Z","steps":["trace[152654157] 'agreement among raft nodes before linearized reading' (duration: 106.08181ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:21:24.719357Z","caller":"traceutil/trace.go:171","msg":"trace[1382419112] transaction","detail":"{read_only:false; response_revision:19928; number_of_response:1; }","duration":"148.354989ms","start":"2026-08-05T22:21:24.570976Z","end":"2026-08-05T22:21:24.719331Z","steps":["trace[1382419112] 'process raft request' (duration: 124.834822ms)","trace[1382419112] 'compare' (duration: 23.21225ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T22:21:25.033743Z","caller":"traceutil/trace.go:171","msg":"trace[589981536] linearizableReadLoop","detail":"{readStateIndex:20603; appliedIndex:20602; }","duration":"260.044252ms","start":"2026-08-05T22:21:24.773672Z","end":"2026-08-05T22:21:25.033717Z","steps":["trace[589981536] 'read index received' (duration: 259.909638ms)","trace[589981536] 'applied index is now lower than readState.Index' (duration: 133.374µs)"],"step_count":2} {"level":"info","ts":"2026-08-05T22:21:25.033856Z","caller":"traceutil/trace.go:171","msg":"trace[1282428702] transaction","detail":"{read_only:false; response_revision:19929; number_of_response:1; }","duration":"432.833407ms","start":"2026-08-05T22:21:24.600988Z","end":"2026-08-05T22:21:25.033822Z","steps":["trace[1282428702] 'process raft request' (duration: 432.53757ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:21:25.033915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.220807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-05T22:21:25.033956Z","caller":"traceutil/trace.go:171","msg":"trace[1973201087] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:19929; }","duration":"260.346909ms","start":"2026-08-05T22:21:24.773597Z","end":"2026-08-05T22:21:25.033944Z","steps":["trace[1973201087] 'agreement among raft nodes before linearized reading' (duration: 260.247677ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:21:25.034041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T22:21:24.600957Z","time spent":"432.990761ms","remote":"127.0.0.1:42238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-05T22:21:25.07628Z","caller":"traceutil/trace.go:171","msg":"trace[2083415211] transaction","detail":"{read_only:false; response_revision:19930; number_of_response:1; }","duration":"301.157935ms","start":"2026-08-05T22:21:24.775099Z","end":"2026-08-05T22:21:25.076257Z","steps":["trace[2083415211] 'process raft request' (duration: 300.879459ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:21:25.07638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.89669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.3\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-08-05T22:21:25.076419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-05T22:21:24.775065Z","time spent":"301.275998ms","remote":"127.0.0.1:42238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-05T22:21:25.076427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.740776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-05T22:21:25.07653Z","caller":"traceutil/trace.go:171","msg":"trace[1008763998] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:19930; }","duration":"157.91933ms","start":"2026-08-05T22:21:24.91859Z","end":"2026-08-05T22:21:25.076509Z","steps":["trace[1008763998] 'agreement among raft nodes before linearized reading' (duration: 157.713105ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:21:25.076577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.501898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-08-05T22:21:25.076651Z","caller":"traceutil/trace.go:171","msg":"trace[1165482537] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:19930; }","duration":"258.619221ms","start":"2026-08-05T22:21:24.818015Z","end":"2026-08-05T22:21:25.076635Z","steps":["trace[1165482537] 'agreement among raft nodes before linearized reading' (duration: 258.406596ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:21:25.076459Z","caller":"traceutil/trace.go:171","msg":"trace[1103739552] range","detail":"{range_begin:/registry/masterleases/199.19.213.3; range_end:; response_count:1; response_revision:19930; }","duration":"158.022981ms","start":"2026-08-05T22:21:24.918418Z","end":"2026-08-05T22:21:25.076441Z","steps":["trace[1103739552] 'agreement among raft nodes before linearized reading' (duration: 157.808217ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:22:01.942881Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":18045} {"level":"info","ts":"2026-08-05T22:22:02.056775Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":18045,"took":"110.041278ms","hash":2140906784,"current-db-size-bytes":58691584,"current-db-size":"59 MB","current-db-size-in-use-bytes":30867456,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-05T22:22:02.056834Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2140906784,"revision":18045,"compact-revision":15469} {"level":"info","ts":"2026-08-05T22:26:38.384654Z","caller":"traceutil/trace.go:171","msg":"trace[639014896] transaction","detail":"{read_only:false; response_revision:23246; number_of_response:1; }","duration":"131.398897ms","start":"2026-08-05T22:26:38.253223Z","end":"2026-08-05T22:26:38.384622Z","steps":["trace[639014896] 'process raft request' (duration: 131.162031ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:26:38.4497Z","caller":"traceutil/trace.go:171","msg":"trace[821258637] linearizableReadLoop","detail":"{readStateIndex:24019; appliedIndex:24017; }","duration":"123.459652ms","start":"2026-08-05T22:26:38.326214Z","end":"2026-08-05T22:26:38.449674Z","steps":["trace[821258637] 'read index received' (duration: 58.164588ms)","trace[821258637] 'applied index is now lower than readState.Index' (duration: 65.293504ms)"],"step_count":2} {"level":"warn","ts":"2026-08-05T22:26:38.44999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.765218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-domain-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-05T22:26:38.450056Z","caller":"traceutil/trace.go:171","msg":"trace[1271365088] range","detail":"{range_begin:/registry/jobs/openstack/magnum-domain-ks-user; range_end:; response_count:0; response_revision:23247; }","duration":"123.870991ms","start":"2026-08-05T22:26:38.326172Z","end":"2026-08-05T22:26:38.450043Z","steps":["trace[1271365088] 'agreement among raft nodes before linearized reading' (duration: 123.738658ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:26:38.450156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.655746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-05T22:26:38.450211Z","caller":"traceutil/trace.go:171","msg":"trace[1119566290] range","detail":"{range_begin:/registry/jobs/openstack/magnum-rabbit-init; range_end:; response_count:0; response_revision:23247; }","duration":"123.742077ms","start":"2026-08-05T22:26:38.326456Z","end":"2026-08-05T22:26:38.450198Z","steps":["trace[1119566290] 'agreement among raft nodes before linearized reading' (duration: 123.638995ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:26:38.450299Z","caller":"traceutil/trace.go:171","msg":"trace[451227578] transaction","detail":"{read_only:false; response_revision:23247; number_of_response:1; }","duration":"167.006227ms","start":"2026-08-05T22:26:38.283278Z","end":"2026-08-05T22:26:38.450285Z","steps":["trace[451227578] 'process raft request' (duration: 165.30384ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:26:38.450548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.648376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-05T22:26:38.450589Z","caller":"traceutil/trace.go:171","msg":"trace[1496536344] range","detail":"{range_begin:/registry/jobs/openstack/magnum-ks-endpoints; range_end:; response_count:0; response_revision:23247; }","duration":"123.726088ms","start":"2026-08-05T22:26:38.326853Z","end":"2026-08-05T22:26:38.450579Z","steps":["trace[1496536344] 'agreement among raft nodes before linearized reading' (duration: 123.665446ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:26:38.450633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.099756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-05T22:26:38.450677Z","caller":"traceutil/trace.go:171","msg":"trace[139324083] range","detail":"{range_begin:/registry/jobs/openstack/magnum-ks-user; range_end:; response_count:0; response_revision:23247; }","duration":"124.167267ms","start":"2026-08-05T22:26:38.3265Z","end":"2026-08-05T22:26:38.450667Z","steps":["trace[139324083] 'agreement among raft nodes before linearized reading' (duration: 124.105845ms)"],"step_count":1} {"level":"warn","ts":"2026-08-05T22:26:38.450851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.267862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-db-sync\" ","response":"range_response_count:1 size:5082"} {"level":"info","ts":"2026-08-05T22:26:38.45091Z","caller":"traceutil/trace.go:171","msg":"trace[601704940] range","detail":"{range_begin:/registry/jobs/openstack/magnum-db-sync; range_end:; response_count:1; response_revision:23247; }","duration":"120.343404ms","start":"2026-08-05T22:26:38.330552Z","end":"2026-08-05T22:26:38.450895Z","steps":["trace[601704940] 'agreement among raft nodes before linearized reading' (duration: 120.18498ms)"],"step_count":1} {"level":"info","ts":"2026-08-05T22:26:38.561433Z","caller":"traceutil/trace.go:171","msg":"trace[493040273] transaction","detail":"{read_only:false; response_revision:23248; number_of_response:1; }","duration":"106.137871ms","start":"2026-08-05T22:26:38.455262Z","end":"2026-08-05T22:26:38.5614Z","steps":["trace[493040273] 'process raft request' (duration: 36.719646ms)","trace[493040273] 'compare' (duration: 69.246942ms)"],"step_count":2} {"level":"info","ts":"2026-08-05T22:27:01.950205Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20353} {"level":"info","ts":"2026-08-05T22:27:02.063227Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20353,"took":"107.0031ms","hash":2547939981,"current-db-size-bytes":59535360,"current-db-size":"60 MB","current-db-size-in-use-bytes":43429888,"current-db-size-in-use":"43 MB"} {"level":"info","ts":"2026-08-05T22:27:02.063287Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2547939981,"revision":20353,"compact-revision":18045} {"level":"info","ts":"2026-08-05T22:32:01.956359Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23433} {"level":"info","ts":"2026-08-05T22:32:02.09052Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23433,"took":"129.929184ms","hash":2430713813,"current-db-size-bytes":59535360,"current-db-size":"60 MB","current-db-size-in-use-bytes":30445568,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-08-05T22:32:02.090606Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2430713813,"revision":23433,"compact-revision":20353}