{"level":"warn","ts":"2026-05-19T22:19:02.109728Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-19T22:19:02.109821Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.100: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://162.253.55.100:2380","--initial-cluster=instance=https://162.253.55.100:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.100:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.100: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-05-19T22:19:02.109883Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-19T22:19:02.109892Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.100:2380"]} {"level":"info","ts":"2026-05-19T22:19:02.109911Z","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-05-19T22:19:02.110509Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.100:2379"]} {"level":"info","ts":"2026-05-19T22:19:02.110581Z","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://162.253.55.100:2380"],"listen-peer-urls":["https://162.253.55.100:2380"],"advertise-client-urls":["https://162.253.55.100:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.100:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.100: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-05-19T22:19:02.11412Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.337644ms"} {"level":"info","ts":"2026-05-19T22:19:02.125346Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d716dd02ccafa30","cluster-id":"c093465db09ae4fe"} {"level":"info","ts":"2026-05-19T22:19:02.125634Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d716dd02ccafa30 switched to configuration voters=()"} {"level":"info","ts":"2026-05-19T22:19:02.125717Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d716dd02ccafa30 became follower at term 0"} {"level":"info","ts":"2026-05-19T22:19:02.125743Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d716dd02ccafa30 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-19T22:19:02.125809Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d716dd02ccafa30 became follower at term 1"} {"level":"info","ts":"2026-05-19T22:19:02.125959Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d716dd02ccafa30 switched to configuration voters=(968676135733492272)"} {"level":"warn","ts":"2026-05-19T22:19:02.132827Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-19T22:19:02.135419Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-19T22:19:02.136437Z","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-05-19T22:19:02.137883Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d716dd02ccafa30","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-19T22:19:02.138039Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d716dd02ccafa30","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-19T22:19:02.13814Z","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-05-19T22:19:02.138246Z","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-05-19T22:19:02.138277Z","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-05-19T22:19:02.138795Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d716dd02ccafa30 switched to configuration voters=(968676135733492272)"} {"level":"info","ts":"2026-05-19T22:19:02.139006Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"c093465db09ae4fe","local-member-id":"d716dd02ccafa30","added-peer-id":"d716dd02ccafa30","added-peer-peer-urls":["https://162.253.55.100:2380"]} {"level":"info","ts":"2026-05-19T22:19:02.139903Z","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-05-19T22:19:02.140057Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.100:2380"} {"level":"info","ts":"2026-05-19T22:19:02.140106Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.100:2380"} {"level":"info","ts":"2026-05-19T22:19:02.14011Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d716dd02ccafa30","initial-advertise-peer-urls":["https://162.253.55.100:2380"],"listen-peer-urls":["https://162.253.55.100:2380"],"advertise-client-urls":["https://162.253.55.100:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.100:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-19T22:19:02.140172Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-19T22:19:02.926843Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d716dd02ccafa30 is starting a new election at term 1"} {"level":"info","ts":"2026-05-19T22:19:02.926929Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d716dd02ccafa30 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-19T22:19:02.926971Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d716dd02ccafa30 received MsgPreVoteResp from d716dd02ccafa30 at term 1"} {"level":"info","ts":"2026-05-19T22:19:02.926993Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d716dd02ccafa30 became candidate at term 2"} {"level":"info","ts":"2026-05-19T22:19:02.927007Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d716dd02ccafa30 received MsgVoteResp from d716dd02ccafa30 at term 2"} {"level":"info","ts":"2026-05-19T22:19:02.927042Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d716dd02ccafa30 became leader at term 2"} {"level":"info","ts":"2026-05-19T22:19:02.927076Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d716dd02ccafa30 elected leader d716dd02ccafa30 at term 2"} {"level":"info","ts":"2026-05-19T22:19:02.928305Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d716dd02ccafa30","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.100:2379]}","request-path":"/0/members/d716dd02ccafa30/attributes","cluster-id":"c093465db09ae4fe","publish-timeout":"7s"} {"level":"info","ts":"2026-05-19T22:19:02.928358Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-19T22:19:02.928338Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T22:19:02.928399Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-19T22:19:02.9285Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-19T22:19:02.928522Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-19T22:19:02.930209Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"c093465db09ae4fe","local-member-id":"d716dd02ccafa30","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T22:19:02.93033Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T22:19:02.930391Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T22:19:02.932748Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.100:2379"} {"level":"info","ts":"2026-05-19T22:19:02.933162Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-19T22:19:32.529856Z","caller":"traceutil/trace.go:171","msg":"trace[1696433098] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"121.651876ms","start":"2026-05-19T22:19:32.408171Z","end":"2026-05-19T22:19:32.529823Z","steps":["trace[1696433098] 'process raft request' (duration: 115.848324ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:19:32.530021Z","caller":"traceutil/trace.go:171","msg":"trace[332515343] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"111.961204ms","start":"2026-05-19T22:19:32.418044Z","end":"2026-05-19T22:19:32.530005Z","steps":["trace[332515343] 'process raft request' (duration: 111.732738ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:19:32.530056Z","caller":"traceutil/trace.go:171","msg":"trace[574841044] transaction","detail":"{read_only:false; number_of_response:1; response_revision:517; }","duration":"111.532942ms","start":"2026-05-19T22:19:32.418486Z","end":"2026-05-19T22:19:32.530019Z","steps":["trace[574841044] 'process raft request' (duration: 111.341977ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:19:32.529913Z","caller":"traceutil/trace.go:171","msg":"trace[56350180] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"117.235721ms","start":"2026-05-19T22:19:32.412628Z","end":"2026-05-19T22:19:32.529864Z","steps":["trace[56350180] 'process raft request' (duration: 117.099367ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:19:32.530319Z","caller":"traceutil/trace.go:171","msg":"trace[12110967] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"101.267304ms","start":"2026-05-19T22:19:32.429033Z","end":"2026-05-19T22:19:32.5303Z","steps":["trace[12110967] 'process raft request' (duration: 100.916174ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:19:32.530393Z","caller":"traceutil/trace.go:171","msg":"trace[865639121] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"100.683288ms","start":"2026-05-19T22:19:32.429692Z","end":"2026-05-19T22:19:32.530376Z","steps":["trace[865639121] 'process raft request' (duration: 100.303128ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:19:32.530496Z","caller":"traceutil/trace.go:171","msg":"trace[1479492679] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"108.327052ms","start":"2026-05-19T22:19:32.422154Z","end":"2026-05-19T22:19:32.530481Z","steps":["trace[1479492679] 'process raft request' (duration: 107.709695ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:19:32.530617Z","caller":"traceutil/trace.go:171","msg":"trace[1074813600] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"107.308814ms","start":"2026-05-19T22:19:32.423262Z","end":"2026-05-19T22:19:32.530571Z","steps":["trace[1074813600] 'process raft request' (duration: 106.647065ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:19:32.801706Z","caller":"traceutil/trace.go:171","msg":"trace[1571696641] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"111.634834ms","start":"2026-05-19T22:19:32.69004Z","end":"2026-05-19T22:19:32.801675Z","steps":["trace[1571696641] 'process raft request' (duration: 106.262594ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:19:38.501672Z","caller":"traceutil/trace.go:171","msg":"trace[1928727190] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"118.425973ms","start":"2026-05-19T22:19:38.383223Z","end":"2026-05-19T22:19:38.501649Z","steps":["trace[1928727190] 'process raft request' (duration: 117.932419ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:19:38.792154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.390871ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-19T22:19:38.79235Z","caller":"traceutil/trace.go:171","msg":"trace[295203803] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"143.744057ms","start":"2026-05-19T22:19:38.648585Z","end":"2026-05-19T22:19:38.792329Z","steps":["trace[295203803] 'process raft request' (duration: 143.683225ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:19:38.792611Z","caller":"traceutil/trace.go:171","msg":"trace[1041710723] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"145.115436ms","start":"2026-05-19T22:19:38.647485Z","end":"2026-05-19T22:19:38.7926Z","steps":["trace[1041710723] 'process raft request' (duration: 42.698705ms)","trace[1041710723] 'compare' (duration: 101.239867ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T22:19:39.27896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.018382ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T22:19:39.279121Z","caller":"traceutil/trace.go:171","msg":"trace[1996816885] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:615; }","duration":"253.193576ms","start":"2026-05-19T22:19:39.025903Z","end":"2026-05-19T22:19:39.279097Z","steps":["trace[1996816885] 'range keys from in-memory index tree' (duration: 252.95598ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:19:39.279239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.428178ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-19T22:19:39.279425Z","caller":"traceutil/trace.go:171","msg":"trace[1258207914] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"445.550057ms","start":"2026-05-19T22:19:38.833843Z","end":"2026-05-19T22:19:39.279393Z","steps":["trace[1258207914] 'process raft request' (duration: 267.80266ms)","trace[1258207914] 'compare' (duration: 177.125479ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T22:19:39.279534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:19:38.833819Z","time spent":"445.665739ms","remote":"127.0.0.1:52174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T22:19:39.281178Z","caller":"traceutil/trace.go:171","msg":"trace[1704784690] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"445.31145ms","start":"2026-05-19T22:19:38.835834Z","end":"2026-05-19T22:19:39.281145Z","steps":["trace[1704784690] 'process raft request' (duration: 444.820756ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:19:39.281299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:19:38.835819Z","time spent":"445.425962ms","remote":"127.0.0.1:52174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T22:19:39.282014Z","caller":"traceutil/trace.go:171","msg":"trace[436300798] linearizableReadLoop","detail":"{readStateIndex:642; appliedIndex:640; }","duration":"419.43785ms","start":"2026-05-19T22:19:38.862529Z","end":"2026-05-19T22:19:39.281966Z","steps":["trace[436300798] 'read index received' (duration: 239.258564ms)","trace[436300798] 'applied index is now lower than readState.Index' (duration: 180.177916ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T22:19:39.282096Z","caller":"traceutil/trace.go:171","msg":"trace[1004221387] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"315.715321ms","start":"2026-05-19T22:19:38.96636Z","end":"2026-05-19T22:19:39.282075Z","steps":["trace[1004221387] 'process raft request' (duration: 314.806915ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:19:39.282182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:19:38.966329Z","time spent":"315.807613ms","remote":"127.0.0.1:52002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036309,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T22:19:39.282277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.728098ms","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-05-19T22:19:39.28235Z","caller":"traceutil/trace.go:171","msg":"trace[1732601583] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:618; }","duration":"419.831991ms","start":"2026-05-19T22:19:38.862499Z","end":"2026-05-19T22:19:39.282331Z","steps":["trace[1732601583] 'agreement among raft nodes before linearized reading' (duration: 419.661416ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:19:39.282386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:19:38.862488Z","time spent":"419.892172ms","remote":"127.0.0.1:52174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-19T22:19:39.282404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.212736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-4mcd8\" ","response":"range_response_count:1 size:16965"} {"level":"warn","ts":"2026-05-19T22:19:39.282406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.064063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T22:19:39.282456Z","caller":"traceutil/trace.go:171","msg":"trace[472115553] range","detail":"{range_begin:/registry/pods/kube-system/cilium-4mcd8; range_end:; response_count:1; response_revision:618; }","duration":"316.34066ms","start":"2026-05-19T22:19:38.966099Z","end":"2026-05-19T22:19:39.28244Z","steps":["trace[472115553] 'agreement among raft nodes before linearized reading' (duration: 316.106353ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:19:39.282491Z","caller":"traceutil/trace.go:171","msg":"trace[1831949548] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:618; }","duration":"123.185927ms","start":"2026-05-19T22:19:39.159283Z","end":"2026-05-19T22:19:39.282469Z","steps":["trace[1831949548] 'agreement among raft nodes before linearized reading' (duration: 123.032012ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:19:39.282504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:19:38.966078Z","time spent":"316.415651ms","remote":"127.0.0.1:52084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-4mcd8\" "} {"level":"warn","ts":"2026-05-19T22:19:41.316436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.307007ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-19T22:19:41.316805Z","caller":"traceutil/trace.go:171","msg":"trace[1062139491] transaction","detail":"{read_only:false; response_revision:663; number_of_response:1; }","duration":"100.321802ms","start":"2026-05-19T22:19:41.216467Z","end":"2026-05-19T22:19:41.316789Z","steps":["trace[1062139491] 'process raft request' (duration: 100.127126ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:21:11.415697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.81316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-19T22:21:11.415811Z","caller":"traceutil/trace.go:171","msg":"trace[389726164] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1842; }","duration":"166.968495ms","start":"2026-05-19T22:21:11.248813Z","end":"2026-05-19T22:21:11.415782Z","steps":["trace[389726164] 'range keys from in-memory index tree' (duration: 166.608035ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:21:11.415827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.921204ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-19T22:21:11.416108Z","caller":"traceutil/trace.go:171","msg":"trace[1380030302] linearizableReadLoop","detail":"{readStateIndex:1915; appliedIndex:1914; }","duration":"166.337816ms","start":"2026-05-19T22:21:11.249721Z","end":"2026-05-19T22:21:11.416059Z","steps":["trace[1380030302] 'read index received' (duration: 47.105783ms)","trace[1380030302] 'applied index is now lower than readState.Index' (duration: 119.228883ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T22:21:11.416136Z","caller":"traceutil/trace.go:171","msg":"trace[1269217767] transaction","detail":"{read_only:false; response_revision:1843; number_of_response:1; }","duration":"166.917123ms","start":"2026-05-19T22:21:11.249187Z","end":"2026-05-19T22:21:11.416104Z","steps":["trace[1269217767] 'process raft request' (duration: 47.634479ms)","trace[1269217767] 'compare' (duration: 118.621795ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T22:21:11.416349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.622764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-19T22:21:11.416417Z","caller":"traceutil/trace.go:171","msg":"trace[1135111303] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1843; }","duration":"166.725157ms","start":"2026-05-19T22:21:11.249674Z","end":"2026-05-19T22:21:11.416399Z","steps":["trace[1135111303] 'agreement among raft nodes before linearized reading' (duration: 166.501781ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:21:11.631594Z","caller":"traceutil/trace.go:171","msg":"trace[698467334] linearizableReadLoop","detail":"{readStateIndex:1916; appliedIndex:1915; }","duration":"215.386037ms","start":"2026-05-19T22:21:11.416184Z","end":"2026-05-19T22:21:11.63157Z","steps":["trace[698467334] 'read index received' (duration: 134.119368ms)","trace[698467334] 'applied index is now lower than readState.Index' (duration: 81.265589ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T22:21:11.632103Z","caller":"traceutil/trace.go:171","msg":"trace[2010513619] transaction","detail":"{read_only:false; response_revision:1845; number_of_response:1; }","duration":"211.392592ms","start":"2026-05-19T22:21:11.420689Z","end":"2026-05-19T22:21:11.632082Z","steps":["trace[2010513619] 'process raft request' (duration: 211.238608ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:21:11.632323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.102078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T22:21:11.632866Z","caller":"traceutil/trace.go:171","msg":"trace[165148736] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:1846; }","duration":"243.654723ms","start":"2026-05-19T22:21:11.389198Z","end":"2026-05-19T22:21:11.632852Z","steps":["trace[165148736] 'agreement among raft nodes before linearized reading' (duration: 242.675985ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:21:11.63217Z","caller":"traceutil/trace.go:171","msg":"trace[676287269] transaction","detail":"{read_only:false; response_revision:1844; number_of_response:1; }","duration":"211.694732ms","start":"2026-05-19T22:21:11.420446Z","end":"2026-05-19T22:21:11.632141Z","steps":["trace[676287269] 'process raft request' (duration: 211.020783ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:21:11.632454Z","caller":"traceutil/trace.go:171","msg":"trace[1103828155] transaction","detail":"{read_only:false; response_revision:1847; number_of_response:1; }","duration":"102.623848ms","start":"2026-05-19T22:21:11.52981Z","end":"2026-05-19T22:21:11.632434Z","steps":["trace[1103828155] 'process raft request' (duration: 102.469384ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:21:11.63247Z","caller":"traceutil/trace.go:171","msg":"trace[1954401758] transaction","detail":"{read_only:false; response_revision:1846; number_of_response:1; }","duration":"176.078605ms","start":"2026-05-19T22:21:11.45636Z","end":"2026-05-19T22:21:11.632439Z","steps":["trace[1954401758] 'process raft request' (duration: 175.662524ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:21:11.632549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.704473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-56a35ad2-2232-4ce5-a96b-7ee13762ec34\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-05-19T22:21:11.633236Z","caller":"traceutil/trace.go:171","msg":"trace[1141477432] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-56a35ad2-2232-4ce5-a96b-7ee13762ec34; range_end:; response_count:1; response_revision:1847; }","duration":"197.427503ms","start":"2026-05-19T22:21:11.435791Z","end":"2026-05-19T22:21:11.633218Z","steps":["trace[1141477432] 'agreement among raft nodes before linearized reading' (duration: 196.58221ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:21:11.632654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.665338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-05-19T22:21:11.633412Z","caller":"traceutil/trace.go:171","msg":"trace[640162649] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1847; }","duration":"166.44937ms","start":"2026-05-19T22:21:11.466942Z","end":"2026-05-19T22:21:11.633391Z","steps":["trace[640162649] 'agreement among raft nodes before linearized reading' (duration: 165.595995ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:21:11.959017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.255599ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-19T22:21:11.959118Z","caller":"traceutil/trace.go:171","msg":"trace[1187355470] linearizableReadLoop","detail":"{readStateIndex:1921; appliedIndex:1920; }","duration":"129.154506ms","start":"2026-05-19T22:21:11.829947Z","end":"2026-05-19T22:21:11.959102Z","steps":["trace[1187355470] 'read index received' (duration: 41.292µs)","trace[1187355470] 'applied index is now lower than readState.Index' (duration: 129.112104ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T22:21:11.959527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.409474ms","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-05-19T22:21:11.959607Z","caller":"traceutil/trace.go:171","msg":"trace[1180398546] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1848; }","duration":"101.533348ms","start":"2026-05-19T22:21:11.858055Z","end":"2026-05-19T22:21:11.959588Z","steps":["trace[1180398546] 'agreement among raft nodes before linearized reading' (duration: 101.327222ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:21:11.959631Z","caller":"traceutil/trace.go:171","msg":"trace[667199340] transaction","detail":"{read_only:false; response_revision:1848; number_of_response:1; }","duration":"318.922872ms","start":"2026-05-19T22:21:11.64067Z","end":"2026-05-19T22:21:11.959593Z","steps":["trace[667199340] 'process raft request' (duration: 140.997944ms)","trace[667199340] 'compare' (duration: 177.078084ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T22:21:11.95999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:21:11.640653Z","time spent":"319.201419ms","remote":"127.0.0.1:52084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3732,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T22:21:11.96013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.163264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-56a35ad2-2232-4ce5-a96b-7ee13762ec34\" ","response":"range_response_count:1 size:3747"} {"level":"info","ts":"2026-05-19T22:21:11.960238Z","caller":"traceutil/trace.go:171","msg":"trace[1954431918] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-56a35ad2-2232-4ce5-a96b-7ee13762ec34; range_end:; response_count:1; response_revision:1848; }","duration":"130.311798ms","start":"2026-05-19T22:21:11.82989Z","end":"2026-05-19T22:21:11.960202Z","steps":["trace[1954431918] 'agreement among raft nodes before linearized reading' (duration: 129.449094ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:22:02.071347Z","caller":"traceutil/trace.go:171","msg":"trace[2043542388] linearizableReadLoop","detail":"{readStateIndex:2257; appliedIndex:2256; }","duration":"191.166758ms","start":"2026-05-19T22:22:01.880154Z","end":"2026-05-19T22:22:02.071321Z","steps":["trace[2043542388] 'read index received' (duration: 111.78421ms)","trace[2043542388] 'applied index is now lower than readState.Index' (duration: 79.380998ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T22:22:02.071591Z","caller":"traceutil/trace.go:171","msg":"trace[1301525043] transaction","detail":"{read_only:false; response_revision:2174; number_of_response:1; }","duration":"195.022447ms","start":"2026-05-19T22:22:01.876545Z","end":"2026-05-19T22:22:02.071567Z","steps":["trace[1301525043] 'process raft request' (duration: 115.457774ms)","trace[1301525043] 'compare' (duration: 79.07876ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T22:22:02.071939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.797536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-19T22:22:02.071996Z","caller":"traceutil/trace.go:171","msg":"trace[1353916733] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2174; }","duration":"191.863048ms","start":"2026-05-19T22:22:01.880118Z","end":"2026-05-19T22:22:02.071981Z","steps":["trace[1353916733] 'agreement among raft nodes before linearized reading' (duration: 191.739794ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:22:08.017245Z","caller":"traceutil/trace.go:171","msg":"trace[121120474] transaction","detail":"{read_only:false; response_revision:2206; number_of_response:1; }","duration":"125.83158ms","start":"2026-05-19T22:22:07.891389Z","end":"2026-05-19T22:22:08.017221Z","steps":["trace[121120474] 'process raft request' (duration: 125.578033ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:22:08.148378Z","caller":"traceutil/trace.go:171","msg":"trace[1752942936] transaction","detail":"{read_only:false; response_revision:2207; number_of_response:1; }","duration":"207.551844ms","start":"2026-05-19T22:22:07.940797Z","end":"2026-05-19T22:22:08.148348Z","steps":["trace[1752942936] 'process raft request' (duration: 207.362569ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:23:27.770208Z","caller":"traceutil/trace.go:171","msg":"trace[1106056210] transaction","detail":"{read_only:false; response_revision:2831; number_of_response:1; }","duration":"137.722713ms","start":"2026-05-19T22:23:27.632456Z","end":"2026-05-19T22:23:27.770178Z","steps":["trace[1106056210] 'process raft request' (duration: 64.153301ms)","trace[1106056210] 'compare' (duration: 73.439849ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T22:25:53.445867Z","caller":"traceutil/trace.go:171","msg":"trace[1758022104] transaction","detail":"{read_only:false; response_revision:3802; number_of_response:1; }","duration":"140.140164ms","start":"2026-05-19T22:25:53.30569Z","end":"2026-05-19T22:25:53.44583Z","steps":["trace[1758022104] 'process raft request' (duration: 114.763175ms)","trace[1758022104] 'compare' (duration: 25.127243ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T22:25:53.446039Z","caller":"traceutil/trace.go:171","msg":"trace[1543249199] transaction","detail":"{read_only:false; response_revision:3803; number_of_response:1; }","duration":"140.038531ms","start":"2026-05-19T22:25:53.305964Z","end":"2026-05-19T22:25:53.446003Z","steps":["trace[1543249199] 'process raft request' (duration: 139.719762ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:27:12.476806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.452229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T22:27:12.476941Z","caller":"traceutil/trace.go:171","msg":"trace[82020604] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:4821; }","duration":"102.629054ms","start":"2026-05-19T22:27:12.374287Z","end":"2026-05-19T22:27:12.476916Z","steps":["trace[82020604] 'count revisions from in-memory index tree' (duration: 102.390328ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:27:12.647521Z","caller":"traceutil/trace.go:171","msg":"trace[2139786452] transaction","detail":"{read_only:false; response_revision:4824; number_of_response:1; }","duration":"141.287232ms","start":"2026-05-19T22:27:12.506216Z","end":"2026-05-19T22:27:12.647503Z","steps":["trace[2139786452] 'process raft request' (duration: 141.127218ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:27:12.647729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.542919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1825"} {"level":"info","ts":"2026-05-19T22:27:12.647758Z","caller":"traceutil/trace.go:171","msg":"trace[970113689] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:4824; }","duration":"135.613101ms","start":"2026-05-19T22:27:12.512137Z","end":"2026-05-19T22:27:12.64775Z","steps":["trace[970113689] 'agreement among raft nodes before linearized reading' (duration: 135.504298ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:27:12.648181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.114945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-05-19T22:27:12.648213Z","caller":"traceutil/trace.go:171","msg":"trace[648294698] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4824; }","duration":"136.190837ms","start":"2026-05-19T22:27:12.512011Z","end":"2026-05-19T22:27:12.648202Z","steps":["trace[648294698] 'agreement among raft nodes before linearized reading' (duration: 136.122385ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:27:16.419562Z","caller":"traceutil/trace.go:171","msg":"trace[1116951302] transaction","detail":"{read_only:false; response_revision:4960; number_of_response:1; }","duration":"143.116204ms","start":"2026-05-19T22:27:16.276423Z","end":"2026-05-19T22:27:16.41954Z","steps":["trace[1116951302] 'process raft request' (duration: 142.94603ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:27:16.462692Z","caller":"traceutil/trace.go:171","msg":"trace[150745473] transaction","detail":"{read_only:false; response_revision:4961; number_of_response:1; }","duration":"181.080093ms","start":"2026-05-19T22:27:16.281576Z","end":"2026-05-19T22:27:16.462657Z","steps":["trace[150745473] 'process raft request' (duration: 180.781024ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:27:18.455313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.678557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-19T22:27:18.455405Z","caller":"traceutil/trace.go:171","msg":"trace[1283372003] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:4998; }","duration":"114.874873ms","start":"2026-05-19T22:27:18.34051Z","end":"2026-05-19T22:27:18.455385Z","steps":["trace[1283372003] 'count revisions from in-memory index tree' (duration: 114.579285ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:27:35.307286Z","caller":"traceutil/trace.go:171","msg":"trace[808065193] transaction","detail":"{read_only:false; response_revision:5352; number_of_response:1; }","duration":"117.150067ms","start":"2026-05-19T22:27:35.190111Z","end":"2026-05-19T22:27:35.307261Z","steps":["trace[808065193] 'process raft request' (duration: 116.847398ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:27:35.566402Z","caller":"traceutil/trace.go:171","msg":"trace[236900125] linearizableReadLoop","detail":"{readStateIndex:5550; appliedIndex:5549; }","duration":"159.036936ms","start":"2026-05-19T22:27:35.407336Z","end":"2026-05-19T22:27:35.566373Z","steps":["trace[236900125] 'read index received' (duration: 108.399448ms)","trace[236900125] 'applied index is now lower than readState.Index' (duration: 50.636138ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T22:27:35.566493Z","caller":"traceutil/trace.go:171","msg":"trace[660949141] transaction","detail":"{read_only:false; response_revision:5353; number_of_response:1; }","duration":"217.966279ms","start":"2026-05-19T22:27:35.348487Z","end":"2026-05-19T22:27:35.566454Z","steps":["trace[660949141] 'process raft request' (duration: 167.257669ms)","trace[660949141] 'compare' (duration: 50.524545ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T22:27:35.566789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.386707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-3849592b-873d-4557-b6c8-a5abdb7c408f\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-05-19T22:27:35.566899Z","caller":"traceutil/trace.go:171","msg":"trace[910050137] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-3849592b-873d-4557-b6c8-a5abdb7c408f; range_end:; response_count:1; response_revision:5353; }","duration":"159.602412ms","start":"2026-05-19T22:27:35.407277Z","end":"2026-05-19T22:27:35.56688Z","steps":["trace[910050137] 'agreement among raft nodes before linearized reading' (duration: 159.263713ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:29:02.958464Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3025} {"level":"info","ts":"2026-05-19T22:29:03.037927Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3025,"took":"78.03976ms","hash":1487646553,"current-db-size-bytes":57511936,"current-db-size":"58 MB","current-db-size-in-use-bytes":31866880,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-19T22:29:03.038004Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1487646553,"revision":3025,"compact-revision":-1} {"level":"info","ts":"2026-05-19T22:33:42.062405Z","caller":"traceutil/trace.go:171","msg":"trace[1986009981] transaction","detail":"{read_only:false; response_revision:8190; number_of_response:1; }","duration":"222.139086ms","start":"2026-05-19T22:33:41.84024Z","end":"2026-05-19T22:33:42.062379Z","steps":["trace[1986009981] 'process raft request' (duration: 221.978472ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:33:42.213841Z","caller":"traceutil/trace.go:171","msg":"trace[2130285141] transaction","detail":"{read_only:false; response_revision:8191; number_of_response:1; }","duration":"208.16588ms","start":"2026-05-19T22:33:42.005644Z","end":"2026-05-19T22:33:42.21381Z","steps":["trace[2130285141] 'process raft request' (duration: 207.09558ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:33:52.777458Z","caller":"traceutil/trace.go:171","msg":"trace[1120004967] linearizableReadLoop","detail":"{readStateIndex:8534; appliedIndex:8533; }","duration":"368.524993ms","start":"2026-05-19T22:33:52.408902Z","end":"2026-05-19T22:33:52.777427Z","steps":["trace[1120004967] 'read index received' (duration: 368.09008ms)","trace[1120004967] 'applied index is now lower than readState.Index' (duration: 433.242µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T22:33:52.777506Z","caller":"traceutil/trace.go:171","msg":"trace[814611486] transaction","detail":"{read_only:false; response_revision:8247; number_of_response:1; }","duration":"463.53803ms","start":"2026-05-19T22:33:52.313922Z","end":"2026-05-19T22:33:52.77746Z","steps":["trace[814611486] 'process raft request' (duration: 463.282263ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:33:52.777754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:33:52.313893Z","time spent":"463.752237ms","remote":"127.0.0.1:52174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T22:33:52.777771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.856952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-05-19T22:33:52.777849Z","caller":"traceutil/trace.go:171","msg":"trace[150574956] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8247; }","duration":"368.981556ms","start":"2026-05-19T22:33:52.408847Z","end":"2026-05-19T22:33:52.777829Z","steps":["trace[150574956] 'agreement among raft nodes before linearized reading' (duration: 368.732479ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:33:52.777902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:33:52.408824Z","time spent":"369.070028ms","remote":"127.0.0.1:52084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12239,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-05-19T22:33:52.81435Z","caller":"traceutil/trace.go:171","msg":"trace[836042313] transaction","detail":"{read_only:false; response_revision:8248; number_of_response:1; }","duration":"226.282724ms","start":"2026-05-19T22:33:52.588047Z","end":"2026-05-19T22:33:52.81433Z","steps":["trace[836042313] 'process raft request' (duration: 225.852462ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:33:52.814571Z","caller":"traceutil/trace.go:171","msg":"trace[1917274026] transaction","detail":"{read_only:false; response_revision:8249; number_of_response:1; }","duration":"104.390814ms","start":"2026-05-19T22:33:52.71017Z","end":"2026-05-19T22:33:52.814561Z","steps":["trace[1917274026] 'process raft request' (duration: 103.855029ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:33:52.814915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.373972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-05-19T22:33:52.814952Z","caller":"traceutil/trace.go:171","msg":"trace[999462125] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8249; }","duration":"210.461625ms","start":"2026-05-19T22:33:52.60448Z","end":"2026-05-19T22:33:52.814941Z","steps":["trace[999462125] 'agreement among raft nodes before linearized reading' (duration: 210.345082ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:33:53.770076Z","caller":"traceutil/trace.go:171","msg":"trace[1260964385] transaction","detail":"{read_only:false; response_revision:8252; number_of_response:1; }","duration":"105.595758ms","start":"2026-05-19T22:33:53.664438Z","end":"2026-05-19T22:33:53.770034Z","steps":["trace[1260964385] 'process raft request' (duration: 105.384132ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:34:02.965619Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6137} {"level":"info","ts":"2026-05-19T22:34:03.078665Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6137,"took":"111.173186ms","hash":2039113774,"current-db-size-bytes":57511936,"current-db-size":"58 MB","current-db-size-in-use-bytes":23224320,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-05-19T22:34:03.078781Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2039113774,"revision":6137,"compact-revision":3025} {"level":"info","ts":"2026-05-19T22:34:22.044758Z","caller":"traceutil/trace.go:171","msg":"trace[1280475504] linearizableReadLoop","detail":"{readStateIndex:8752; appliedIndex:8751; }","duration":"149.828813ms","start":"2026-05-19T22:34:21.894912Z","end":"2026-05-19T22:34:22.044741Z","steps":["trace[1280475504] 'read index received' (duration: 149.668809ms)","trace[1280475504] 'applied index is now lower than readState.Index' (duration: 159.584µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T22:34:22.044817Z","caller":"traceutil/trace.go:171","msg":"trace[607690475] transaction","detail":"{read_only:false; response_revision:8456; number_of_response:1; }","duration":"266.710632ms","start":"2026-05-19T22:34:21.77808Z","end":"2026-05-19T22:34:22.04479Z","steps":["trace[607690475] 'process raft request' (duration: 266.544737ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:34:22.044962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.410931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-05-19T22:34:22.04501Z","caller":"traceutil/trace.go:171","msg":"trace[1997382618] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8456; }","duration":"112.490764ms","start":"2026-05-19T22:34:21.932508Z","end":"2026-05-19T22:34:22.044999Z","steps":["trace[1997382618] 'agreement among raft nodes before linearized reading' (duration: 112.37486ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:34:22.044977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.997997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-19T22:34:22.045038Z","caller":"traceutil/trace.go:171","msg":"trace[1735626392] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8456; }","duration":"119.146682ms","start":"2026-05-19T22:34:21.925884Z","end":"2026-05-19T22:34:22.04503Z","steps":["trace[1735626392] 'agreement among raft nodes before linearized reading' (duration: 118.969217ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:34:22.045312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.404941ms","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-05-19T22:34:22.04534Z","caller":"traceutil/trace.go:171","msg":"trace[521455294] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8456; }","duration":"150.478193ms","start":"2026-05-19T22:34:21.894855Z","end":"2026-05-19T22:34:22.045333Z","steps":["trace[521455294] 'agreement among raft nodes before linearized reading' (duration: 149.965048ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:34:22.200425Z","caller":"traceutil/trace.go:171","msg":"trace[806696485] transaction","detail":"{read_only:false; response_revision:8457; number_of_response:1; }","duration":"151.379608ms","start":"2026-05-19T22:34:22.049019Z","end":"2026-05-19T22:34:22.200399Z","steps":["trace[806696485] 'process raft request' (duration: 137.272817ms)","trace[806696485] 'compare' (duration: 13.823193ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T22:34:22.210443Z","caller":"traceutil/trace.go:171","msg":"trace[559019243] transaction","detail":"{read_only:false; response_revision:8458; number_of_response:1; }","duration":"159.073515ms","start":"2026-05-19T22:34:22.05134Z","end":"2026-05-19T22:34:22.210414Z","steps":["trace[559019243] 'process raft request' (duration: 158.898551ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:34:22.210661Z","caller":"traceutil/trace.go:171","msg":"trace[988970315] transaction","detail":"{read_only:false; response_revision:8459; number_of_response:1; }","duration":"159.219331ms","start":"2026-05-19T22:34:22.051411Z","end":"2026-05-19T22:34:22.210631Z","steps":["trace[988970315] 'process raft request' (duration: 158.904161ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:34:22.210723Z","caller":"traceutil/trace.go:171","msg":"trace[295102121] transaction","detail":"{read_only:false; response_revision:8460; number_of_response:1; }","duration":"159.161099ms","start":"2026-05-19T22:34:22.051545Z","end":"2026-05-19T22:34:22.210706Z","steps":["trace[295102121] 'process raft request' (duration: 158.805878ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:34:25.0701Z","caller":"traceutil/trace.go:171","msg":"trace[1687085431] transaction","detail":"{read_only:false; response_revision:8474; number_of_response:1; }","duration":"128.93993ms","start":"2026-05-19T22:34:24.941138Z","end":"2026-05-19T22:34:25.070078Z","steps":["trace[1687085431] 'process raft request' (duration: 128.800807ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:34:25.184184Z","caller":"traceutil/trace.go:171","msg":"trace[1946646825] transaction","detail":"{read_only:false; response_revision:8475; number_of_response:1; }","duration":"242.596128ms","start":"2026-05-19T22:34:24.941574Z","end":"2026-05-19T22:34:25.18417Z","steps":["trace[1946646825] 'process raft request' (duration: 182.062729ms)","trace[1946646825] 'compare' (duration: 60.375035ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T22:34:25.184146Z","caller":"traceutil/trace.go:171","msg":"trace[577748130] linearizableReadLoop","detail":"{readStateIndex:8771; appliedIndex:8769; }","duration":"202.288973ms","start":"2026-05-19T22:34:24.981838Z","end":"2026-05-19T22:34:25.184127Z","steps":["trace[577748130] 'read index received' (duration: 88.034469ms)","trace[577748130] 'applied index is now lower than readState.Index' (duration: 114.253784ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T22:34:25.184423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.575851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-05-19T22:34:25.184489Z","caller":"traceutil/trace.go:171","msg":"trace[1783891756] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8475; }","duration":"202.691314ms","start":"2026-05-19T22:34:24.981781Z","end":"2026-05-19T22:34:25.184473Z","steps":["trace[1783891756] 'agreement among raft nodes before linearized reading' (duration: 202.486139ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:34:25.184558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.648232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19382"} {"level":"info","ts":"2026-05-19T22:34:25.184584Z","caller":"traceutil/trace.go:171","msg":"trace[1952892324] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:8475; }","duration":"196.711754ms","start":"2026-05-19T22:34:24.987866Z","end":"2026-05-19T22:34:25.184578Z","steps":["trace[1952892324] 'agreement among raft nodes before linearized reading' (duration: 196.589301ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:34:34.307631Z","caller":"traceutil/trace.go:171","msg":"trace[220541770] transaction","detail":"{read_only:false; response_revision:8560; number_of_response:1; }","duration":"103.924851ms","start":"2026-05-19T22:34:34.203668Z","end":"2026-05-19T22:34:34.307593Z","steps":["trace[220541770] 'process raft request' (duration: 98.828315ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:34:35.065781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.773873ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-19T22:34:35.065961Z","caller":"traceutil/trace.go:171","msg":"trace[978637730] transaction","detail":"{read_only:false; response_revision:8567; number_of_response:1; }","duration":"363.542ms","start":"2026-05-19T22:34:34.702393Z","end":"2026-05-19T22:34:35.065935Z","steps":["trace[978637730] 'process raft request' (duration: 127.53678ms)","trace[978637730] 'compare' (duration: 235.482746ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T22:34:35.066065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:34:34.702376Z","time spent":"363.639473ms","remote":"127.0.0.1:52084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4635,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T22:34:35.373674Z","caller":"traceutil/trace.go:171","msg":"trace[472053208] transaction","detail":"{read_only:false; response_revision:8568; number_of_response:1; }","duration":"295.934841ms","start":"2026-05-19T22:34:35.077687Z","end":"2026-05-19T22:34:35.373622Z","steps":["trace[472053208] 'process raft request' (duration: 230.284947ms)","trace[472053208] 'compare' (duration: 65.319894ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T22:34:35.406742Z","caller":"traceutil/trace.go:171","msg":"trace[904002812] transaction","detail":"{read_only:false; response_revision:8569; number_of_response:1; }","duration":"326.614532ms","start":"2026-05-19T22:34:35.080106Z","end":"2026-05-19T22:34:35.406721Z","steps":["trace[904002812] 'process raft request' (duration: 326.432637ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:34:35.40717Z","caller":"traceutil/trace.go:171","msg":"trace[884443184] linearizableReadLoop","detail":"{readStateIndex:8870; appliedIndex:8867; }","duration":"247.379564ms","start":"2026-05-19T22:34:35.159759Z","end":"2026-05-19T22:34:35.407138Z","steps":["trace[884443184] 'read index received' (duration: 148.223789ms)","trace[884443184] 'applied index is now lower than readState.Index' (duration: 99.154645ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T22:34:35.407146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:34:35.080024Z","time spent":"326.954493ms","remote":"127.0.0.1:34186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5857,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T22:34:35.407183Z","caller":"traceutil/trace.go:171","msg":"trace[742004060] transaction","detail":"{read_only:false; response_revision:8570; number_of_response:1; }","duration":"296.205459ms","start":"2026-05-19T22:34:35.110948Z","end":"2026-05-19T22:34:35.407154Z","steps":["trace[742004060] 'process raft request' (duration: 295.713875ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:34:35.407229Z","caller":"traceutil/trace.go:171","msg":"trace[38553804] transaction","detail":"{read_only:false; response_revision:8571; number_of_response:1; }","duration":"185.491326ms","start":"2026-05-19T22:34:35.221683Z","end":"2026-05-19T22:34:35.407174Z","steps":["trace[38553804] 'process raft request' (duration: 185.348252ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:34:35.407324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.553738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T22:34:35.407365Z","caller":"traceutil/trace.go:171","msg":"trace[950961317] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8571; }","duration":"247.650611ms","start":"2026-05-19T22:34:35.159703Z","end":"2026-05-19T22:34:35.407353Z","steps":["trace[950961317] 'agreement among raft nodes before linearized reading' (duration: 247.568528ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:34:35.407566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.406996ms","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-05-19T22:34:35.407661Z","caller":"traceutil/trace.go:171","msg":"trace[567567803] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8571; }","duration":"171.54989ms","start":"2026-05-19T22:34:35.236097Z","end":"2026-05-19T22:34:35.407647Z","steps":["trace[567567803] 'agreement among raft nodes before linearized reading' (duration: 171.326134ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:34:36.62472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.644442ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-19T22:34:36.625068Z","caller":"traceutil/trace.go:171","msg":"trace[1873116976] transaction","detail":"{read_only:false; response_revision:8581; number_of_response:1; }","duration":"153.405625ms","start":"2026-05-19T22:34:36.471627Z","end":"2026-05-19T22:34:36.625033Z","steps":["trace[1873116976] 'process raft request' (duration: 24.337251ms)","trace[1873116976] 'compare' (duration: 128.528889ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T22:34:36.625447Z","caller":"traceutil/trace.go:171","msg":"trace[167956809] linearizableReadLoop","detail":"{readStateIndex:8883; appliedIndex:8881; }","duration":"119.202174ms","start":"2026-05-19T22:34:36.50607Z","end":"2026-05-19T22:34:36.625272Z","steps":["trace[167956809] 'read index received' (duration: 15.714256ms)","trace[167956809] 'applied index is now lower than readState.Index' (duration: 103.486898ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T22:34:36.625701Z","caller":"traceutil/trace.go:171","msg":"trace[1847531831] transaction","detail":"{read_only:false; response_revision:8582; number_of_response:1; }","duration":"136.182956ms","start":"2026-05-19T22:34:36.489485Z","end":"2026-05-19T22:34:36.625668Z","steps":["trace[1847531831] 'process raft request' (duration: 135.506297ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:34:36.625787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.704308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:529"} {"level":"info","ts":"2026-05-19T22:34:36.625845Z","caller":"traceutil/trace.go:171","msg":"trace[1527048787] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:8582; }","duration":"119.797291ms","start":"2026-05-19T22:34:36.50603Z","end":"2026-05-19T22:34:36.625827Z","steps":["trace[1527048787] 'agreement among raft nodes before linearized reading' (duration: 119.634266ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:35:24.027128Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000245c.wal"} {"level":"info","ts":"2026-05-19T22:35:51.875443Z","caller":"traceutil/trace.go:171","msg":"trace[1121120797] transaction","detail":"{read_only:false; response_revision:9318; number_of_response:1; }","duration":"112.98756ms","start":"2026-05-19T22:35:51.762427Z","end":"2026-05-19T22:35:51.875415Z","steps":["trace[1121120797] 'process raft request' (duration: 108.610696ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:36:41.167153Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"d716dd02ccafa30","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-19T22:36:41.173017Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-05-19T22:36:41.173105Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-05-19T22:39:02.97191Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8308} {"level":"info","ts":"2026-05-19T22:39:03.044248Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8308,"took":"70.397713ms","hash":3804211055,"current-db-size-bytes":57511936,"current-db-size":"58 MB","current-db-size-in-use-bytes":25464832,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-05-19T22:39:03.044397Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3804211055,"revision":8308,"compact-revision":6137} {"level":"info","ts":"2026-05-19T22:39:27.370629Z","caller":"traceutil/trace.go:171","msg":"trace[531088255] linearizableReadLoop","detail":"{readStateIndex:11494; appliedIndex:11493; }","duration":"213.239174ms","start":"2026-05-19T22:39:27.157371Z","end":"2026-05-19T22:39:27.37061Z","steps":["trace[531088255] 'read index received' (duration: 213.068499ms)","trace[531088255] 'applied index is now lower than readState.Index' (duration: 167.275µs)"],"step_count":2} {"level":"warn","ts":"2026-05-19T22:39:27.370767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.395118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T22:39:27.370786Z","caller":"traceutil/trace.go:171","msg":"trace[1606273804] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:11122; }","duration":"213.470111ms","start":"2026-05-19T22:39:27.157309Z","end":"2026-05-19T22:39:27.370779Z","steps":["trace[1606273804] 'agreement among raft nodes before linearized reading' (duration: 213.372878ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:39:27.370794Z","caller":"traceutil/trace.go:171","msg":"trace[401610332] transaction","detail":"{read_only:false; response_revision:11122; number_of_response:1; }","duration":"328.778213ms","start":"2026-05-19T22:39:27.041979Z","end":"2026-05-19T22:39:27.370757Z","steps":["trace[401610332] 'process raft request' (duration: 328.509196ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:39:27.370944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:39:27.041955Z","time spent":"328.906937ms","remote":"127.0.0.1:52174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T22:39:30.095295Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18028083316047796373,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-19T22:39:30.127761Z","caller":"traceutil/trace.go:171","msg":"trace[1573918459] transaction","detail":"{read_only:false; response_revision:11136; number_of_response:1; }","duration":"746.955756ms","start":"2026-05-19T22:39:29.380781Z","end":"2026-05-19T22:39:30.127737Z","steps":["trace[1573918459] 'process raft request' (duration: 746.770441ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:39:30.127919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:39:29.380771Z","time spent":"747.06553ms","remote":"127.0.0.1:52174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T22:39:30.132386Z","caller":"traceutil/trace.go:171","msg":"trace[1190743403] linearizableReadLoop","detail":"{readStateIndex:11510; appliedIndex:11507; }","duration":"659.164792ms","start":"2026-05-19T22:39:29.4732Z","end":"2026-05-19T22:39:30.132365Z","steps":["trace[1190743403] 'read index received' (duration: 654.344706ms)","trace[1190743403] 'applied index is now lower than readState.Index' (duration: 4.819216ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T22:39:30.1324Z","caller":"traceutil/trace.go:171","msg":"trace[1564482880] transaction","detail":"{read_only:false; response_revision:11137; number_of_response:1; }","duration":"437.095669ms","start":"2026-05-19T22:39:29.695291Z","end":"2026-05-19T22:39:30.132387Z","steps":["trace[1564482880] 'process raft request' (duration: 436.907024ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:39:30.132642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:39:29.695275Z","time spent":"437.317455ms","remote":"127.0.0.1:52174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T22:39:30.132872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"553.645866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-8jb44\" ","response":"range_response_count:1 size:6530"} {"level":"info","ts":"2026-05-19T22:39:30.132906Z","caller":"traceutil/trace.go:171","msg":"trace[649658640] range","detail":"{range_begin:/registry/pods/openstack/keepalived-8jb44; range_end:; response_count:1; response_revision:11138; }","duration":"553.716648ms","start":"2026-05-19T22:39:29.579181Z","end":"2026-05-19T22:39:30.132897Z","steps":["trace[649658640] 'agreement among raft nodes before linearized reading' (duration: 553.557224ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:39:30.132926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:39:29.579164Z","time spent":"553.758069ms","remote":"127.0.0.1:52084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6553,"request content":"key:\"/registry/pods/openstack/keepalived-8jb44\" "} {"level":"warn","ts":"2026-05-19T22:39:30.133498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.937734ms","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-05-19T22:39:30.133536Z","caller":"traceutil/trace.go:171","msg":"trace[1749065485] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-endpoints; range_end:; response_count:0; response_revision:11138; }","duration":"378.002856ms","start":"2026-05-19T22:39:29.755522Z","end":"2026-05-19T22:39:30.133525Z","steps":["trace[1749065485] 'agreement among raft nodes before linearized reading' (duration: 377.936814ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:39:30.133555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:39:29.75551Z","time spent":"378.040328ms","remote":"127.0.0.1:52142","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/cinder-ks-endpoints\" "} {"level":"warn","ts":"2026-05-19T22:39:30.133907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"453.008699ms","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-05-19T22:39:30.133936Z","caller":"traceutil/trace.go:171","msg":"trace[1516917659] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-user; range_end:; response_count:0; response_revision:11138; }","duration":"453.057171ms","start":"2026-05-19T22:39:29.680872Z","end":"2026-05-19T22:39:30.133929Z","steps":["trace[1516917659] 'agreement among raft nodes before linearized reading' (duration: 453.00873ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:39:30.133957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:39:29.680864Z","time spent":"453.087272ms","remote":"127.0.0.1:52142","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/cinder-ks-user\" "} {"level":"warn","ts":"2026-05-19T22:39:30.134049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.695312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-db-sync\" ","response":"range_response_count:1 size:5077"} {"level":"info","ts":"2026-05-19T22:39:30.13407Z","caller":"traceutil/trace.go:171","msg":"trace[1062051396] range","detail":"{range_begin:/registry/jobs/openstack/cinder-db-sync; range_end:; response_count:1; response_revision:11138; }","duration":"451.755555ms","start":"2026-05-19T22:39:29.682307Z","end":"2026-05-19T22:39:30.134063Z","steps":["trace[1062051396] 'agreement among raft nodes before linearized reading' (duration: 451.693673ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:39:30.134089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:39:29.682287Z","time spent":"451.795775ms","remote":"127.0.0.1:52142","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":5100,"request content":"key:\"/registry/jobs/openstack/cinder-db-sync\" "} {"level":"warn","ts":"2026-05-19T22:39:30.134087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"453.232375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-19T22:39:30.134156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"453.244106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-storage-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T22:39:30.134174Z","caller":"traceutil/trace.go:171","msg":"trace[818507081] range","detail":"{range_begin:/registry/jobs/openstack/cinder-storage-init; range_end:; response_count:0; response_revision:11138; }","duration":"453.308828ms","start":"2026-05-19T22:39:29.680859Z","end":"2026-05-19T22:39:30.134167Z","steps":["trace[818507081] 'agreement among raft nodes before linearized reading' (duration: 453.279147ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:39:30.13419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:39:29.680836Z","time spent":"453.350089ms","remote":"127.0.0.1:52142","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/cinder-storage-init\" "} {"level":"warn","ts":"2026-05-19T22:39:30.134246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"453.345899ms","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-05-19T22:39:30.134278Z","caller":"traceutil/trace.go:171","msg":"trace[1451716694] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-endpoints; range_end:; response_count:0; response_revision:11138; }","duration":"453.417051ms","start":"2026-05-19T22:39:29.680855Z","end":"2026-05-19T22:39:30.134272Z","steps":["trace[1451716694] 'agreement among raft nodes before linearized reading' (duration: 453.37334ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:39:30.134297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:39:29.680834Z","time spent":"453.458172ms","remote":"127.0.0.1:52142","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/cinder-ks-endpoints\" "} {"level":"info","ts":"2026-05-19T22:39:30.135098Z","caller":"traceutil/trace.go:171","msg":"trace[117789400] range","detail":"{range_begin:/registry/jobs/openstack/cinder-rabbit-init; range_end:; response_count:0; response_revision:11138; }","duration":"453.257566ms","start":"2026-05-19T22:39:29.680845Z","end":"2026-05-19T22:39:30.134103Z","steps":["trace[117789400] 'agreement among raft nodes before linearized reading' (duration: 453.218665ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:39:30.135146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:39:29.680787Z","time spent":"454.351037ms","remote":"127.0.0.1:52142","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/cinder-rabbit-init\" "} {"level":"warn","ts":"2026-05-19T22:39:30.135224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"662.019953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeaturerules/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeaturerules0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T22:39:30.13528Z","caller":"traceutil/trace.go:171","msg":"trace[515016239] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeaturerules/; range_end:/registry/nfd.k8s-sigs.io/nodefeaturerules0; response_count:0; response_revision:11138; }","duration":"662.122006ms","start":"2026-05-19T22:39:29.473149Z","end":"2026-05-19T22:39:30.135271Z","steps":["trace[515016239] 'agreement among raft nodes before linearized reading' (duration: 660.105168ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:39:30.135307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:39:29.473127Z","time spent":"662.172878ms","remote":"127.0.0.1:41770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":92,"response count":0,"response size":28,"request content":"key:\"/registry/nfd.k8s-sigs.io/nodefeaturerules/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeaturerules0\" count_only:true "} {"level":"warn","ts":"2026-05-19T22:39:30.642314Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18028083316047796378,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-19T22:39:30.790382Z","caller":"traceutil/trace.go:171","msg":"trace[738146482] transaction","detail":"{read_only:false; response_revision:11140; number_of_response:1; }","duration":"652.200296ms","start":"2026-05-19T22:39:30.138154Z","end":"2026-05-19T22:39:30.790355Z","steps":["trace[738146482] 'process raft request' (duration: 649.961733ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:39:30.790542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:39:30.138143Z","time spent":"652.319569ms","remote":"127.0.0.1:52174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T22:39:30.792531Z","caller":"traceutil/trace.go:171","msg":"trace[907765350] linearizableReadLoop","detail":"{readStateIndex:11514; appliedIndex:11511; }","duration":"651.141077ms","start":"2026-05-19T22:39:30.141371Z","end":"2026-05-19T22:39:30.792512Z","steps":["trace[907765350] 'read index received' (duration: 646.759742ms)","trace[907765350] 'applied index is now lower than readState.Index' (duration: 4.380425ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T22:39:30.792762Z","caller":"traceutil/trace.go:171","msg":"trace[1867219147] transaction","detail":"{read_only:false; response_revision:11141; number_of_response:1; }","duration":"274.327243ms","start":"2026-05-19T22:39:30.518426Z","end":"2026-05-19T22:39:30.792754Z","steps":["trace[1867219147] 'process raft request' (duration: 273.898711ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:39:30.792912Z","caller":"traceutil/trace.go:171","msg":"trace[1354006768] transaction","detail":"{read_only:false; response_revision:11142; number_of_response:1; }","duration":"267.607814ms","start":"2026-05-19T22:39:30.525298Z","end":"2026-05-19T22:39:30.792906Z","steps":["trace[1354006768] 'process raft request' (duration: 267.13466ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:39:30.793431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"652.044612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:48 size:447791"} {"level":"warn","ts":"2026-05-19T22:39:30.793446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"629.583316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-19T22:39:30.793495Z","caller":"traceutil/trace.go:171","msg":"trace[2126706350] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:11143; }","duration":"629.658619ms","start":"2026-05-19T22:39:30.163824Z","end":"2026-05-19T22:39:30.793482Z","steps":["trace[2126706350] 'agreement among raft nodes before linearized reading' (duration: 629.539205ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:39:30.793512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"643.797869ms","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-05-19T22:39:30.793464Z","caller":"traceutil/trace.go:171","msg":"trace[2046274149] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:48; response_revision:11143; }","duration":"652.114314ms","start":"2026-05-19T22:39:30.141342Z","end":"2026-05-19T22:39:30.793457Z","steps":["trace[2046274149] 'agreement among raft nodes before linearized reading' (duration: 651.715103ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:39:30.793537Z","caller":"traceutil/trace.go:171","msg":"trace[664581111] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:11143; }","duration":"643.835149ms","start":"2026-05-19T22:39:30.149697Z","end":"2026-05-19T22:39:30.793532Z","steps":["trace[664581111] 'agreement among raft nodes before linearized reading' (duration: 643.543171ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:39:30.793543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:39:30.141332Z","time spent":"652.204316ms","remote":"127.0.0.1:52084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":48,"response size":447814,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"warn","ts":"2026-05-19T22:39:30.794024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:39:30.149689Z","time spent":"643.86365ms","remote":"127.0.0.1:52174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-19T22:39:30.793519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:39:30.163811Z","time spent":"629.70382ms","remote":"127.0.0.1:52174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-19T22:39:36.289315Z","caller":"traceutil/trace.go:171","msg":"trace[1763708061] linearizableReadLoop","detail":"{readStateIndex:11541; appliedIndex:11540; }","duration":"136.495413ms","start":"2026-05-19T22:39:36.15278Z","end":"2026-05-19T22:39:36.289275Z","steps":["trace[1763708061] 'read index received' (duration: 135.66081ms)","trace[1763708061] 'applied index is now lower than readState.Index' (duration: 833.163µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T22:39:36.289463Z","caller":"traceutil/trace.go:171","msg":"trace[370939278] transaction","detail":"{read_only:false; response_revision:11167; number_of_response:1; }","duration":"137.638384ms","start":"2026-05-19T22:39:36.151783Z","end":"2026-05-19T22:39:36.289422Z","steps":["trace[370939278] 'process raft request' (duration: 137.234983ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:39:36.289706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.889994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/cinder-api\" ","response":"range_response_count:1 size:667"} {"level":"info","ts":"2026-05-19T22:39:36.290075Z","caller":"traceutil/trace.go:171","msg":"trace[1978932322] range","detail":"{range_begin:/registry/services/endpoints/openstack/cinder-api; range_end:; response_count:1; response_revision:11167; }","duration":"137.302755ms","start":"2026-05-19T22:39:36.152758Z","end":"2026-05-19T22:39:36.290061Z","steps":["trace[1978932322] 'agreement among raft nodes before linearized reading' (duration: 136.708008ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:39:36.487922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.575543ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-19T22:39:36.48815Z","caller":"traceutil/trace.go:171","msg":"trace[862690724] linearizableReadLoop","detail":"{readStateIndex:11543; appliedIndex:11541; }","duration":"198.26571ms","start":"2026-05-19T22:39:36.289866Z","end":"2026-05-19T22:39:36.488131Z","steps":["trace[862690724] 'read index received' (duration: 48.299136ms)","trace[862690724] 'applied index is now lower than readState.Index' (duration: 149.965594ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T22:39:36.488226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.311719ms","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-05-19T22:39:36.488257Z","caller":"traceutil/trace.go:171","msg":"trace[1401439086] range","detail":"{range_begin:/registry/jobs/openstack/cinder-rabbit-init; range_end:; response_count:0; response_revision:11169; }","duration":"335.370781ms","start":"2026-05-19T22:39:36.152878Z","end":"2026-05-19T22:39:36.488249Z","steps":["trace[1401439086] 'agreement among raft nodes before linearized reading' (duration: 335.312389ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:39:36.488242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.99162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-19T22:39:36.488294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:39:36.152864Z","time spent":"335.421222ms","remote":"127.0.0.1:52142","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/cinder-rabbit-init\" "} {"level":"warn","ts":"2026-05-19T22:39:36.488291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.205826ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-db-sync\" ","response":"range_response_count:1 size:5077"} {"level":"info","ts":"2026-05-19T22:39:36.488312Z","caller":"traceutil/trace.go:171","msg":"trace[402217478] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-user; range_end:; response_count:0; response_revision:11169; }","duration":"335.072252ms","start":"2026-05-19T22:39:36.153219Z","end":"2026-05-19T22:39:36.488291Z","steps":["trace[402217478] 'agreement among raft nodes before linearized reading' (duration: 334.975179ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:39:36.488345Z","caller":"traceutil/trace.go:171","msg":"trace[1256466615] range","detail":"{range_begin:/registry/jobs/openstack/cinder-db-sync; range_end:; response_count:1; response_revision:11169; }","duration":"335.280808ms","start":"2026-05-19T22:39:36.15305Z","end":"2026-05-19T22:39:36.488331Z","steps":["trace[1256466615] 'agreement among raft nodes before linearized reading' (duration: 335.100533ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:39:36.488356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:39:36.15321Z","time spent":"335.137414ms","remote":"127.0.0.1:52142","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/cinder-ks-user\" "} {"level":"warn","ts":"2026-05-19T22:39:36.488387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:39:36.153042Z","time spent":"335.337819ms","remote":"127.0.0.1:52142","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":5100,"request content":"key:\"/registry/jobs/openstack/cinder-db-sync\" "} {"level":"info","ts":"2026-05-19T22:39:36.48841Z","caller":"traceutil/trace.go:171","msg":"trace[183832071] transaction","detail":"{read_only:false; response_revision:11169; number_of_response:1; }","duration":"332.952162ms","start":"2026-05-19T22:39:36.155441Z","end":"2026-05-19T22:39:36.488393Z","steps":["trace[183832071] 'process raft request' (duration: 332.584811ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:39:36.48824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.847686ms","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-05-19T22:39:36.488482Z","caller":"traceutil/trace.go:171","msg":"trace[238098626] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-endpoints; range_end:; response_count:0; response_revision:11169; }","duration":"335.120904ms","start":"2026-05-19T22:39:36.153352Z","end":"2026-05-19T22:39:36.488473Z","steps":["trace[238098626] 'agreement among raft nodes before linearized reading' (duration: 334.795655ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:39:36.488511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:39:36.15543Z","time spent":"333.011314ms","remote":"127.0.0.1:52174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T22:39:36.488528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.765068ms","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-05-19T22:39:36.488545Z","caller":"traceutil/trace.go:171","msg":"trace[280830293] transaction","detail":"{read_only:false; response_revision:11168; number_of_response:1; }","duration":"335.35228ms","start":"2026-05-19T22:39:36.15294Z","end":"2026-05-19T22:39:36.488293Z","steps":["trace[280830293] 'process raft request' (duration: 185.303193ms)","trace[280830293] 'compare' (duration: 149.273035ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T22:39:36.488554Z","caller":"traceutil/trace.go:171","msg":"trace[1838931118] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:11169; }","duration":"331.805799ms","start":"2026-05-19T22:39:36.156741Z","end":"2026-05-19T22:39:36.488546Z","steps":["trace[1838931118] 'agreement among raft nodes before linearized reading' (duration: 331.729347ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:39:36.488521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.496324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-storage-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-19T22:39:36.488517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:39:36.153347Z","time spent":"335.163045ms","remote":"127.0.0.1:52142","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/cinder-ks-endpoints\" "} {"level":"warn","ts":"2026-05-19T22:39:36.488614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:39:36.15292Z","time spent":"335.654629ms","remote":"127.0.0.1:52174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T22:39:36.488654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.71454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-db-sync\" ","response":"range_response_count:1 size:5077"} {"level":"info","ts":"2026-05-19T22:39:36.488667Z","caller":"traceutil/trace.go:171","msg":"trace[1715607372] range","detail":"{range_begin:/registry/jobs/openstack/cinder-storage-init; range_end:; response_count:0; response_revision:11169; }","duration":"335.692879ms","start":"2026-05-19T22:39:36.152941Z","end":"2026-05-19T22:39:36.488633Z","steps":["trace[1715607372] 'agreement among raft nodes before linearized reading' (duration: 335.276958ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:39:36.488699Z","caller":"traceutil/trace.go:171","msg":"trace[686735091] range","detail":"{range_begin:/registry/jobs/openstack/cinder-db-sync; range_end:; response_count:1; response_revision:11169; }","duration":"335.784682ms","start":"2026-05-19T22:39:36.152904Z","end":"2026-05-19T22:39:36.488688Z","steps":["trace[686735091] 'agreement among raft nodes before linearized reading' (duration: 335.631098ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:39:36.488731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:39:36.15289Z","time spent":"335.834443ms","remote":"127.0.0.1:52142","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":5100,"request content":"key:\"/registry/jobs/openstack/cinder-db-sync\" "} {"level":"warn","ts":"2026-05-19T22:39:36.488729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:39:36.152925Z","time spent":"335.792812ms","remote":"127.0.0.1:52142","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/cinder-storage-init\" "} {"level":"warn","ts":"2026-05-19T22:39:36.488584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T22:39:36.15673Z","time spent":"331.84848ms","remote":"127.0.0.1:52174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-19T22:39:36.709294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.647107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/cinder-backup-7487cc489-tmbb4.18b11892690e27f3\" ","response":"range_response_count:1 size:801"} {"level":"info","ts":"2026-05-19T22:39:36.709395Z","caller":"traceutil/trace.go:171","msg":"trace[652821612] range","detail":"{range_begin:/registry/events/openstack/cinder-backup-7487cc489-tmbb4.18b11892690e27f3; range_end:; response_count:1; response_revision:11170; }","duration":"119.818461ms","start":"2026-05-19T22:39:36.589554Z","end":"2026-05-19T22:39:36.709373Z","steps":["trace[652821612] 'range keys from in-memory index tree' (duration: 119.42245ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:39:37.628219Z","caller":"traceutil/trace.go:171","msg":"trace[623068190] transaction","detail":"{read_only:false; response_revision:11180; number_of_response:1; }","duration":"172.854722ms","start":"2026-05-19T22:39:37.455336Z","end":"2026-05-19T22:39:37.62819Z","steps":["trace[623068190] 'process raft request' (duration: 172.608335ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:44:02.980195Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10801} {"level":"info","ts":"2026-05-19T22:44:03.06486Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10801,"took":"80.595916ms","hash":1156148170,"current-db-size-bytes":57511936,"current-db-size":"58 MB","current-db-size-in-use-bytes":28012544,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-05-19T22:44:03.064977Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1156148170,"revision":10801,"compact-revision":8308} {"level":"info","ts":"2026-05-19T22:45:52.10456Z","caller":"traceutil/trace.go:171","msg":"trace[736412041] transaction","detail":"{read_only:false; response_revision:13701; number_of_response:1; }","duration":"110.270952ms","start":"2026-05-19T22:45:51.994256Z","end":"2026-05-19T22:45:52.104527Z","steps":["trace[736412041] 'process raft request' (duration: 110.073867ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:46:08.941797Z","caller":"traceutil/trace.go:171","msg":"trace[1090517339] linearizableReadLoop","detail":"{readStateIndex:14273; appliedIndex:14272; }","duration":"170.992782ms","start":"2026-05-19T22:46:08.770775Z","end":"2026-05-19T22:46:08.941768Z","steps":["trace[1090517339] 'read index received' (duration: 170.755116ms)","trace[1090517339] 'applied index is now lower than readState.Index' (duration: 236.906µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T22:46:08.941867Z","caller":"traceutil/trace.go:171","msg":"trace[2079980197] transaction","detail":"{read_only:false; response_revision:13807; number_of_response:1; }","duration":"242.349603ms","start":"2026-05-19T22:46:08.699491Z","end":"2026-05-19T22:46:08.941841Z","steps":["trace[2079980197] 'process raft request' (duration: 242.055614ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:46:08.942101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.616273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-19T22:46:08.942238Z","caller":"traceutil/trace.go:171","msg":"trace[737688570] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:13807; }","duration":"169.818009ms","start":"2026-05-19T22:46:08.772405Z","end":"2026-05-19T22:46:08.942223Z","steps":["trace[737688570] 'agreement among raft nodes before linearized reading' (duration: 169.527201ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:46:08.942133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.76874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-19T22:46:08.94241Z","caller":"traceutil/trace.go:171","msg":"trace[529723290] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:13807; }","duration":"169.044527ms","start":"2026-05-19T22:46:08.773331Z","end":"2026-05-19T22:46:08.942376Z","steps":["trace[529723290] 'agreement among raft nodes before linearized reading' (duration: 168.711318ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:46:08.942125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.335243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-05-19T22:46:08.942553Z","caller":"traceutil/trace.go:171","msg":"trace[695480766] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:13807; }","duration":"171.815906ms","start":"2026-05-19T22:46:08.770706Z","end":"2026-05-19T22:46:08.942522Z","steps":["trace[695480766] 'agreement among raft nodes before linearized reading' (duration: 171.199449ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:46:09.139426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.634179ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T22:46:09.139605Z","caller":"traceutil/trace.go:171","msg":"trace[1614107184] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:13807; }","duration":"113.837375ms","start":"2026-05-19T22:46:09.025741Z","end":"2026-05-19T22:46:09.139578Z","steps":["trace[1614107184] 'range keys from in-memory index tree' (duration: 113.607828ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:46:09.139617Z","caller":"traceutil/trace.go:171","msg":"trace[604184568] transaction","detail":"{read_only:false; response_revision:13808; number_of_response:1; }","duration":"192.827521ms","start":"2026-05-19T22:46:08.946763Z","end":"2026-05-19T22:46:09.139591Z","steps":["trace[604184568] 'process raft request' (duration: 173.769021ms)","trace[604184568] 'compare' (duration: 18.780092ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T22:46:09.153957Z","caller":"traceutil/trace.go:171","msg":"trace[2096155026] transaction","detail":"{read_only:false; response_revision:13810; number_of_response:1; }","duration":"206.800426ms","start":"2026-05-19T22:46:08.947144Z","end":"2026-05-19T22:46:09.153945Z","steps":["trace[2096155026] 'process raft request' (duration: 206.766335ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:46:09.154017Z","caller":"traceutil/trace.go:171","msg":"trace[1057764476] transaction","detail":"{read_only:false; response_revision:13809; number_of_response:1; }","duration":"206.981581ms","start":"2026-05-19T22:46:08.946994Z","end":"2026-05-19T22:46:09.153975Z","steps":["trace[1057764476] 'process raft request' (duration: 206.850527ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:46:10.358334Z","caller":"traceutil/trace.go:171","msg":"trace[849653641] transaction","detail":"{read_only:false; response_revision:13821; number_of_response:1; }","duration":"100.593269ms","start":"2026-05-19T22:46:10.257718Z","end":"2026-05-19T22:46:10.358312Z","steps":["trace[849653641] 'process raft request' (duration: 100.426174ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:47:55.836278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.407481ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-19T22:47:55.836382Z","caller":"traceutil/trace.go:171","msg":"trace[1613706244] linearizableReadLoop","detail":"{readStateIndex:15421; appliedIndex:15420; }","duration":"164.762885ms","start":"2026-05-19T22:47:55.671604Z","end":"2026-05-19T22:47:55.836367Z","steps":["trace[1613706244] 'read index received' (duration: 4.151837ms)","trace[1613706244] 'applied index is now lower than readState.Index' (duration: 160.609988ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T22:47:55.836431Z","caller":"traceutil/trace.go:171","msg":"trace[423199454] transaction","detail":"{read_only:false; response_revision:14919; number_of_response:1; }","duration":"165.613889ms","start":"2026-05-19T22:47:55.670776Z","end":"2026-05-19T22:47:55.83639Z","steps":["trace[423199454] 'process raft request' (duration: 16.016912ms)","trace[423199454] 'compare' (duration: 148.938718ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T22:47:55.836552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.94179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-05-19T22:47:55.836584Z","caller":"traceutil/trace.go:171","msg":"trace[2007274428] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:14919; }","duration":"164.991102ms","start":"2026-05-19T22:47:55.671583Z","end":"2026-05-19T22:47:55.836574Z","steps":["trace[2007274428] 'agreement among raft nodes before linearized reading' (duration: 164.868678ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:47:55.836813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.433078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"info","ts":"2026-05-19T22:47:55.836913Z","caller":"traceutil/trace.go:171","msg":"trace[555852453] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:14919; }","duration":"122.676436ms","start":"2026-05-19T22:47:55.714221Z","end":"2026-05-19T22:47:55.836897Z","steps":["trace[555852453] 'agreement among raft nodes before linearized reading' (duration: 122.300325ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T22:47:56.074529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.469011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/topicpermissions/\" range_end:\"/registry/rabbitmq.com/topicpermissions0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T22:47:56.075243Z","caller":"traceutil/trace.go:171","msg":"trace[473919513] range","detail":"{range_begin:/registry/rabbitmq.com/topicpermissions/; range_end:/registry/rabbitmq.com/topicpermissions0; response_count:0; response_revision:14920; }","duration":"128.199662ms","start":"2026-05-19T22:47:55.946986Z","end":"2026-05-19T22:47:56.075185Z","steps":["trace[473919513] 'count revisions from in-memory index tree' (duration: 127.408859ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T22:49:02.986592Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12998} {"level":"info","ts":"2026-05-19T22:49:03.060776Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12998,"took":"71.798409ms","hash":577130065,"current-db-size-bytes":57511936,"current-db-size":"58 MB","current-db-size-in-use-bytes":30683136,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-19T22:49:03.060853Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":577130065,"revision":12998,"compact-revision":10801} {"level":"info","ts":"2026-05-19T22:54:02.99271Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15361} {"level":"info","ts":"2026-05-19T22:54:03.082389Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15361,"took":"86.689063ms","hash":2579896825,"current-db-size-bytes":57511936,"current-db-size":"58 MB","current-db-size-in-use-bytes":31408128,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-19T22:54:03.082462Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2579896825,"revision":15361,"compact-revision":12998} {"level":"info","ts":"2026-05-19T22:59:02.998325Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17585} {"level":"info","ts":"2026-05-19T22:59:03.083837Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17585,"took":"82.976369ms","hash":1426859139,"current-db-size-bytes":57511936,"current-db-size":"58 MB","current-db-size-in-use-bytes":25927680,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-05-19T22:59:03.083888Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1426859139,"revision":17585,"compact-revision":15361} {"level":"info","ts":"2026-05-19T22:59:40.473766Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"d716dd02ccafa30","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-19T22:59:40.478359Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-05-19T22:59:40.478475Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002}