{"level":"warn","ts":"2026-05-19T00:06:33.161284Z","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-19T00:06:33.161393Z","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-19T00:06:33.161474Z","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-19T00:06:33.161495Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.100:2380"]} {"level":"info","ts":"2026-05-19T00:06:33.161522Z","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-19T00:06:33.162232Z","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-19T00:06:33.162307Z","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-19T00:06:33.184177Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"21.694197ms"} {"level":"info","ts":"2026-05-19T00:06:33.232182Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d716dd02ccafa30","cluster-id":"c093465db09ae4fe"} {"level":"info","ts":"2026-05-19T00:06:33.232286Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d716dd02ccafa30 switched to configuration voters=()"} {"level":"info","ts":"2026-05-19T00:06:33.232308Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d716dd02ccafa30 became follower at term 0"} {"level":"info","ts":"2026-05-19T00:06:33.232323Z","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-19T00:06:33.232347Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d716dd02ccafa30 became follower at term 1"} {"level":"info","ts":"2026-05-19T00:06:33.232384Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d716dd02ccafa30 switched to configuration voters=(968676135733492272)"} {"level":"warn","ts":"2026-05-19T00:06:33.274343Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-19T00:06:33.278309Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-19T00:06:33.282206Z","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-19T00:06:33.287416Z","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-19T00:06:33.288478Z","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-19T00:06:33.288808Z","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-19T00:06:33.29016Z","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-19T00:06:33.290226Z","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-19T00:06:33.29022Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d716dd02ccafa30 switched to configuration voters=(968676135733492272)"} {"level":"info","ts":"2026-05-19T00:06:33.290788Z","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-19T00:06:33.291987Z","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-19T00:06:33.292117Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.100:2380"} {"level":"info","ts":"2026-05-19T00:06:33.29216Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.100:2380"} {"level":"info","ts":"2026-05-19T00:06:33.29231Z","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-19T00:06:33.292363Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-19T00:06:33.533614Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d716dd02ccafa30 is starting a new election at term 1"} {"level":"info","ts":"2026-05-19T00:06:33.53367Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d716dd02ccafa30 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-19T00:06:33.533693Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d716dd02ccafa30 received MsgPreVoteResp from d716dd02ccafa30 at term 1"} {"level":"info","ts":"2026-05-19T00:06:33.533712Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d716dd02ccafa30 became candidate at term 2"} {"level":"info","ts":"2026-05-19T00:06:33.533724Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d716dd02ccafa30 received MsgVoteResp from d716dd02ccafa30 at term 2"} {"level":"info","ts":"2026-05-19T00:06:33.533746Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d716dd02ccafa30 became leader at term 2"} {"level":"info","ts":"2026-05-19T00:06:33.53376Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d716dd02ccafa30 elected leader d716dd02ccafa30 at term 2"} {"level":"info","ts":"2026-05-19T00:06:33.539605Z","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-19T00:06:33.539617Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-19T00:06:33.539636Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-19T00:06:33.539652Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T00:06:33.539984Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-19T00:06:33.540129Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-19T00:06:33.540826Z","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-19T00:06:33.540978Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T00:06:33.541033Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-19T00:06:33.542352Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.100:2379"} {"level":"info","ts":"2026-05-19T00:06:33.544157Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-05-19T00:06:48.648711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.406781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T00:06:48.648898Z","caller":"traceutil/trace.go:171","msg":"trace[1803978578] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:366; }","duration":"183.633527ms","start":"2026-05-19T00:06:48.465236Z","end":"2026-05-19T00:06:48.648869Z","steps":["trace[1803978578] 'range keys from in-memory index tree' (duration: 183.308809ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:06:49.210831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.60598ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-19T00:06:49.210964Z","caller":"traceutil/trace.go:171","msg":"trace[126380966] linearizableReadLoop","detail":"{readStateIndex:386; appliedIndex:385; }","duration":"212.912148ms","start":"2026-05-19T00:06:48.998035Z","end":"2026-05-19T00:06:49.210947Z","steps":["trace[126380966] 'read index received' (duration: 87.398586ms)","trace[126380966] 'applied index is now lower than readState.Index' (duration: 125.512222ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T00:06:49.211009Z","caller":"traceutil/trace.go:171","msg":"trace[431325956] transaction","detail":"{read_only:false; response_revision:368; number_of_response:1; }","duration":"375.917653ms","start":"2026-05-19T00:06:48.835054Z","end":"2026-05-19T00:06:49.210972Z","steps":["trace[431325956] 'process raft request' (duration: 250.432721ms)","trace[431325956] 'compare' (duration: 124.500598ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T00:06:49.211079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.059442ms","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-19T00:06:49.211109Z","caller":"traceutil/trace.go:171","msg":"trace[2002111575] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:368; }","duration":"213.111773ms","start":"2026-05-19T00:06:48.997987Z","end":"2026-05-19T00:06:49.211099Z","steps":["trace[2002111575] 'agreement among raft nodes before linearized reading' (duration: 213.049641ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:06:49.211133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T00:06:48.835032Z","time spent":"376.038016ms","remote":"127.0.0.1:52314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T00:06:49.211204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.863058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4379"} {"level":"info","ts":"2026-05-19T00:06:49.211255Z","caller":"traceutil/trace.go:171","msg":"trace[672377009] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:368; }","duration":"150.95387ms","start":"2026-05-19T00:06:49.060291Z","end":"2026-05-19T00:06:49.211245Z","steps":["trace[672377009] 'agreement among raft nodes before linearized reading' (duration: 150.839637ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:06:49.211309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.229993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-05-19T00:06:49.211383Z","caller":"traceutil/trace.go:171","msg":"trace[132094430] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:368; }","duration":"106.357827ms","start":"2026-05-19T00:06:49.105008Z","end":"2026-05-19T00:06:49.211365Z","steps":["trace[132094430] 'agreement among raft nodes before linearized reading' (duration: 106.169622ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:07:09.474628Z","caller":"traceutil/trace.go:171","msg":"trace[1190575404] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"155.455472ms","start":"2026-05-19T00:07:09.319146Z","end":"2026-05-19T00:07:09.474601Z","steps":["trace[1190575404] 'process raft request' (duration: 155.153515ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:07:09.683789Z","caller":"traceutil/trace.go:171","msg":"trace[149768625] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"184.829435ms","start":"2026-05-19T00:07:09.498942Z","end":"2026-05-19T00:07:09.683771Z","steps":["trace[149768625] 'process raft request' (duration: 176.456472ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:07:10.027085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.198656ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-19T00:07:10.027153Z","caller":"traceutil/trace.go:171","msg":"trace[244626191] linearizableReadLoop","detail":"{readStateIndex:602; appliedIndex:600; }","duration":"353.188169ms","start":"2026-05-19T00:07:09.673953Z","end":"2026-05-19T00:07:10.027142Z","steps":["trace[244626191] 'read index received' (duration: 1.494356ms)","trace[244626191] 'applied index is now lower than readState.Index' (duration: 351.693123ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T00:07:10.027233Z","caller":"traceutil/trace.go:171","msg":"trace[1094785140] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"372.580539ms","start":"2026-05-19T00:07:09.654623Z","end":"2026-05-19T00:07:10.027203Z","steps":["trace[1094785140] 'process raft request' (duration: 158.203699ms)","trace[1094785140] 'compare' (duration: 213.901699ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T00:07:10.027298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.339732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-kqtt8\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-05-19T00:07:10.027316Z","caller":"traceutil/trace.go:171","msg":"trace[2031025472] range","detail":"{range_begin:/registry/pods/kube-system/cilium-kqtt8; range_end:; response_count:1; response_revision:578; }","duration":"353.392064ms","start":"2026-05-19T00:07:09.673919Z","end":"2026-05-19T00:07:10.027311Z","steps":["trace[2031025472] 'agreement among raft nodes before linearized reading' (duration: 353.24905ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:07:10.027346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T00:07:09.654599Z","time spent":"372.690902ms","remote":"127.0.0.1:52144","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-19T00:07:10.027376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T00:07:09.673903Z","time spent":"353.467265ms","remote":"127.0.0.1:52248","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-kqtt8\" "} {"level":"info","ts":"2026-05-19T00:07:10.161338Z","caller":"traceutil/trace.go:171","msg":"trace[2065011955] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"116.666631ms","start":"2026-05-19T00:07:10.044648Z","end":"2026-05-19T00:07:10.161314Z","steps":["trace[2065011955] 'process raft request' (duration: 116.357084ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:08:41.495272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.286785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T00:08:41.495374Z","caller":"traceutil/trace.go:171","msg":"trace[446302679] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1811; }","duration":"128.451118ms","start":"2026-05-19T00:08:41.366903Z","end":"2026-05-19T00:08:41.495354Z","steps":["trace[446302679] 'range keys from in-memory index tree' (duration: 128.174531ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:08:41.996091Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18028083295580855936,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-19T00:08:42.057676Z","caller":"traceutil/trace.go:171","msg":"trace[883263278] transaction","detail":"{read_only:false; response_revision:1813; number_of_response:1; }","duration":"569.256888ms","start":"2026-05-19T00:08:41.488303Z","end":"2026-05-19T00:08:42.05756Z","steps":["trace[883263278] 'process raft request' (duration: 567.67513ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:08:42.057904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T00:08:41.488268Z","time spent":"569.507294ms","remote":"127.0.0.1:52314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T00:08:42.112756Z","caller":"traceutil/trace.go:171","msg":"trace[2142830192] transaction","detail":"{read_only:false; response_revision:1814; number_of_response:1; }","duration":"344.505739ms","start":"2026-05-19T00:08:41.768218Z","end":"2026-05-19T00:08:42.112724Z","steps":["trace[2142830192] 'process raft request' (duration: 344.224161ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:08:42.112911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T00:08:41.768195Z","time spent":"344.632811ms","remote":"127.0.0.1:52314","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-19T00:08:42.198787Z","caller":"traceutil/trace.go:171","msg":"trace[88550413] linearizableReadLoop","detail":"{readStateIndex:1888; appliedIndex:1885; }","duration":"703.2572ms","start":"2026-05-19T00:08:41.495498Z","end":"2026-05-19T00:08:42.198756Z","steps":["trace[88550413] 'read index received' (duration: 560.451116ms)","trace[88550413] 'applied index is now lower than readState.Index' (duration: 142.804624ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T00:08:42.228334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"740.900519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-19T00:08:42.228432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.503276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-58e8559c-407b-46f3-b885-6ac79adafd34\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-05-19T00:08:42.228452Z","caller":"traceutil/trace.go:171","msg":"trace[1558009999] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1815; }","duration":"741.080553ms","start":"2026-05-19T00:08:41.487343Z","end":"2026-05-19T00:08:42.228424Z","steps":["trace[1558009999] 'agreement among raft nodes before linearized reading' (duration: 711.424427ms)","trace[1558009999] 'range keys from in-memory index tree' (duration: 29.295047ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T00:08:42.22846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.723358ms","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-19T00:08:42.228488Z","caller":"traceutil/trace.go:171","msg":"trace[1383190994] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-58e8559c-407b-46f3-b885-6ac79adafd34; range_end:; response_count:1; response_revision:1815; }","duration":"195.659889ms","start":"2026-05-19T00:08:42.032811Z","end":"2026-05-19T00:08:42.228471Z","steps":["trace[1383190994] 'agreement among raft nodes before linearized reading' (duration: 166.159217ms)","trace[1383190994] 'range keys from in-memory index tree' (duration: 29.350359ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T00:08:42.228515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T00:08:41.487319Z","time spent":"741.181986ms","remote":"127.0.0.1:52314","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-19T00:08:42.228616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.876044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-19T00:08:42.228689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.095833ms","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-19T00:08:42.228726Z","caller":"traceutil/trace.go:171","msg":"trace[1390269372] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1815; }","duration":"209.851581ms","start":"2026-05-19T00:08:42.018666Z","end":"2026-05-19T00:08:42.228517Z","steps":["trace[1390269372] 'agreement among raft nodes before linearized reading' (duration: 180.275707ms)","trace[1390269372] 'range keys from in-memory index tree' (duration: 29.271607ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T00:08:42.228729Z","caller":"traceutil/trace.go:171","msg":"trace[108230426] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1815; }","duration":"264.064109ms","start":"2026-05-19T00:08:41.964631Z","end":"2026-05-19T00:08:42.228695Z","steps":["trace[108230426] 'agreement among raft nodes before linearized reading' (duration: 234.337391ms)","trace[108230426] 'count revisions from in-memory index tree' (duration: 29.537203ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T00:08:42.228756Z","caller":"traceutil/trace.go:171","msg":"trace[9867453] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1815; }","duration":"374.207646ms","start":"2026-05-19T00:08:41.854531Z","end":"2026-05-19T00:08:42.228738Z","steps":["trace[9867453] 'agreement among raft nodes before linearized reading' (duration: 344.401546ms)","trace[9867453] 'range keys from in-memory index tree' (duration: 29.584834ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T00:08:42.228729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"763.548335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-19T00:08:42.228619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.538054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-05-19T00:08:42.228805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T00:08:41.854514Z","time spent":"374.281257ms","remote":"127.0.0.1:52314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-19T00:08:42.228837Z","caller":"traceutil/trace.go:171","msg":"trace[1216752078] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1815; }","duration":"763.710709ms","start":"2026-05-19T00:08:41.465094Z","end":"2026-05-19T00:08:42.228805Z","steps":["trace[1216752078] 'agreement among raft nodes before linearized reading' (duration: 733.762856ms)","trace[1216752078] 'range keys from in-memory index tree' (duration: 29.80495ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T00:08:42.228892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T00:08:41.46507Z","time spent":"763.811581ms","remote":"127.0.0.1:52050","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-19T00:08:42.228845Z","caller":"traceutil/trace.go:171","msg":"trace[821051494] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1815; }","duration":"209.843552ms","start":"2026-05-19T00:08:42.018976Z","end":"2026-05-19T00:08:42.22882Z","steps":["trace[821051494] 'agreement among raft nodes before linearized reading' (duration: 179.96988ms)","trace[821051494] 'range keys from in-memory index tree' (duration: 29.442521ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T00:08:42.617544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.02913ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-19T00:08:42.617875Z","caller":"traceutil/trace.go:171","msg":"trace[819170347] transaction","detail":"{read_only:false; response_revision:1817; number_of_response:1; }","duration":"384.307818ms","start":"2026-05-19T00:08:42.233541Z","end":"2026-05-19T00:08:42.617848Z","steps":["trace[819170347] 'process raft request' (duration: 116.848467ms)","trace[819170347] 'compare' (duration: 266.825865ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T00:08:42.618147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T00:08:42.233512Z","time spent":"384.487282ms","remote":"127.0.0.1:52314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T00:08:42.618253Z","caller":"traceutil/trace.go:171","msg":"trace[1176123706] transaction","detail":"{read_only:false; response_revision:1818; number_of_response:1; }","duration":"384.36937ms","start":"2026-05-19T00:08:42.233863Z","end":"2026-05-19T00:08:42.618233Z","steps":["trace[1176123706] 'process raft request' (duration: 383.810036ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:08:42.618356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T00:08:42.233828Z","time spent":"384.483553ms","remote":"127.0.0.1:52314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T00:08:42.618697Z","caller":"traceutil/trace.go:171","msg":"trace[1737044627] transaction","detail":"{read_only:false; response_revision:1819; number_of_response:1; }","duration":"383.162911ms","start":"2026-05-19T00:08:42.2355Z","end":"2026-05-19T00:08:42.618663Z","steps":["trace[1737044627] 'process raft request' (duration: 382.661489ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:08:42.618763Z","caller":"traceutil/trace.go:171","msg":"trace[1873049396] linearizableReadLoop","detail":"{readStateIndex:1894; appliedIndex:1889; }","duration":"364.123412ms","start":"2026-05-19T00:08:42.25462Z","end":"2026-05-19T00:08:42.618743Z","steps":["trace[1873049396] 'read index received' (duration: 6.891427ms)","trace[1873049396] 'applied index is now lower than readState.Index' (duration: 357.230765ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T00:08:42.618866Z","caller":"traceutil/trace.go:171","msg":"trace[1799571451] transaction","detail":"{read_only:false; response_revision:1821; number_of_response:1; }","duration":"210.060546ms","start":"2026-05-19T00:08:42.408788Z","end":"2026-05-19T00:08:42.618849Z","steps":["trace[1799571451] 'process raft request' (duration: 209.909223ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:08:42.618964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T00:08:42.235471Z","time spent":"383.331605ms","remote":"127.0.0.1:52314","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-19T00:08:42.619104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.522032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T00:08:42.619172Z","caller":"traceutil/trace.go:171","msg":"trace[17745771] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1821; }","duration":"153.640786ms","start":"2026-05-19T00:08:42.465519Z","end":"2026-05-19T00:08:42.61916Z","steps":["trace[17745771] 'agreement among raft nodes before linearized reading' (duration: 153.496082ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:08:42.61915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.497921ms","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-19T00:08:42.619305Z","caller":"traceutil/trace.go:171","msg":"trace[1294724073] transaction","detail":"{read_only:false; response_revision:1820; number_of_response:1; }","duration":"383.722855ms","start":"2026-05-19T00:08:42.23555Z","end":"2026-05-19T00:08:42.619273Z","steps":["trace[1294724073] 'process raft request' (duration: 383.031088ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:08:42.619378Z","caller":"traceutil/trace.go:171","msg":"trace[1793489953] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1821; }","duration":"364.739607ms","start":"2026-05-19T00:08:42.254588Z","end":"2026-05-19T00:08:42.619327Z","steps":["trace[1793489953] 'agreement among raft nodes before linearized reading' (duration: 364.235195ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:08:42.619443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T00:08:42.25457Z","time spent":"364.857009ms","remote":"127.0.0.1:44492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-05-19T00:08:42.620462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T00:08:42.235526Z","time spent":"383.868417ms","remote":"127.0.0.1:52248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3729,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T00:09:20.992665Z","caller":"traceutil/trace.go:171","msg":"trace[1904100213] transaction","detail":"{read_only:false; response_revision:2080; number_of_response:1; }","duration":"125.27524ms","start":"2026-05-19T00:09:20.867351Z","end":"2026-05-19T00:09:20.992626Z","steps":["trace[1904100213] 'process raft request' (duration: 107.382179ms)","trace[1904100213] 'compare' (duration: 17.708567ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T00:09:21.002422Z","caller":"traceutil/trace.go:171","msg":"trace[2124384369] transaction","detail":"{read_only:false; response_revision:2081; number_of_response:1; }","duration":"134.394191ms","start":"2026-05-19T00:09:20.868005Z","end":"2026-05-19T00:09:21.002399Z","steps":["trace[2124384369] 'process raft request' (duration: 134.226327ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:09:21.002672Z","caller":"traceutil/trace.go:171","msg":"trace[918236824] transaction","detail":"{read_only:false; response_revision:2082; number_of_response:1; }","duration":"134.323389ms","start":"2026-05-19T00:09:20.868321Z","end":"2026-05-19T00:09:21.002645Z","steps":["trace[918236824] 'process raft request' (duration: 134.034082ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:09:32.534213Z","caller":"traceutil/trace.go:171","msg":"trace[1604626833] transaction","detail":"{read_only:false; response_revision:2139; number_of_response:1; }","duration":"122.514464ms","start":"2026-05-19T00:09:32.41167Z","end":"2026-05-19T00:09:32.534184Z","steps":["trace[1604626833] 'process raft request' (duration: 122.044653ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:09:32.988744Z","caller":"traceutil/trace.go:171","msg":"trace[900606131] transaction","detail":"{read_only:false; response_revision:2141; number_of_response:1; }","duration":"107.942884ms","start":"2026-05-19T00:09:32.880772Z","end":"2026-05-19T00:09:32.988715Z","steps":["trace[900606131] 'process raft request' (duration: 107.739089ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:09:33.322076Z","caller":"traceutil/trace.go:171","msg":"trace[1726191443] linearizableReadLoop","detail":"{readStateIndex:2228; appliedIndex:2227; }","duration":"109.308316ms","start":"2026-05-19T00:09:33.212741Z","end":"2026-05-19T00:09:33.322049Z","steps":["trace[1726191443] 'read index received' (duration: 109.145922ms)","trace[1726191443] 'applied index is now lower than readState.Index' (duration: 161.374µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T00:09:33.322151Z","caller":"traceutil/trace.go:171","msg":"trace[375141673] transaction","detail":"{read_only:false; response_revision:2143; number_of_response:1; }","duration":"170.146704ms","start":"2026-05-19T00:09:33.151956Z","end":"2026-05-19T00:09:33.322103Z","steps":["trace[375141673] 'process raft request' (duration: 169.899628ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:09:33.322517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.892294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-19T00:09:33.322552Z","caller":"traceutil/trace.go:171","msg":"trace[2051359038] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2143; }","duration":"106.031597ms","start":"2026-05-19T00:09:33.216513Z","end":"2026-05-19T00:09:33.322545Z","steps":["trace[2051359038] 'agreement among raft nodes before linearized reading' (duration: 105.7343ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:09:33.322644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.87635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-19T00:09:33.322736Z","caller":"traceutil/trace.go:171","msg":"trace[1986386685] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2143; }","duration":"110.023183ms","start":"2026-05-19T00:09:33.212694Z","end":"2026-05-19T00:09:33.322717Z","steps":["trace[1986386685] 'agreement among raft nodes before linearized reading' (duration: 109.508821ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:09:33.323004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.592088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-19T00:09:33.323085Z","caller":"traceutil/trace.go:171","msg":"trace[402993694] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2143; }","duration":"103.718251ms","start":"2026-05-19T00:09:33.219349Z","end":"2026-05-19T00:09:33.323068Z","steps":["trace[402993694] 'agreement among raft nodes before linearized reading' (duration: 102.935002ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:10:33.919512Z","caller":"traceutil/trace.go:171","msg":"trace[891597753] transaction","detail":"{read_only:false; response_revision:2638; number_of_response:1; }","duration":"200.674121ms","start":"2026-05-19T00:10:33.718799Z","end":"2026-05-19T00:10:33.919473Z","steps":["trace[891597753] 'process raft request' (duration: 180.66837ms)","trace[891597753] 'compare' (duration: 19.728795ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T00:10:33.91956Z","caller":"traceutil/trace.go:171","msg":"trace[1095497798] linearizableReadLoop","detail":"{readStateIndex:2743; appliedIndex:2742; }","duration":"200.500307ms","start":"2026-05-19T00:10:33.719008Z","end":"2026-05-19T00:10:33.919508Z","steps":["trace[1095497798] 'read index received' (duration: 180.462425ms)","trace[1095497798] 'applied index is now lower than readState.Index' (duration: 20.033562ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T00:10:33.919576Z","caller":"traceutil/trace.go:171","msg":"trace[1114024083] transaction","detail":"{read_only:false; response_revision:2639; number_of_response:1; }","duration":"200.041575ms","start":"2026-05-19T00:10:33.719506Z","end":"2026-05-19T00:10:33.919548Z","steps":["trace[1114024083] 'process raft request' (duration: 199.907762ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:10:33.920482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.44495ms","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-19T00:10:33.920557Z","caller":"traceutil/trace.go:171","msg":"trace[1528855002] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2640; }","duration":"201.540361ms","start":"2026-05-19T00:10:33.718989Z","end":"2026-05-19T00:10:33.920529Z","steps":["trace[1528855002] 'agreement among raft nodes before linearized reading' (duration: 200.655551ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:10:58.143456Z","caller":"traceutil/trace.go:171","msg":"trace[2116285287] linearizableReadLoop","detail":"{readStateIndex:2902; appliedIndex:2901; }","duration":"104.545636ms","start":"2026-05-19T00:10:58.038888Z","end":"2026-05-19T00:10:58.143433Z","steps":["trace[2116285287] 'read index received' (duration: 104.345591ms)","trace[2116285287] 'applied index is now lower than readState.Index' (duration: 199.035µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T00:10:58.143527Z","caller":"traceutil/trace.go:171","msg":"trace[500466585] transaction","detail":"{read_only:false; response_revision:2791; number_of_response:1; }","duration":"113.303707ms","start":"2026-05-19T00:10:58.030196Z","end":"2026-05-19T00:10:58.1435Z","steps":["trace[500466585] 'process raft request' (duration: 113.087932ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:10:58.143702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.800652ms","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-19T00:10:58.143767Z","caller":"traceutil/trace.go:171","msg":"trace[613498919] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2791; }","duration":"104.909715ms","start":"2026-05-19T00:10:58.038834Z","end":"2026-05-19T00:10:58.143744Z","steps":["trace[613498919] 'agreement among raft nodes before linearized reading' (duration: 104.73794ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:10:58.143831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.362464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-19T00:10:58.1439Z","caller":"traceutil/trace.go:171","msg":"trace[1395097207] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2791; }","duration":"102.448056ms","start":"2026-05-19T00:10:58.041437Z","end":"2026-05-19T00:10:58.143885Z","steps":["trace[1395097207] 'agreement among raft nodes before linearized reading' (duration: 102.235471ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:10:58.360705Z","caller":"traceutil/trace.go:171","msg":"trace[1619714736] transaction","detail":"{read_only:false; response_revision:2795; number_of_response:1; }","duration":"115.944212ms","start":"2026-05-19T00:10:58.244738Z","end":"2026-05-19T00:10:58.360683Z","steps":["trace[1619714736] 'process raft request' (duration: 111.205027ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:15:07.683376Z","caller":"traceutil/trace.go:171","msg":"trace[1465557498] transaction","detail":"{read_only:false; response_revision:4921; number_of_response:1; }","duration":"139.1641ms","start":"2026-05-19T00:15:07.544178Z","end":"2026-05-19T00:15:07.683342Z","steps":["trace[1465557498] 'process raft request' (duration: 84.17425ms)","trace[1465557498] 'compare' (duration: 54.499709ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T00:15:07.683486Z","caller":"traceutil/trace.go:171","msg":"trace[73883146] linearizableReadLoop","detail":"{readStateIndex:5115; appliedIndex:5113; }","duration":"137.89409ms","start":"2026-05-19T00:15:07.545534Z","end":"2026-05-19T00:15:07.683429Z","steps":["trace[73883146] 'read index received' (duration: 82.835589ms)","trace[73883146] 'applied index is now lower than readState.Index' (duration: 55.055841ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T00:15:07.683465Z","caller":"traceutil/trace.go:171","msg":"trace[1443669363] transaction","detail":"{read_only:false; response_revision:4922; number_of_response:1; }","duration":"137.925141ms","start":"2026-05-19T00:15:07.545479Z","end":"2026-05-19T00:15:07.683404Z","steps":["trace[1443669363] 'process raft request' (duration: 137.603393ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:15:07.684017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.443203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3506"} {"level":"info","ts":"2026-05-19T00:15:07.684162Z","caller":"traceutil/trace.go:171","msg":"trace[1087541841] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:4923; }","duration":"138.624307ms","start":"2026-05-19T00:15:07.545507Z","end":"2026-05-19T00:15:07.684131Z","steps":["trace[1087541841] 'agreement among raft nodes before linearized reading' (duration: 138.094674ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:15:11.254854Z","caller":"traceutil/trace.go:171","msg":"trace[647029041] transaction","detail":"{read_only:false; response_revision:5047; number_of_response:1; }","duration":"144.679012ms","start":"2026-05-19T00:15:11.110143Z","end":"2026-05-19T00:15:11.254822Z","steps":["trace[647029041] 'process raft request' (duration: 144.484228ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:15:11.363791Z","caller":"traceutil/trace.go:171","msg":"trace[185098881] transaction","detail":"{read_only:false; response_revision:5048; number_of_response:1; }","duration":"249.792704ms","start":"2026-05-19T00:15:11.113963Z","end":"2026-05-19T00:15:11.363755Z","steps":["trace[185098881] 'process raft request' (duration: 231.26757ms)","trace[185098881] 'compare' (duration: 18.313619ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T00:15:13.626981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.781258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T00:15:13.627092Z","caller":"traceutil/trace.go:171","msg":"trace[1550374459] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5087; }","duration":"160.975503ms","start":"2026-05-19T00:15:13.466095Z","end":"2026-05-19T00:15:13.62707Z","steps":["trace[1550374459] 'range keys from in-memory index tree' (duration: 160.629294ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:15:23.599879Z","caller":"traceutil/trace.go:171","msg":"trace[1111210734] transaction","detail":"{read_only:false; response_revision:5299; number_of_response:1; }","duration":"117.92067ms","start":"2026-05-19T00:15:23.481926Z","end":"2026-05-19T00:15:23.599846Z","steps":["trace[1111210734] 'process raft request' (duration: 66.917216ms)","trace[1111210734] 'compare' (duration: 50.780479ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T00:15:23.600147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.77401ms","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-19T00:15:23.600275Z","caller":"traceutil/trace.go:171","msg":"trace[511951267] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5299; }","duration":"123.915454ms","start":"2026-05-19T00:15:23.476325Z","end":"2026-05-19T00:15:23.600241Z","steps":["trace[511951267] 'agreement among raft nodes before linearized reading' (duration: 123.588876ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:15:23.60008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.907799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/memcached-memcached-6479589586-hjxpl\" ","response":"range_response_count:1 size:7818"} {"level":"info","ts":"2026-05-19T00:15:23.600435Z","caller":"traceutil/trace.go:171","msg":"trace[654503016] range","detail":"{range_begin:/registry/pods/openstack/memcached-memcached-6479589586-hjxpl; range_end:; response_count:1; response_revision:5299; }","duration":"133.309499ms","start":"2026-05-19T00:15:23.467097Z","end":"2026-05-19T00:15:23.600407Z","steps":["trace[654503016] 'agreement among raft nodes before linearized reading' (duration: 132.748195ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:15:23.740851Z","caller":"traceutil/trace.go:171","msg":"trace[283188965] transaction","detail":"{read_only:false; response_revision:5301; number_of_response:1; }","duration":"136.171908ms","start":"2026-05-19T00:15:23.604652Z","end":"2026-05-19T00:15:23.740824Z","steps":["trace[283188965] 'process raft request' (duration: 117.173652ms)","trace[283188965] 'compare' (duration: 18.727289ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T00:15:23.740996Z","caller":"traceutil/trace.go:171","msg":"trace[2101284750] transaction","detail":"{read_only:false; response_revision:5302; number_of_response:1; }","duration":"128.371171ms","start":"2026-05-19T00:15:23.612595Z","end":"2026-05-19T00:15:23.740966Z","steps":["trace[2101284750] 'process raft request' (duration: 128.163496ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:15:23.940321Z","caller":"traceutil/trace.go:171","msg":"trace[1957283521] transaction","detail":"{read_only:false; response_revision:5310; number_of_response:1; }","duration":"120.554763ms","start":"2026-05-19T00:15:23.819737Z","end":"2026-05-19T00:15:23.940291Z","steps":["trace[1957283521] 'process raft request' (duration: 86.523036ms)","trace[1957283521] 'compare' (duration: 33.814072ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T00:15:24.257915Z","caller":"traceutil/trace.go:171","msg":"trace[1134862550] transaction","detail":"{read_only:false; response_revision:5311; number_of_response:1; }","duration":"276.579276ms","start":"2026-05-19T00:15:23.981309Z","end":"2026-05-19T00:15:24.257888Z","steps":["trace[1134862550] 'process raft request' (duration: 236.645188ms)","trace[1134862550] 'compare' (duration: 39.803935ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T00:15:31.620498Z","caller":"traceutil/trace.go:171","msg":"trace[1580673393] transaction","detail":"{read_only:false; response_revision:5461; number_of_response:1; }","duration":"116.485424ms","start":"2026-05-19T00:15:31.503958Z","end":"2026-05-19T00:15:31.620443Z","steps":["trace[1580673393] 'process raft request' (duration: 116.058774ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:15:31.74677Z","caller":"traceutil/trace.go:171","msg":"trace[167280140] transaction","detail":"{read_only:false; response_revision:5462; number_of_response:1; }","duration":"115.750527ms","start":"2026-05-19T00:15:31.63099Z","end":"2026-05-19T00:15:31.746741Z","steps":["trace[167280140] 'process raft request' (duration: 101.196537ms)","trace[167280140] 'compare' (duration: 14.395285ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T00:15:31.939996Z","caller":"traceutil/trace.go:171","msg":"trace[494736284] transaction","detail":"{read_only:false; response_revision:5464; number_of_response:1; }","duration":"184.079666ms","start":"2026-05-19T00:15:31.75586Z","end":"2026-05-19T00:15:31.939939Z","steps":["trace[494736284] 'process raft request' (duration: 183.702326ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:15:31.940098Z","caller":"traceutil/trace.go:171","msg":"trace[1275648609] transaction","detail":"{read_only:false; response_revision:5465; number_of_response:1; }","duration":"184.047455ms","start":"2026-05-19T00:15:31.756021Z","end":"2026-05-19T00:15:31.940069Z","steps":["trace[1275648609] 'process raft request' (duration: 183.757557ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:15:31.94031Z","caller":"traceutil/trace.go:171","msg":"trace[1361039823] transaction","detail":"{read_only:false; response_revision:5466; number_of_response:1; }","duration":"183.918702ms","start":"2026-05-19T00:15:31.75636Z","end":"2026-05-19T00:15:31.940279Z","steps":["trace[1361039823] 'process raft request' (duration: 183.642025ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:15:31.940362Z","caller":"traceutil/trace.go:171","msg":"trace[958929947] linearizableReadLoop","detail":"{readStateIndex:5676; appliedIndex:5670; }","duration":"165.279425ms","start":"2026-05-19T00:15:31.775058Z","end":"2026-05-19T00:15:31.940338Z","steps":["trace[958929947] 'read index received' (duration: 50.474281ms)","trace[958929947] 'applied index is now lower than readState.Index' (duration: 114.803724ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T00:15:31.940478Z","caller":"traceutil/trace.go:171","msg":"trace[782124065] transaction","detail":"{read_only:false; response_revision:5467; number_of_response:1; }","duration":"183.638955ms","start":"2026-05-19T00:15:31.756823Z","end":"2026-05-19T00:15:31.940462Z","steps":["trace[782124065] 'process raft request' (duration: 183.272967ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:15:31.940595Z","caller":"traceutil/trace.go:171","msg":"trace[456231787] transaction","detail":"{read_only:false; response_revision:5469; number_of_response:1; }","duration":"181.75206ms","start":"2026-05-19T00:15:31.758825Z","end":"2026-05-19T00:15:31.940577Z","steps":["trace[456231787] 'process raft request' (duration: 181.431062ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:15:31.940691Z","caller":"traceutil/trace.go:171","msg":"trace[2124464439] transaction","detail":"{read_only:false; response_revision:5468; number_of_response:1; }","duration":"183.552204ms","start":"2026-05-19T00:15:31.757105Z","end":"2026-05-19T00:15:31.940657Z","steps":["trace[2124464439] 'process raft request' (duration: 183.060002ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:15:31.940773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.698755ms","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-19T00:15:31.940805Z","caller":"traceutil/trace.go:171","msg":"trace[1028748369] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5469; }","duration":"165.788007ms","start":"2026-05-19T00:15:31.775009Z","end":"2026-05-19T00:15:31.940797Z","steps":["trace[1028748369] 'agreement among raft nodes before linearized reading' (duration: 165.610953ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:15:31.946908Z","caller":"traceutil/trace.go:171","msg":"trace[1062937264] transaction","detail":"{read_only:false; response_revision:5470; number_of_response:1; }","duration":"105.03218ms","start":"2026-05-19T00:15:31.841848Z","end":"2026-05-19T00:15:31.946881Z","steps":["trace[1062937264] 'process raft request' (duration: 104.749863ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:15:31.947117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.110109ms","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-19T00:15:31.947146Z","caller":"traceutil/trace.go:171","msg":"trace[539475550] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5470; }","duration":"127.184721ms","start":"2026-05-19T00:15:31.819954Z","end":"2026-05-19T00:15:31.947139Z","steps":["trace[539475550] 'agreement among raft nodes before linearized reading' (duration: 127.072018ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:15:31.946946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.960419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-19T00:15:31.947465Z","caller":"traceutil/trace.go:171","msg":"trace[2035321676] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:5470; }","duration":"128.570004ms","start":"2026-05-19T00:15:31.81887Z","end":"2026-05-19T00:15:31.94744Z","steps":["trace[2035321676] 'agreement among raft nodes before linearized reading' (duration: 127.930199ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:16:33.629728Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2989} {"level":"info","ts":"2026-05-19T00:16:33.705939Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2989,"took":"74.803035ms","hash":1538565050,"current-db-size-bytes":58265600,"current-db-size":"58 MB","current-db-size-in-use-bytes":33349632,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-05-19T00:16:33.706006Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1538565050,"revision":2989,"compact-revision":-1} {"level":"info","ts":"2026-05-19T00:21:33.635674Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6044} {"level":"info","ts":"2026-05-19T00:21:33.744135Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6044,"took":"106.058288ms","hash":3666563853,"current-db-size-bytes":58265600,"current-db-size":"58 MB","current-db-size-in-use-bytes":23683072,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-05-19T00:21:33.744218Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3666563853,"revision":6044,"compact-revision":2989} {"level":"info","ts":"2026-05-19T00:21:36.488797Z","caller":"traceutil/trace.go:171","msg":"trace[285762047] transaction","detail":"{read_only:false; response_revision:8290; number_of_response:1; }","duration":"257.618577ms","start":"2026-05-19T00:21:36.231149Z","end":"2026-05-19T00:21:36.488768Z","steps":["trace[285762047] 'process raft request' (duration: 256.798566ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:21:36.669067Z","caller":"traceutil/trace.go:171","msg":"trace[1201577141] linearizableReadLoop","detail":"{readStateIndex:8588; appliedIndex:8587; }","duration":"113.51914ms","start":"2026-05-19T00:21:36.555522Z","end":"2026-05-19T00:21:36.669042Z","steps":["trace[1201577141] 'read index received' (duration: 112.858314ms)","trace[1201577141] 'applied index is now lower than readState.Index' (duration: 659.496µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T00:21:36.669126Z","caller":"traceutil/trace.go:171","msg":"trace[2015291621] transaction","detail":"{read_only:false; response_revision:8291; number_of_response:1; }","duration":"121.545116ms","start":"2026-05-19T00:21:36.547548Z","end":"2026-05-19T00:21:36.669093Z","steps":["trace[2015291621] 'process raft request' (duration: 120.90284ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:21:36.669306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.746886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-19T00:21:36.669407Z","caller":"traceutil/trace.go:171","msg":"trace[1791902305] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:8291; }","duration":"113.910241ms","start":"2026-05-19T00:21:36.555477Z","end":"2026-05-19T00:21:36.669387Z","steps":["trace[1791902305] 'agreement among raft nodes before linearized reading' (duration: 113.696285ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:21:48.391871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.97749ms","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":"warn","ts":"2026-05-19T00:21:48.392175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.438386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-19T00:21:48.393014Z","caller":"traceutil/trace.go:171","msg":"trace[1717910224] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:8353; }","duration":"143.299167ms","start":"2026-05-19T00:21:48.249673Z","end":"2026-05-19T00:21:48.392972Z","steps":["trace[1717910224] 'count revisions from in-memory index tree' (duration: 142.309423ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:21:48.392948Z","caller":"traceutil/trace.go:171","msg":"trace[529523009] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8353; }","duration":"233.138878ms","start":"2026-05-19T00:21:48.15977Z","end":"2026-05-19T00:21:48.392909Z","steps":["trace[529523009] 'range keys from in-memory index tree' (duration: 231.699543ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:21:50.254695Z","caller":"traceutil/trace.go:171","msg":"trace[1671055332] transaction","detail":"{read_only:false; response_revision:8362; number_of_response:1; }","duration":"167.054766ms","start":"2026-05-19T00:21:50.087603Z","end":"2026-05-19T00:21:50.254658Z","steps":["trace[1671055332] 'process raft request' (duration: 157.226037ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:21:50.255197Z","caller":"traceutil/trace.go:171","msg":"trace[1420457479] transaction","detail":"{read_only:false; response_revision:8363; number_of_response:1; }","duration":"166.805031ms","start":"2026-05-19T00:21:50.08838Z","end":"2026-05-19T00:21:50.255185Z","steps":["trace[1420457479] 'process raft request' (duration: 166.181415ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:22:17.352777Z","caller":"traceutil/trace.go:171","msg":"trace[741791798] transaction","detail":"{read_only:false; response_revision:8562; number_of_response:1; }","duration":"155.507175ms","start":"2026-05-19T00:22:17.197237Z","end":"2026-05-19T00:22:17.352744Z","steps":["trace[741791798] 'process raft request' (duration: 155.182828ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:22:30.134978Z","caller":"traceutil/trace.go:171","msg":"trace[884667248] transaction","detail":"{read_only:false; response_revision:8668; number_of_response:1; }","duration":"113.751306ms","start":"2026-05-19T00:22:30.021196Z","end":"2026-05-19T00:22:30.134947Z","steps":["trace[884667248] 'process raft request' (duration: 113.545271ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:22:30.137949Z","caller":"traceutil/trace.go:171","msg":"trace[356634862] transaction","detail":"{read_only:false; response_revision:8669; number_of_response:1; }","duration":"108.215661ms","start":"2026-05-19T00:22:30.029689Z","end":"2026-05-19T00:22:30.137905Z","steps":["trace[356634862] 'process raft request' (duration: 107.949834ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:22:30.283199Z","caller":"traceutil/trace.go:171","msg":"trace[1548816259] transaction","detail":"{read_only:false; response_revision:8670; number_of_response:1; }","duration":"141.539064ms","start":"2026-05-19T00:22:30.141621Z","end":"2026-05-19T00:22:30.28316Z","steps":["trace[1548816259] 'process raft request' (duration: 131.468629ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:22:30.432846Z","caller":"traceutil/trace.go:171","msg":"trace[318388968] linearizableReadLoop","detail":"{readStateIndex:8985; appliedIndex:8984; }","duration":"142.710392ms","start":"2026-05-19T00:22:30.290102Z","end":"2026-05-19T00:22:30.432812Z","steps":["trace[318388968] 'read index received' (duration: 122.562211ms)","trace[318388968] 'applied index is now lower than readState.Index' (duration: 20.146661ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T00:22:30.433046Z","caller":"traceutil/trace.go:171","msg":"trace[1777859532] transaction","detail":"{read_only:false; response_revision:8671; number_of_response:1; }","duration":"145.505031ms","start":"2026-05-19T00:22:30.287508Z","end":"2026-05-19T00:22:30.433013Z","steps":["trace[1777859532] 'process raft request' (duration: 125.171005ms)","trace[1777859532] 'compare' (duration: 19.893735ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T00:22:30.433109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.98195ms","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-19T00:22:30.433149Z","caller":"traceutil/trace.go:171","msg":"trace[377082195] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8671; }","duration":"143.069411ms","start":"2026-05-19T00:22:30.290068Z","end":"2026-05-19T00:22:30.433137Z","steps":["trace[377082195] 'agreement among raft nodes before linearized reading' (duration: 142.860036ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:22:30.445439Z","caller":"traceutil/trace.go:171","msg":"trace[1347170079] transaction","detail":"{read_only:false; response_revision:8672; number_of_response:1; }","duration":"153.350691ms","start":"2026-05-19T00:22:30.292071Z","end":"2026-05-19T00:22:30.445422Z","steps":["trace[1347170079] 'process raft request' (duration: 153.219668ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:22:30.959824Z","caller":"traceutil/trace.go:171","msg":"trace[1647310781] transaction","detail":"{read_only:false; response_revision:8677; number_of_response:1; }","duration":"110.036345ms","start":"2026-05-19T00:22:30.849757Z","end":"2026-05-19T00:22:30.959794Z","steps":["trace[1647310781] 'process raft request' (duration: 109.869131ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:22:31.003376Z","caller":"traceutil/trace.go:171","msg":"trace[384404682] transaction","detail":"{read_only:false; response_revision:8678; number_of_response:1; }","duration":"104.348666ms","start":"2026-05-19T00:22:30.898992Z","end":"2026-05-19T00:22:31.003341Z","steps":["trace[384404682] 'process raft request' (duration: 104.061539ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:22:31.285023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.925272ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-19T00:22:31.285169Z","caller":"traceutil/trace.go:171","msg":"trace[1547088139] transaction","detail":"{read_only:false; response_revision:8679; number_of_response:1; }","duration":"277.615564ms","start":"2026-05-19T00:22:31.007531Z","end":"2026-05-19T00:22:31.285147Z","steps":["trace[1547088139] 'process raft request' (duration: 31.448327ms)","trace[1547088139] 'compare' (duration: 245.367298ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T00:22:31.304448Z","caller":"traceutil/trace.go:171","msg":"trace[2007437303] linearizableReadLoop","detail":"{readStateIndex:8994; appliedIndex:8992; }","duration":"284.539044ms","start":"2026-05-19T00:22:31.019881Z","end":"2026-05-19T00:22:31.30442Z","steps":["trace[2007437303] 'read index received' (duration: 19.115026ms)","trace[2007437303] 'applied index is now lower than readState.Index' (duration: 265.423158ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T00:22:31.304545Z","caller":"traceutil/trace.go:171","msg":"trace[225992224] transaction","detail":"{read_only:false; response_revision:8680; number_of_response:1; }","duration":"294.383324ms","start":"2026-05-19T00:22:31.010112Z","end":"2026-05-19T00:22:31.304495Z","steps":["trace[225992224] 'process raft request' (duration: 294.168868ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:22:31.304862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.939764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T00:22:31.305128Z","caller":"traceutil/trace.go:171","msg":"trace[1968186057] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:8680; }","duration":"285.090428ms","start":"2026-05-19T00:22:31.019863Z","end":"2026-05-19T00:22:31.304954Z","steps":["trace[1968186057] 'agreement among raft nodes before linearized reading' (duration: 284.661007ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:22:31.326027Z","caller":"traceutil/trace.go:171","msg":"trace[2016244918] transaction","detail":"{read_only:false; response_revision:8681; number_of_response:1; }","duration":"256.990421ms","start":"2026-05-19T00:22:31.069Z","end":"2026-05-19T00:22:31.32599Z","steps":["trace[2016244918] 'process raft request' (duration: 256.261943ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:22:49.711463Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000023e9.wal"} {"level":"info","ts":"2026-05-19T00:23:38.049564Z","caller":"traceutil/trace.go:171","msg":"trace[394946859] transaction","detail":"{read_only:false; response_revision:9371; number_of_response:1; }","duration":"108.850207ms","start":"2026-05-19T00:23:37.940669Z","end":"2026-05-19T00:23:38.049519Z","steps":["trace[394946859] 'process raft request' (duration: 98.172059ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:24:20.599744Z","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-19T00:24:20.604913Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-05-19T00:24:20.605014Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-05-19T00:26:33.641055Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8273} {"level":"info","ts":"2026-05-19T00:26:33.720722Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8273,"took":"77.524375ms","hash":3092024571,"current-db-size-bytes":58265600,"current-db-size":"58 MB","current-db-size-in-use-bytes":24969216,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-05-19T00:26:33.720795Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3092024571,"revision":8273,"compact-revision":6044} {"level":"warn","ts":"2026-05-19T00:29:22.882315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.733311ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-05-19T00:29:22.882431Z","caller":"traceutil/trace.go:171","msg":"trace[1304011958] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:11838; }","duration":"101.886654ms","start":"2026-05-19T00:29:22.780518Z","end":"2026-05-19T00:29:22.882405Z","steps":["trace[1304011958] 'range keys from in-memory index tree' (duration: 101.038994ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:31:33.647236Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10499} {"level":"info","ts":"2026-05-19T00:31:33.730905Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10499,"took":"81.031159ms","hash":3694945893,"current-db-size-bytes":58265600,"current-db-size":"58 MB","current-db-size-in-use-bytes":26198016,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-05-19T00:31:33.731001Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3694945893,"revision":10499,"compact-revision":8273} {"level":"warn","ts":"2026-05-19T00:35:28.571168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.980906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T00:35:28.571289Z","caller":"traceutil/trace.go:171","msg":"trace[1587609140] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14313; }","duration":"105.17886ms","start":"2026-05-19T00:35:28.466088Z","end":"2026-05-19T00:35:28.571267Z","steps":["trace[1587609140] 'range keys from in-memory index tree' (duration: 104.859232ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:35:49.056482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.193824ms","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-19T00:35:49.056545Z","caller":"traceutil/trace.go:171","msg":"trace[374649586] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:14423; }","duration":"227.267855ms","start":"2026-05-19T00:35:48.829264Z","end":"2026-05-19T00:35:49.056532Z","steps":["trace[374649586] 'range keys from in-memory index tree' (duration: 227.026879ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:35:49.056708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.152985ms","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-19T00:35:49.056725Z","caller":"traceutil/trace.go:171","msg":"trace[502153255] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:14423; }","duration":"225.219186ms","start":"2026-05-19T00:35:48.831502Z","end":"2026-05-19T00:35:49.056721Z","steps":["trace[502153255] 'range keys from in-memory index tree' (duration: 225.028151ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:35:49.057165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.383277ms","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-19T00:35:49.057258Z","caller":"traceutil/trace.go:171","msg":"trace[1240194253] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:14423; }","duration":"150.490319ms","start":"2026-05-19T00:35:48.906747Z","end":"2026-05-19T00:35:49.057237Z","steps":["trace[1240194253] 'range keys from in-memory index tree' (duration: 150.159282ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:36:27.68674Z","caller":"traceutil/trace.go:171","msg":"trace[1204045913] transaction","detail":"{read_only:false; response_revision:14829; number_of_response:1; }","duration":"309.39723ms","start":"2026-05-19T00:36:27.377316Z","end":"2026-05-19T00:36:27.686713Z","steps":["trace[1204045913] 'process raft request' (duration: 309.249307ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:36:27.686989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T00:36:27.377296Z","time spent":"309.591025ms","remote":"127.0.0.1:52314","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-19T00:36:27.692567Z","caller":"traceutil/trace.go:171","msg":"trace[571778953] transaction","detail":"{read_only:false; response_revision:14832; number_of_response:1; }","duration":"158.02943ms","start":"2026-05-19T00:36:27.534511Z","end":"2026-05-19T00:36:27.692541Z","steps":["trace[571778953] 'process raft request' (duration: 157.949988ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:36:27.692662Z","caller":"traceutil/trace.go:171","msg":"trace[1291025290] transaction","detail":"{read_only:false; response_revision:14830; number_of_response:1; }","duration":"312.69648ms","start":"2026-05-19T00:36:27.379908Z","end":"2026-05-19T00:36:27.692604Z","steps":["trace[1291025290] 'process raft request' (duration: 312.372402ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:36:27.692936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T00:36:27.37989Z","time spent":"312.907335ms","remote":"127.0.0.1:52314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T00:36:27.692916Z","caller":"traceutil/trace.go:171","msg":"trace[818052158] transaction","detail":"{read_only:false; response_revision:14833; number_of_response:1; }","duration":"140.220252ms","start":"2026-05-19T00:36:27.552649Z","end":"2026-05-19T00:36:27.692869Z","steps":["trace[818052158] 'process raft request' (duration: 139.853054ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:36:27.692915Z","caller":"traceutil/trace.go:171","msg":"trace[1038306643] linearizableReadLoop","detail":"{readStateIndex:15353; appliedIndex:15350; }","duration":"226.845036ms","start":"2026-05-19T00:36:27.466003Z","end":"2026-05-19T00:36:27.692848Z","steps":["trace[1038306643] 'read index received' (duration: 220.667987ms)","trace[1038306643] 'applied index is now lower than readState.Index' (duration: 6.172749ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T00:36:27.693018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.000259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T00:36:27.693064Z","caller":"traceutil/trace.go:171","msg":"trace[2115037816] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14833; }","duration":"227.100942ms","start":"2026-05-19T00:36:27.46595Z","end":"2026-05-19T00:36:27.693051Z","steps":["trace[2115037816] 'agreement among raft nodes before linearized reading' (duration: 226.998549ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:36:27.692827Z","caller":"traceutil/trace.go:171","msg":"trace[729465057] transaction","detail":"{read_only:false; response_revision:14831; number_of_response:1; }","duration":"312.752721ms","start":"2026-05-19T00:36:27.379966Z","end":"2026-05-19T00:36:27.692719Z","steps":["trace[729465057] 'process raft request' (duration: 312.455054ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:36:27.693468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T00:36:27.379943Z","time spent":"313.406177ms","remote":"127.0.0.1:52314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T00:36:33.654059Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12740} {"level":"info","ts":"2026-05-19T00:36:33.727004Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12740,"took":"69.84627ms","hash":1194439476,"current-db-size-bytes":58265600,"current-db-size":"58 MB","current-db-size-in-use-bytes":28717056,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-05-19T00:36:33.727063Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1194439476,"revision":12740,"compact-revision":10499} {"level":"info","ts":"2026-05-19T00:37:53.371622Z","caller":"traceutil/trace.go:171","msg":"trace[295484365] transaction","detail":"{read_only:false; response_revision:15641; number_of_response:1; }","duration":"125.705744ms","start":"2026-05-19T00:37:53.245878Z","end":"2026-05-19T00:37:53.371584Z","steps":["trace[295484365] 'process raft request' (duration: 116.04162ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:37:53.743942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.689708ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-19T00:37:53.744116Z","caller":"traceutil/trace.go:171","msg":"trace[1244659617] linearizableReadLoop","detail":"{readStateIndex:16189; appliedIndex:16188; }","duration":"280.874996ms","start":"2026-05-19T00:37:53.463216Z","end":"2026-05-19T00:37:53.744091Z","steps":["trace[1244659617] 'read index received' (duration: 49.927611ms)","trace[1244659617] 'applied index is now lower than readState.Index' (duration: 230.942825ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T00:37:53.744169Z","caller":"traceutil/trace.go:171","msg":"trace[1861611746] transaction","detail":"{read_only:false; response_revision:15642; number_of_response:1; }","duration":"291.986611ms","start":"2026-05-19T00:37:53.452132Z","end":"2026-05-19T00:37:53.744119Z","steps":["trace[1861611746] 'process raft request' (duration: 61.006506ms)","trace[1861611746] 'compare' (duration: 230.478573ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T00:37:53.744349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.111691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-19T00:37:53.744494Z","caller":"traceutil/trace.go:171","msg":"trace[289997875] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:15642; }","duration":"281.306256ms","start":"2026-05-19T00:37:53.463161Z","end":"2026-05-19T00:37:53.744467Z","steps":["trace[289997875] 'agreement among raft nodes before linearized reading' (duration: 281.06341ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:37:53.744531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.302953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-19T00:37:53.744714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.902568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-j5hn2\" ","response":"range_response_count:1 size:22099"} {"level":"info","ts":"2026-05-19T00:37:53.744755Z","caller":"traceutil/trace.go:171","msg":"trace[1848740740] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:15642; }","duration":"278.546199ms","start":"2026-05-19T00:37:53.466179Z","end":"2026-05-19T00:37:53.744725Z","steps":["trace[1848740740] 'agreement among raft nodes before linearized reading' (duration: 278.239171ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:37:53.744786Z","caller":"traceutil/trace.go:171","msg":"trace[237875348] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-j5hn2; range_end:; response_count:1; response_revision:15642; }","duration":"126.013171ms","start":"2026-05-19T00:37:53.618753Z","end":"2026-05-19T00:37:53.744766Z","steps":["trace[237875348] 'agreement among raft nodes before linearized reading' (duration: 125.726354ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:37:53.74483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.751991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-j5hn2\" ","response":"range_response_count:1 size:22099"} {"level":"info","ts":"2026-05-19T00:37:53.744944Z","caller":"traceutil/trace.go:171","msg":"trace[811026026] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-j5hn2; range_end:; response_count:1; response_revision:15642; }","duration":"149.922465ms","start":"2026-05-19T00:37:53.595001Z","end":"2026-05-19T00:37:53.744923Z","steps":["trace[811026026] 'agreement among raft nodes before linearized reading' (duration: 149.619968ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:37:53.744999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.691435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/\" range_end:\"/registry/rabbitmq.com/rabbitmqclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-19T00:37:53.74522Z","caller":"traceutil/trace.go:171","msg":"trace[1990704194] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/; range_end:/registry/rabbitmq.com/rabbitmqclusters0; response_count:0; response_revision:15642; }","duration":"208.035603ms","start":"2026-05-19T00:37:53.537159Z","end":"2026-05-19T00:37:53.745194Z","steps":["trace[1990704194] 'agreement among raft nodes before linearized reading' (duration: 207.525361ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:37:54.124997Z","caller":"traceutil/trace.go:171","msg":"trace[227542291] transaction","detail":"{read_only:false; response_revision:15643; number_of_response:1; }","duration":"110.310303ms","start":"2026-05-19T00:37:54.014655Z","end":"2026-05-19T00:37:54.124965Z","steps":["trace[227542291] 'process raft request' (duration: 110.100248ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:37:54.293164Z","caller":"traceutil/trace.go:171","msg":"trace[1708300615] transaction","detail":"{read_only:false; response_revision:15645; number_of_response:1; }","duration":"147.397735ms","start":"2026-05-19T00:37:54.14572Z","end":"2026-05-19T00:37:54.293117Z","steps":["trace[1708300615] 'process raft request' (duration: 147.18748ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:37:54.301272Z","caller":"traceutil/trace.go:171","msg":"trace[801192476] linearizableReadLoop","detail":"{readStateIndex:16194; appliedIndex:16191; }","duration":"123.48603ms","start":"2026-05-19T00:37:54.177766Z","end":"2026-05-19T00:37:54.301252Z","steps":["trace[801192476] 'read index received' (duration: 115.130629ms)","trace[801192476] 'applied index is now lower than readState.Index' (duration: 8.354481ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T00:37:54.301333Z","caller":"traceutil/trace.go:171","msg":"trace[354492953] transaction","detail":"{read_only:false; response_revision:15646; number_of_response:1; }","duration":"155.378877ms","start":"2026-05-19T00:37:54.145909Z","end":"2026-05-19T00:37:54.301288Z","steps":["trace[354492953] 'process raft request' (duration: 155.184663ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:37:54.301366Z","caller":"traceutil/trace.go:171","msg":"trace[1186421636] transaction","detail":"{read_only:false; response_revision:15647; number_of_response:1; }","duration":"155.06664ms","start":"2026-05-19T00:37:54.146268Z","end":"2026-05-19T00:37:54.301335Z","steps":["trace[1186421636] 'process raft request' (duration: 154.955627ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:37:54.301526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.730715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-19T00:37:54.301605Z","caller":"traceutil/trace.go:171","msg":"trace[323210056] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:15647; }","duration":"123.860009ms","start":"2026-05-19T00:37:54.17773Z","end":"2026-05-19T00:37:54.30159Z","steps":["trace[323210056] 'agreement among raft nodes before linearized reading' (duration: 123.649734ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:41:33.660901Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14880} {"level":"info","ts":"2026-05-19T00:41:33.753203Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14880,"took":"89.215963ms","hash":786260746,"current-db-size-bytes":58265600,"current-db-size":"58 MB","current-db-size-in-use-bytes":30138368,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-19T00:41:33.753288Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":786260746,"revision":14880,"compact-revision":12740} {"level":"info","ts":"2026-05-19T00:43:56.646091Z","caller":"traceutil/trace.go:171","msg":"trace[778698162] linearizableReadLoop","detail":"{readStateIndex:18940; appliedIndex:18939; }","duration":"252.849496ms","start":"2026-05-19T00:43:56.39321Z","end":"2026-05-19T00:43:56.646059Z","steps":["trace[778698162] 'read index received' (duration: 64.990979ms)","trace[778698162] 'applied index is now lower than readState.Index' (duration: 187.857497ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T00:43:56.64626Z","caller":"traceutil/trace.go:171","msg":"trace[1058687502] transaction","detail":"{read_only:false; response_revision:18304; number_of_response:1; }","duration":"250.651084ms","start":"2026-05-19T00:43:56.395596Z","end":"2026-05-19T00:43:56.646247Z","steps":["trace[1058687502] 'process raft request' (duration: 250.341876ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:43:56.646417Z","caller":"traceutil/trace.go:171","msg":"trace[346761986] transaction","detail":"{read_only:false; response_revision:18303; number_of_response:1; }","duration":"253.40262ms","start":"2026-05-19T00:43:56.392963Z","end":"2026-05-19T00:43:56.646365Z","steps":["trace[346761986] 'process raft request' (duration: 236.786511ms)","trace[346761986] 'compare' (duration: 15.881531ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T00:43:56.646543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.325397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-05-19T00:43:56.646597Z","caller":"traceutil/trace.go:171","msg":"trace[176127847] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:18304; }","duration":"253.461821ms","start":"2026-05-19T00:43:56.393122Z","end":"2026-05-19T00:43:56.646584Z","steps":["trace[176127847] 'agreement among raft nodes before linearized reading' (duration: 253.273306ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:43:56.646771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.598657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-19T00:43:56.646812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.682981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-2hbq7\" ","response":"range_response_count:1 size:20857"} {"level":"info","ts":"2026-05-19T00:43:56.646827Z","caller":"traceutil/trace.go:171","msg":"trace[77680071] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:18304; }","duration":"181.703199ms","start":"2026-05-19T00:43:56.465114Z","end":"2026-05-19T00:43:56.646817Z","steps":["trace[77680071] 'agreement among raft nodes before linearized reading' (duration: 181.599527ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:43:56.646845Z","caller":"traceutil/trace.go:171","msg":"trace[1088588639] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-2hbq7; range_end:; response_count:1; response_revision:18304; }","duration":"154.728673ms","start":"2026-05-19T00:43:56.492107Z","end":"2026-05-19T00:43:56.646835Z","steps":["trace[1088588639] 'agreement among raft nodes before linearized reading' (duration: 154.62425ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:43:56.647049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.842739ms","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:6"} {"level":"warn","ts":"2026-05-19T00:43:56.647069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.097136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretimports/\" range_end:\"/registry/secretgen.carvel.dev/secretimports0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T00:43:56.647081Z","caller":"traceutil/trace.go:171","msg":"trace[2143283660] range","detail":"{range_begin:/registry/rabbitmq.com/topicpermissions/; range_end:/registry/rabbitmq.com/topicpermissions0; response_count:0; response_revision:18304; }","duration":"155.91737ms","start":"2026-05-19T00:43:56.491152Z","end":"2026-05-19T00:43:56.647069Z","steps":["trace[2143283660] 'agreement among raft nodes before linearized reading' (duration: 155.832688ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:43:56.647105Z","caller":"traceutil/trace.go:171","msg":"trace[1651515241] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretimports/; range_end:/registry/secretgen.carvel.dev/secretimports0; response_count:0; response_revision:18304; }","duration":"106.134297ms","start":"2026-05-19T00:43:56.540957Z","end":"2026-05-19T00:43:56.647091Z","steps":["trace[1651515241] 'agreement among raft nodes before linearized reading' (duration: 106.072535ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:43:57.027637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.347663ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-19T00:43:57.027853Z","caller":"traceutil/trace.go:171","msg":"trace[1513128533] linearizableReadLoop","detail":"{readStateIndex:18944; appliedIndex:18942; }","duration":"271.927114ms","start":"2026-05-19T00:43:56.755907Z","end":"2026-05-19T00:43:57.027834Z","steps":["trace[1513128533] 'read index received' (duration: 147.202552ms)","trace[1513128533] 'applied index is now lower than readState.Index' (duration: 124.723492ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T00:43:57.028019Z","caller":"traceutil/trace.go:171","msg":"trace[1810842248] transaction","detail":"{read_only:false; response_revision:18307; number_of_response:1; }","duration":"291.306849ms","start":"2026-05-19T00:43:56.736643Z","end":"2026-05-19T00:43:57.02795Z","steps":["trace[1810842248] 'process raft request' (duration: 291.125135ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:43:57.028133Z","caller":"traceutil/trace.go:171","msg":"trace[26186260] transaction","detail":"{read_only:false; response_revision:18306; number_of_response:1; }","duration":"367.547917ms","start":"2026-05-19T00:43:56.660548Z","end":"2026-05-19T00:43:57.028096Z","steps":["trace[26186260] 'process raft request' (duration: 242.646311ms)","trace[26186260] 'compare' (duration: 124.20827ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T00:43:57.028339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T00:43:56.660532Z","time spent":"367.693321ms","remote":"127.0.0.1:52248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":20974,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T00:43:57.028324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.134482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-05-19T00:43:57.028487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.57897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.100\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-05-19T00:43:57.02852Z","caller":"traceutil/trace.go:171","msg":"trace[1765475882] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:18307; }","duration":"165.352857ms","start":"2026-05-19T00:43:56.863151Z","end":"2026-05-19T00:43:57.028504Z","steps":["trace[1765475882] 'agreement among raft nodes before linearized reading' (duration: 164.969138ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:43:57.028527Z","caller":"traceutil/trace.go:171","msg":"trace[263640953] range","detail":"{range_begin:/registry/masterleases/162.253.55.100; range_end:; response_count:1; response_revision:18307; }","duration":"272.63538ms","start":"2026-05-19T00:43:56.755876Z","end":"2026-05-19T00:43:57.028512Z","steps":["trace[263640953] 'agreement among raft nodes before linearized reading' (duration: 272.504837ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:43:57.205269Z","caller":"traceutil/trace.go:171","msg":"trace[1094622875] transaction","detail":"{read_only:false; response_revision:18308; number_of_response:1; }","duration":"109.011895ms","start":"2026-05-19T00:43:57.09623Z","end":"2026-05-19T00:43:57.205242Z","steps":["trace[1094622875] 'process raft request' (duration: 77.057358ms)","trace[1094622875] 'compare' (duration: 31.335502ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T00:45:37.970237Z","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-19T00:45:37.976116Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-05-19T00:45:37.976465Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-05-19T00:46:33.67842Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17343} {"level":"info","ts":"2026-05-19T00:46:33.776685Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17343,"took":"95.234865ms","hash":3116724788,"current-db-size-bytes":58265600,"current-db-size":"58 MB","current-db-size-in-use-bytes":34013184,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-05-19T00:46:33.776775Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3116724788,"revision":17343,"compact-revision":14880} {"level":"info","ts":"2026-05-19T00:49:56.823368Z","caller":"traceutil/trace.go:171","msg":"trace[2056509] transaction","detail":"{read_only:false; response_revision:21062; number_of_response:1; }","duration":"263.399685ms","start":"2026-05-19T00:49:56.559943Z","end":"2026-05-19T00:49:56.823343Z","steps":["trace[2056509] 'process raft request' (duration: 263.17132ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:49:56.836949Z","caller":"traceutil/trace.go:171","msg":"trace[1887529912] transaction","detail":"{read_only:false; response_revision:21063; number_of_response:1; }","duration":"276.125659ms","start":"2026-05-19T00:49:56.560788Z","end":"2026-05-19T00:49:56.836913Z","steps":["trace[1887529912] 'process raft request' (duration: 275.662079ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:49:56.836976Z","caller":"traceutil/trace.go:171","msg":"trace[1373672603] transaction","detail":"{read_only:false; response_revision:21064; number_of_response:1; }","duration":"276.072768ms","start":"2026-05-19T00:49:56.560876Z","end":"2026-05-19T00:49:56.836949Z","steps":["trace[1373672603] 'process raft request' (duration: 275.958716ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:51:33.683486Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19659} {"level":"info","ts":"2026-05-19T00:51:33.80342Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19659,"took":"116.102586ms","hash":613615545,"current-db-size-bytes":58265600,"current-db-size":"58 MB","current-db-size-in-use-bytes":29872128,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-19T00:51:33.803497Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":613615545,"revision":19659,"compact-revision":17343} {"level":"info","ts":"2026-05-19T00:53:53.913963Z","caller":"traceutil/trace.go:171","msg":"trace[544189755] transaction","detail":"{read_only:false; response_revision:23507; number_of_response:1; }","duration":"160.717536ms","start":"2026-05-19T00:53:53.753215Z","end":"2026-05-19T00:53:53.913932Z","steps":["trace[544189755] 'process raft request' (duration: 160.499231ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:53:54.026687Z","caller":"traceutil/trace.go:171","msg":"trace[9694174] linearizableReadLoop","detail":"{readStateIndex:24315; appliedIndex:24313; }","duration":"120.937242ms","start":"2026-05-19T00:53:53.905718Z","end":"2026-05-19T00:53:54.026655Z","steps":["trace[9694174] 'read index received' (duration: 7.92716ms)","trace[9694174] 'applied index is now lower than readState.Index' (duration: 113.008922ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T00:53:54.026718Z","caller":"traceutil/trace.go:171","msg":"trace[271992812] transaction","detail":"{read_only:false; response_revision:23508; number_of_response:1; }","duration":"180.696085ms","start":"2026-05-19T00:53:53.845988Z","end":"2026-05-19T00:53:54.026684Z","steps":["trace[271992812] 'process raft request' (duration: 180.423619ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:53:54.026959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.218259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-19T00:53:54.027024Z","caller":"traceutil/trace.go:171","msg":"trace[350477445] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:23508; }","duration":"121.355032ms","start":"2026-05-19T00:53:53.905657Z","end":"2026-05-19T00:53:54.027012Z","steps":["trace[350477445] 'agreement among raft nodes before linearized reading' (duration: 121.123287ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:56:33.691381Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21920} {"level":"info","ts":"2026-05-19T00:56:33.805825Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21920,"took":"109.58415ms","hash":4224469223,"current-db-size-bytes":58265600,"current-db-size":"58 MB","current-db-size-in-use-bytes":43360256,"current-db-size-in-use":"43 MB"} {"level":"info","ts":"2026-05-19T00:56:33.806294Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4224469223,"revision":21920,"compact-revision":19659} {"level":"info","ts":"2026-05-19T00:57:09.058017Z","caller":"traceutil/trace.go:171","msg":"trace[1457671388] transaction","detail":"{read_only:false; response_revision:25522; number_of_response:1; }","duration":"176.483005ms","start":"2026-05-19T00:57:08.881505Z","end":"2026-05-19T00:57:09.057988Z","steps":["trace[1457671388] 'process raft request' (duration: 97.320646ms)","trace[1457671388] 'compare' (duration: 78.845882ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T00:57:09.058183Z","caller":"traceutil/trace.go:171","msg":"trace[2118323430] linearizableReadLoop","detail":"{readStateIndex:26382; appliedIndex:26380; }","duration":"120.143983ms","start":"2026-05-19T00:57:08.937999Z","end":"2026-05-19T00:57:09.058143Z","steps":["trace[2118323430] 'read index received' (duration: 40.762998ms)","trace[2118323430] 'applied index is now lower than readState.Index' (duration: 79.378145ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T00:57:09.058268Z","caller":"traceutil/trace.go:171","msg":"trace[918574834] transaction","detail":"{read_only:false; response_revision:25523; number_of_response:1; }","duration":"175.198764ms","start":"2026-05-19T00:57:08.883059Z","end":"2026-05-19T00:57:09.058258Z","steps":["trace[918574834] 'process raft request' (duration: 174.887936ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:57:09.058423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.393469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-05-19T00:57:09.058431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.259986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-19T00:57:09.05846Z","caller":"traceutil/trace.go:171","msg":"trace[1417943799] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:25523; }","duration":"120.509892ms","start":"2026-05-19T00:57:08.937942Z","end":"2026-05-19T00:57:09.058451Z","steps":["trace[1417943799] 'agreement among raft nodes before linearized reading' (duration: 120.391779ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:57:09.058474Z","caller":"traceutil/trace.go:171","msg":"trace[1814202808] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:25523; }","duration":"120.341967ms","start":"2026-05-19T00:57:08.938117Z","end":"2026-05-19T00:57:09.058459Z","steps":["trace[1814202808] 'agreement among raft nodes before linearized reading' (duration: 120.227105ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:57:09.058747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.806318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/rsakeys/\" range_end:\"/registry/secretgen.k14s.io/rsakeys0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T00:57:09.058782Z","caller":"traceutil/trace.go:171","msg":"trace[561644471] range","detail":"{range_begin:/registry/secretgen.k14s.io/rsakeys/; range_end:/registry/secretgen.k14s.io/rsakeys0; response_count:0; response_revision:25523; }","duration":"105.844819ms","start":"2026-05-19T00:57:08.95293Z","end":"2026-05-19T00:57:09.058774Z","steps":["trace[561644471] 'agreement among raft nodes before linearized reading' (duration: 105.783917ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:57:09.366707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.396189ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-19T00:57:09.366812Z","caller":"traceutil/trace.go:171","msg":"trace[492060014] linearizableReadLoop","detail":"{readStateIndex:26383; appliedIndex:26382; }","duration":"250.172523ms","start":"2026-05-19T00:57:09.116624Z","end":"2026-05-19T00:57:09.366796Z","steps":["trace[492060014] 'read index received' (duration: 124.612801ms)","trace[492060014] 'applied index is now lower than readState.Index' (duration: 125.558352ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T00:57:09.367039Z","caller":"traceutil/trace.go:171","msg":"trace[2016850178] transaction","detail":"{read_only:false; response_revision:25524; number_of_response:1; }","duration":"263.297988ms","start":"2026-05-19T00:57:09.103729Z","end":"2026-05-19T00:57:09.367027Z","steps":["trace[2016850178] 'process raft request' (duration: 137.48953ms)","trace[2016850178] 'compare' (duration: 124.789413ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T00:57:09.367476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.887971ms","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-19T00:57:09.367519Z","caller":"traceutil/trace.go:171","msg":"trace[130395198] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25524; }","duration":"250.987403ms","start":"2026-05-19T00:57:09.116521Z","end":"2026-05-19T00:57:09.367508Z","steps":["trace[130395198] 'agreement among raft nodes before linearized reading' (duration: 250.84264ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:57:09.666242Z","caller":"traceutil/trace.go:171","msg":"trace[1489737839] transaction","detail":"{read_only:false; response_revision:25525; number_of_response:1; }","duration":"428.277927ms","start":"2026-05-19T00:57:09.237914Z","end":"2026-05-19T00:57:09.666192Z","steps":["trace[1489737839] 'process raft request' (duration: 428.078772ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:57:09.666446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T00:57:09.237877Z","time spent":"428.459851ms","remote":"127.0.0.1:52314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T00:57:09.666468Z","caller":"traceutil/trace.go:171","msg":"trace[377111056] linearizableReadLoop","detail":"{readStateIndex:26384; appliedIndex:26384; }","duration":"299.60899ms","start":"2026-05-19T00:57:09.366834Z","end":"2026-05-19T00:57:09.666443Z","steps":["trace[377111056] 'read index received' (duration: 299.601399ms)","trace[377111056] 'applied index is now lower than readState.Index' (duration: 5.691µs)"],"step_count":2} {"level":"warn","ts":"2026-05-19T00:57:09.666678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.121208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-05-19T00:57:09.66671Z","caller":"traceutil/trace.go:171","msg":"trace[1413316057] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25525; }","duration":"309.254711ms","start":"2026-05-19T00:57:09.357446Z","end":"2026-05-19T00:57:09.666701Z","steps":["trace[1413316057] 'agreement among raft nodes before linearized reading' (duration: 309.117567ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:57:09.666738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T00:57:09.357411Z","time spent":"309.320112ms","remote":"127.0.0.1:52314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-19T00:57:09.666927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"489.356842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/thanosrulers/\" range_end:\"/registry/monitoring.coreos.com/thanosrulers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T00:57:09.666962Z","caller":"traceutil/trace.go:171","msg":"trace[388131699] range","detail":"{range_begin:/registry/monitoring.coreos.com/thanosrulers/; range_end:/registry/monitoring.coreos.com/thanosrulers0; response_count:0; response_revision:25525; }","duration":"489.432504ms","start":"2026-05-19T00:57:09.177517Z","end":"2026-05-19T00:57:09.666949Z","steps":["trace[388131699] 'agreement among raft nodes before linearized reading' (duration: 489.371202ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:57:09.666966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.375342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-05-19T00:57:09.667025Z","caller":"traceutil/trace.go:171","msg":"trace[395823288] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25525; }","duration":"311.486445ms","start":"2026-05-19T00:57:09.355522Z","end":"2026-05-19T00:57:09.667009Z","steps":["trace[395823288] 'agreement among raft nodes before linearized reading' (duration: 311.293279ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:57:09.667065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T00:57:09.355491Z","time spent":"311.566937ms","remote":"127.0.0.1:52314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-19T00:57:09.666984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T00:57:09.177501Z","time spent":"489.476925ms","remote":"127.0.0.1:33224","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":29,"request content":"key:\"/registry/monitoring.coreos.com/thanosrulers/\" range_end:\"/registry/monitoring.coreos.com/thanosrulers0\" count_only:true "} {"level":"warn","ts":"2026-05-19T00:57:09.686291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.433126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-05-19T00:57:09.686354Z","caller":"traceutil/trace.go:171","msg":"trace[1621013832] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25528; }","duration":"287.523939ms","start":"2026-05-19T00:57:09.398813Z","end":"2026-05-19T00:57:09.686336Z","steps":["trace[1621013832] 'agreement among raft nodes before linearized reading' (duration: 287.348505ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:57:09.686642Z","caller":"traceutil/trace.go:171","msg":"trace[1770347678] transaction","detail":"{read_only:false; response_revision:25526; number_of_response:1; }","duration":"295.778578ms","start":"2026-05-19T00:57:09.390849Z","end":"2026-05-19T00:57:09.686628Z","steps":["trace[1770347678] 'process raft request' (duration: 295.132721ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:57:09.686777Z","caller":"traceutil/trace.go:171","msg":"trace[1111434344] transaction","detail":"{read_only:false; response_revision:25527; number_of_response:1; }","duration":"219.030647ms","start":"2026-05-19T00:57:09.467735Z","end":"2026-05-19T00:57:09.686766Z","steps":["trace[1111434344] 'process raft request' (duration: 218.328199ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:57:09.687014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.256885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinedrainrules/\" range_end:\"/registry/cluster.x-k8s.io/machinedrainrules0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T00:57:09.687055Z","caller":"traceutil/trace.go:171","msg":"trace[1035113736] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinedrainrules/; range_end:/registry/cluster.x-k8s.io/machinedrainrules0; response_count:0; response_revision:25528; }","duration":"102.344977ms","start":"2026-05-19T00:57:09.584698Z","end":"2026-05-19T00:57:09.687043Z","steps":["trace[1035113736] 'agreement among raft nodes before linearized reading' (duration: 102.277725ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:57:09.83141Z","caller":"traceutil/trace.go:171","msg":"trace[588029480] transaction","detail":"{read_only:false; response_revision:25530; number_of_response:1; }","duration":"159.20234ms","start":"2026-05-19T00:57:09.672165Z","end":"2026-05-19T00:57:09.831367Z","steps":["trace[588029480] 'process raft request' (duration: 159.125798ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:57:09.831893Z","caller":"traceutil/trace.go:171","msg":"trace[1372566953] transaction","detail":"{read_only:false; response_revision:25529; number_of_response:1; }","duration":"160.04326ms","start":"2026-05-19T00:57:09.671824Z","end":"2026-05-19T00:57:09.831868Z","steps":["trace[1372566953] 'process raft request' (duration: 138.870693ms)","trace[1372566953] 'compare' (duration: 20.399429ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T00:57:09.956507Z","caller":"traceutil/trace.go:171","msg":"trace[1723580362] transaction","detail":"{read_only:false; response_revision:25531; number_of_response:1; }","duration":"264.411556ms","start":"2026-05-19T00:57:09.692065Z","end":"2026-05-19T00:57:09.956477Z","steps":["trace[1723580362] 'process raft request' (duration: 263.574056ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:57:10.106706Z","caller":"traceutil/trace.go:171","msg":"trace[1091677294] transaction","detail":"{read_only:false; response_revision:25532; number_of_response:1; }","duration":"144.168349ms","start":"2026-05-19T00:57:09.962503Z","end":"2026-05-19T00:57:10.106671Z","steps":["trace[1091677294] 'process raft request' (duration: 134.680892ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:57:10.532557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.536748ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-19T00:57:10.532728Z","caller":"traceutil/trace.go:171","msg":"trace[60457777] transaction","detail":"{read_only:false; response_revision:25534; number_of_response:1; }","duration":"361.091756ms","start":"2026-05-19T00:57:10.171612Z","end":"2026-05-19T00:57:10.532704Z","steps":["trace[60457777] 'process raft request' (duration: 71.25196ms)","trace[60457777] 'compare' (duration: 289.358483ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T00:57:10.532799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T00:57:10.171585Z","time spent":"361.182177ms","remote":"127.0.0.1:52132","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1377,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T00:57:10.629813Z","caller":"traceutil/trace.go:171","msg":"trace[61982145] transaction","detail":"{read_only:false; response_revision:25535; number_of_response:1; }","duration":"239.611019ms","start":"2026-05-19T00:57:10.390168Z","end":"2026-05-19T00:57:10.629779Z","steps":["trace[61982145] 'process raft request' (duration: 239.361333ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:57:10.784848Z","caller":"traceutil/trace.go:171","msg":"trace[1120427125] linearizableReadLoop","detail":"{readStateIndex:26396; appliedIndex:26394; }","duration":"320.366658ms","start":"2026-05-19T00:57:10.464439Z","end":"2026-05-19T00:57:10.784805Z","steps":["trace[1120427125] 'read index received' (duration: 165.069651ms)","trace[1120427125] 'applied index is now lower than readState.Index' (duration: 155.295327ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T00:57:10.784961Z","caller":"traceutil/trace.go:171","msg":"trace[269750552] transaction","detail":"{read_only:false; response_revision:25536; number_of_response:1; }","duration":"329.898657ms","start":"2026-05-19T00:57:10.455021Z","end":"2026-05-19T00:57:10.78492Z","steps":["trace[269750552] 'process raft request' (duration: 315.646064ms)","trace[269750552] 'compare' (duration: 13.948384ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T00:57:10.785086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.621064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T00:57:10.785152Z","caller":"traceutil/trace.go:171","msg":"trace[297743940] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25536; }","duration":"320.748367ms","start":"2026-05-19T00:57:10.464389Z","end":"2026-05-19T00:57:10.785137Z","steps":["trace[297743940] 'agreement among raft nodes before linearized reading' (duration: 320.594063ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:57:10.78519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T00:57:10.464361Z","time spent":"320.822939ms","remote":"127.0.0.1:52056","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-19T00:57:10.785185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T00:57:10.45499Z","time spent":"330.059711ms","remote":"127.0.0.1:52314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T00:57:15.973041Z","caller":"traceutil/trace.go:171","msg":"trace[1476189679] transaction","detail":"{read_only:false; response_revision:25584; number_of_response:1; }","duration":"107.097061ms","start":"2026-05-19T00:57:15.86591Z","end":"2026-05-19T00:57:15.973007Z","steps":["trace[1476189679] 'process raft request' (duration: 106.909575ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:57:16.435857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.164772ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-19T00:57:16.436009Z","caller":"traceutil/trace.go:171","msg":"trace[199816315] linearizableReadLoop","detail":"{readStateIndex:26446; appliedIndex:26445; }","duration":"437.853366ms","start":"2026-05-19T00:57:15.998135Z","end":"2026-05-19T00:57:16.435988Z","steps":["trace[199816315] 'read index received' (duration: 107.430787ms)","trace[199816315] 'applied index is now lower than readState.Index' (duration: 330.417079ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T00:57:16.436121Z","caller":"traceutil/trace.go:171","msg":"trace[319916635] transaction","detail":"{read_only:false; response_revision:25585; number_of_response:1; }","duration":"569.683021ms","start":"2026-05-19T00:57:15.86642Z","end":"2026-05-19T00:57:16.436103Z","steps":["trace[319916635] 'process raft request' (duration: 239.129469ms)","trace[319916635] 'compare' (duration: 330.006709ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T00:57:16.436195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T00:57:15.866404Z","time spent":"569.753782ms","remote":"127.0.0.1:52314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T00:57:16.436728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.593374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-05-19T00:57:16.43674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.764633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-05-19T00:57:16.436778Z","caller":"traceutil/trace.go:171","msg":"trace[1185909335] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25585; }","duration":"438.683207ms","start":"2026-05-19T00:57:15.99808Z","end":"2026-05-19T00:57:16.436763Z","steps":["trace[1185909335] 'agreement among raft nodes before linearized reading' (duration: 438.479023ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:57:16.436809Z","caller":"traceutil/trace.go:171","msg":"trace[1301903386] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25585; }","duration":"304.887406ms","start":"2026-05-19T00:57:16.131902Z","end":"2026-05-19T00:57:16.43679Z","steps":["trace[1301903386] 'agreement among raft nodes before linearized reading' (duration: 304.663621ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:57:16.436867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T00:57:16.131877Z","time spent":"304.977639ms","remote":"127.0.0.1:52314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-19T00:57:16.436817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T00:57:15.998059Z","time spent":"438.750199ms","remote":"127.0.0.1:52314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-19T00:57:16.904214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.638088ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-19T00:57:16.904538Z","caller":"traceutil/trace.go:171","msg":"trace[430182232] transaction","detail":"{read_only:false; response_revision:25587; number_of_response:1; }","duration":"458.83036ms","start":"2026-05-19T00:57:16.44569Z","end":"2026-05-19T00:57:16.90452Z","steps":["trace[430182232] 'process raft request' (duration: 458.642796ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:57:16.904689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T00:57:16.445669Z","time spent":"458.904642ms","remote":"127.0.0.1:52314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T00:57:16.904546Z","caller":"traceutil/trace.go:171","msg":"trace[409580477] transaction","detail":"{read_only:false; response_revision:25586; number_of_response:1; }","duration":"458.852441ms","start":"2026-05-19T00:57:16.445658Z","end":"2026-05-19T00:57:16.90451Z","steps":["trace[409580477] 'process raft request' (duration: 181.785702ms)","trace[409580477] 'compare' (duration: 276.031044ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T00:57:16.905255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T00:57:16.445637Z","time spent":"459.22183ms","remote":"127.0.0.1:52314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T00:57:17.094169Z","caller":"traceutil/trace.go:171","msg":"trace[1414989167] linearizableReadLoop","detail":"{readStateIndex:26449; appliedIndex:26446; }","duration":"296.67534ms","start":"2026-05-19T00:57:16.797465Z","end":"2026-05-19T00:57:17.09414Z","steps":["trace[1414989167] 'read index received' (duration: 68.491153ms)","trace[1414989167] 'applied index is now lower than readState.Index' (duration: 228.183237ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T00:57:17.094468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.982777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.100\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-05-19T00:57:17.094503Z","caller":"traceutil/trace.go:171","msg":"trace[393587415] range","detail":"{range_begin:/registry/masterleases/162.253.55.100; range_end:; response_count:1; response_revision:25589; }","duration":"297.090819ms","start":"2026-05-19T00:57:16.797401Z","end":"2026-05-19T00:57:17.094492Z","steps":["trace[393587415] 'agreement among raft nodes before linearized reading' (duration: 296.884074ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:57:17.094891Z","caller":"traceutil/trace.go:171","msg":"trace[1782818924] transaction","detail":"{read_only:false; response_revision:25588; number_of_response:1; }","duration":"444.307042ms","start":"2026-05-19T00:57:16.650574Z","end":"2026-05-19T00:57:17.094881Z","steps":["trace[1782818924] 'process raft request' (duration: 443.37134ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:57:17.094997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T00:57:16.650548Z","time spent":"444.371224ms","remote":"127.0.0.1:52314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T00:57:17.095453Z","caller":"traceutil/trace.go:171","msg":"trace[1197738092] transaction","detail":"{read_only:false; response_revision:25589; number_of_response:1; }","duration":"289.506146ms","start":"2026-05-19T00:57:16.805937Z","end":"2026-05-19T00:57:17.095443Z","steps":["trace[1197738092] 'process raft request' (duration: 288.156645ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:57:17.362804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.163211ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-19T00:57:17.363119Z","caller":"traceutil/trace.go:171","msg":"trace[371358916] transaction","detail":"{read_only:false; response_revision:25591; number_of_response:1; }","duration":"423.942173ms","start":"2026-05-19T00:57:16.939156Z","end":"2026-05-19T00:57:17.363098Z","steps":["trace[371358916] 'process raft request' (duration: 423.769419ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:57:17.363201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T00:57:16.939127Z","time spent":"424.035846ms","remote":"127.0.0.1:52314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T00:57:17.363335Z","caller":"traceutil/trace.go:171","msg":"trace[1452609184] transaction","detail":"{read_only:false; response_revision:25590; number_of_response:1; }","duration":"489.170129ms","start":"2026-05-19T00:57:16.874153Z","end":"2026-05-19T00:57:17.363323Z","steps":["trace[1452609184] 'process raft request' (duration: 351.391743ms)","trace[1452609184] 'compare' (duration: 136.383062ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T00:57:17.363389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T00:57:16.874123Z","time spent":"489.23949ms","remote":"127.0.0.1:52314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T00:57:17.363532Z","caller":"traceutil/trace.go:171","msg":"trace[1518605083] transaction","detail":"{read_only:false; response_revision:25592; number_of_response:1; }","duration":"275.210833ms","start":"2026-05-19T00:57:17.08831Z","end":"2026-05-19T00:57:17.363521Z","steps":["trace[1518605083] 'process raft request' (duration: 274.722222ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:57:17.36381Z","caller":"traceutil/trace.go:171","msg":"trace[353962791] linearizableReadLoop","detail":"{readStateIndex:26455; appliedIndex:26450; }","duration":"218.771059ms","start":"2026-05-19T00:57:17.145029Z","end":"2026-05-19T00:57:17.3638Z","steps":["trace[353962791] 'read index received' (duration: 80.589724ms)","trace[353962791] 'applied index is now lower than readState.Index' (duration: 138.180135ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T00:57:17.363903Z","caller":"traceutil/trace.go:171","msg":"trace[1173918360] transaction","detail":"{read_only:false; response_revision:25593; number_of_response:1; }","duration":"275.49331ms","start":"2026-05-19T00:57:17.088401Z","end":"2026-05-19T00:57:17.363894Z","steps":["trace[1173918360] 'process raft request' (duration: 275.07934ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:57:17.364137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.099377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddresses/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddresses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T00:57:17.364178Z","caller":"traceutil/trace.go:171","msg":"trace[1191760041] range","detail":"{range_begin:/registry/ipam.cluster.x-k8s.io/ipaddresses/; range_end:/registry/ipam.cluster.x-k8s.io/ipaddresses0; response_count:0; response_revision:25593; }","duration":"219.157369ms","start":"2026-05-19T00:57:17.145009Z","end":"2026-05-19T00:57:17.364166Z","steps":["trace[1191760041] 'agreement among raft nodes before linearized reading' (duration: 219.090337ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:57:17.690656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.829849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T00:57:17.690778Z","caller":"traceutil/trace.go:171","msg":"trace[891072537] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25594; }","duration":"216.033794ms","start":"2026-05-19T00:57:17.474719Z","end":"2026-05-19T00:57:17.690752Z","steps":["trace[891072537] 'range keys from in-memory index tree' (duration: 215.712556ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:57:17.951025Z","caller":"traceutil/trace.go:171","msg":"trace[338510064] transaction","detail":"{read_only:false; response_revision:25595; number_of_response:1; }","duration":"239.110028ms","start":"2026-05-19T00:57:17.711888Z","end":"2026-05-19T00:57:17.950998Z","steps":["trace[338510064] 'process raft request' (duration: 238.915633ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:57:17.967183Z","caller":"traceutil/trace.go:171","msg":"trace[528242674] transaction","detail":"{read_only:false; response_revision:25596; number_of_response:1; }","duration":"247.890988ms","start":"2026-05-19T00:57:17.719259Z","end":"2026-05-19T00:57:17.96715Z","steps":["trace[528242674] 'process raft request' (duration: 247.578491ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:57:17.967178Z","caller":"traceutil/trace.go:171","msg":"trace[1703990947] linearizableReadLoop","detail":"{readStateIndex:26459; appliedIndex:26456; }","duration":"238.279778ms","start":"2026-05-19T00:57:17.728857Z","end":"2026-05-19T00:57:17.967137Z","steps":["trace[1703990947] 'read index received' (duration: 221.996538ms)","trace[1703990947] 'applied index is now lower than readState.Index' (duration: 16.28187ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T00:57:17.967231Z","caller":"traceutil/trace.go:171","msg":"trace[1445992920] transaction","detail":"{read_only:false; response_revision:25597; number_of_response:1; }","duration":"247.817176ms","start":"2026-05-19T00:57:17.719377Z","end":"2026-05-19T00:57:17.967194Z","steps":["trace[1445992920] 'process raft request' (duration: 247.648812ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:57:17.967247Z","caller":"traceutil/trace.go:171","msg":"trace[742909038] transaction","detail":"{read_only:false; response_revision:25598; number_of_response:1; }","duration":"211.479715ms","start":"2026-05-19T00:57:17.75574Z","end":"2026-05-19T00:57:17.96722Z","steps":["trace[742909038] 'process raft request' (duration: 211.345942ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:57:17.967511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.625468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-19T00:57:17.967553Z","caller":"traceutil/trace.go:171","msg":"trace[1548798482] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25598; }","duration":"238.736789ms","start":"2026-05-19T00:57:17.728807Z","end":"2026-05-19T00:57:17.967543Z","steps":["trace[1548798482] 'agreement among raft nodes before linearized reading' (duration: 238.517573ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:57:17.967572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.469117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-19T00:57:17.96763Z","caller":"traceutil/trace.go:171","msg":"trace[1268648208] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:25598; }","duration":"114.59604ms","start":"2026-05-19T00:57:17.853022Z","end":"2026-05-19T00:57:17.967618Z","steps":["trace[1268648208] 'agreement among raft nodes before linearized reading' (duration: 114.375154ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:57:18.125436Z","caller":"traceutil/trace.go:171","msg":"trace[321244185] linearizableReadLoop","detail":"{readStateIndex:26461; appliedIndex:26460; }","duration":"148.309819ms","start":"2026-05-19T00:57:17.977088Z","end":"2026-05-19T00:57:18.125397Z","steps":["trace[321244185] 'read index received' (duration: 134.149239ms)","trace[321244185] 'applied index is now lower than readState.Index' (duration: 14.15899ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T00:57:18.1255Z","caller":"traceutil/trace.go:171","msg":"trace[1541516383] transaction","detail":"{read_only:false; response_revision:25599; number_of_response:1; }","duration":"152.003857ms","start":"2026-05-19T00:57:17.973453Z","end":"2026-05-19T00:57:18.125457Z","steps":["trace[1541516383] 'process raft request' (duration: 137.93295ms)","trace[1541516383] 'compare' (duration: 13.842762ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T00:57:18.125708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.585425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-05-19T00:57:18.125757Z","caller":"traceutil/trace.go:171","msg":"trace[1209102021] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25599; }","duration":"148.715239ms","start":"2026-05-19T00:57:17.977029Z","end":"2026-05-19T00:57:18.125745Z","steps":["trace[1209102021] 'agreement among raft nodes before linearized reading' (duration: 148.504294ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:57:18.297071Z","caller":"traceutil/trace.go:171","msg":"trace[1858961145] linearizableReadLoop","detail":"{readStateIndex:26462; appliedIndex:26461; }","duration":"159.16047ms","start":"2026-05-19T00:57:18.137881Z","end":"2026-05-19T00:57:18.297041Z","steps":["trace[1858961145] 'read index received' (duration: 158.934674ms)","trace[1858961145] 'applied index is now lower than readState.Index' (duration: 224.606µs)"],"step_count":2} {"level":"warn","ts":"2026-05-19T00:57:18.297312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.411066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/addressscopes/\" range_end:\"/registry/openstack.k-orc.cloud/addressscopes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T00:57:18.297387Z","caller":"traceutil/trace.go:171","msg":"trace[1505948664] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/addressscopes/; range_end:/registry/openstack.k-orc.cloud/addressscopes0; response_count:0; response_revision:25600; }","duration":"159.543109ms","start":"2026-05-19T00:57:18.137819Z","end":"2026-05-19T00:57:18.297362Z","steps":["trace[1505948664] 'agreement among raft nodes before linearized reading' (duration: 159.366975ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:57:18.297862Z","caller":"traceutil/trace.go:171","msg":"trace[703258169] transaction","detail":"{read_only:false; response_revision:25600; number_of_response:1; }","duration":"166.694299ms","start":"2026-05-19T00:57:18.131148Z","end":"2026-05-19T00:57:18.297842Z","steps":["trace[703258169] 'process raft request' (duration: 165.744297ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:57:18.446239Z","caller":"traceutil/trace.go:171","msg":"trace[1767537922] transaction","detail":"{read_only:false; response_revision:25601; number_of_response:1; }","duration":"113.210067ms","start":"2026-05-19T00:57:18.332995Z","end":"2026-05-19T00:57:18.446205Z","steps":["trace[1767537922] 'process raft request' (duration: 110.461221ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:57:18.736413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.834288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:561"} {"level":"info","ts":"2026-05-19T00:57:18.736539Z","caller":"traceutil/trace.go:171","msg":"trace[1654532378] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:25602; }","duration":"173.012572ms","start":"2026-05-19T00:57:18.563497Z","end":"2026-05-19T00:57:18.73651Z","steps":["trace[1654532378] 'range keys from in-memory index tree' (duration: 172.593402ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:57:29.325634Z","caller":"traceutil/trace.go:171","msg":"trace[1743856598] transaction","detail":"{read_only:false; response_revision:25688; number_of_response:1; }","duration":"173.147105ms","start":"2026-05-19T00:57:29.152456Z","end":"2026-05-19T00:57:29.325603Z","steps":["trace[1743856598] 'process raft request' (duration: 172.95311ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:57:29.567328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.260938ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-19T00:57:29.567515Z","caller":"traceutil/trace.go:171","msg":"trace[1939856573] transaction","detail":"{read_only:false; response_revision:25692; number_of_response:1; }","duration":"151.837004ms","start":"2026-05-19T00:57:29.415669Z","end":"2026-05-19T00:57:29.567506Z","steps":["trace[1939856573] 'process raft request' (duration: 151.811323ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:57:29.567509Z","caller":"traceutil/trace.go:171","msg":"trace[2144719094] transaction","detail":"{read_only:false; response_revision:25689; number_of_response:1; }","duration":"414.65343ms","start":"2026-05-19T00:57:29.152831Z","end":"2026-05-19T00:57:29.567484Z","steps":["trace[2144719094] 'process raft request' (duration: 276.173497ms)","trace[2144719094] 'compare' (duration: 138.153645ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T00:57:29.567616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T00:57:29.152796Z","time spent":"414.756043ms","remote":"127.0.0.1:52314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T00:57:29.567718Z","caller":"traceutil/trace.go:171","msg":"trace[1952885542] transaction","detail":"{read_only:false; response_revision:25690; number_of_response:1; }","duration":"154.907706ms","start":"2026-05-19T00:57:29.412803Z","end":"2026-05-19T00:57:29.567711Z","steps":["trace[1952885542] 'process raft request' (duration: 154.611449ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:57:29.567779Z","caller":"traceutil/trace.go:171","msg":"trace[1228114199] transaction","detail":"{read_only:false; response_revision:25691; number_of_response:1; }","duration":"152.884078ms","start":"2026-05-19T00:57:29.414889Z","end":"2026-05-19T00:57:29.567773Z","steps":["trace[1228114199] 'process raft request' (duration: 152.56327ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:57:34.465029Z","caller":"traceutil/trace.go:171","msg":"trace[718133005] linearizableReadLoop","detail":"{readStateIndex:26602; appliedIndex:26601; }","duration":"178.875092ms","start":"2026-05-19T00:57:34.286129Z","end":"2026-05-19T00:57:34.465004Z","steps":["trace[718133005] 'read index received' (duration: 178.666217ms)","trace[718133005] 'applied index is now lower than readState.Index' (duration: 207.785µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T00:57:34.465166Z","caller":"traceutil/trace.go:171","msg":"trace[725718572] transaction","detail":"{read_only:false; response_revision:25737; number_of_response:1; }","duration":"235.901131ms","start":"2026-05-19T00:57:34.229222Z","end":"2026-05-19T00:57:34.465123Z","steps":["trace[725718572] 'process raft request' (duration: 235.576813ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:57:34.465238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.318467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T00:57:34.46527Z","caller":"traceutil/trace.go:171","msg":"trace[1222171018] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:25737; }","duration":"125.380298ms","start":"2026-05-19T00:57:34.33988Z","end":"2026-05-19T00:57:34.465261Z","steps":["trace[1222171018] 'agreement among raft nodes before linearized reading' (duration: 125.267386ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:57:34.465303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.17271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-05-19T00:57:34.465348Z","caller":"traceutil/trace.go:171","msg":"trace[639376202] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:25737; }","duration":"179.230711ms","start":"2026-05-19T00:57:34.286106Z","end":"2026-05-19T00:57:34.465337Z","steps":["trace[639376202] 'agreement among raft nodes before linearized reading' (duration: 179.030336ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:57:34.921045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.577411ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T00:57:34.921166Z","caller":"traceutil/trace.go:171","msg":"trace[823052889] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:25738; }","duration":"127.704964ms","start":"2026-05-19T00:57:34.79344Z","end":"2026-05-19T00:57:34.921145Z","steps":["trace[823052889] 'range keys from in-memory index tree' (duration: 127.559211ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:57:34.921412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.370895ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-19T00:57:34.921752Z","caller":"traceutil/trace.go:171","msg":"trace[1782066262] linearizableReadLoop","detail":"{readStateIndex:26604; appliedIndex:26602; }","duration":"435.593273ms","start":"2026-05-19T00:57:34.486136Z","end":"2026-05-19T00:57:34.92173Z","steps":["trace[1782066262] 'read index received' (duration: 700.587µs)","trace[1782066262] 'applied index is now lower than readState.Index' (duration: 434.890686ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T00:57:34.92182Z","caller":"traceutil/trace.go:171","msg":"trace[164164855] transaction","detail":"{read_only:false; response_revision:25739; number_of_response:1; }","duration":"450.624173ms","start":"2026-05-19T00:57:34.471162Z","end":"2026-05-19T00:57:34.921786Z","steps":["trace[164164855] 'process raft request' (duration: 295.761736ms)","trace[164164855] 'compare' (duration: 154.226011ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T00:57:34.921925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.783607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T00:57:34.922009Z","caller":"traceutil/trace.go:171","msg":"trace[564243032] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:25739; }","duration":"435.874569ms","start":"2026-05-19T00:57:34.486114Z","end":"2026-05-19T00:57:34.921989Z","steps":["trace[564243032] 'agreement among raft nodes before linearized reading' (duration: 435.720085ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:57:34.922009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.15536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-19T00:57:34.922009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T00:57:34.471141Z","time spent":"450.769447ms","remote":"127.0.0.1:52314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T00:57:34.922042Z","caller":"traceutil/trace.go:171","msg":"trace[109376109] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:25739; }","duration":"357.211162ms","start":"2026-05-19T00:57:34.56482Z","end":"2026-05-19T00:57:34.922031Z","steps":["trace[109376109] 'agreement among raft nodes before linearized reading' (duration: 357.156011ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:57:34.922084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T00:57:34.564805Z","time spent":"357.270743ms","remote":"127.0.0.1:52438","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":29,"request content":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true "} {"level":"warn","ts":"2026-05-19T00:57:34.922109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.726427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"warn","ts":"2026-05-19T00:57:34.922045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T00:57:34.486041Z","time spent":"435.994852ms","remote":"127.0.0.1:51070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true "} {"level":"info","ts":"2026-05-19T00:57:34.922151Z","caller":"traceutil/trace.go:171","msg":"trace[747042297] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25739; }","duration":"340.812909ms","start":"2026-05-19T00:57:34.581331Z","end":"2026-05-19T00:57:34.922144Z","steps":["trace[747042297] 'agreement among raft nodes before linearized reading' (duration: 340.620404ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:57:34.922242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T00:57:34.581297Z","time spent":"340.933202ms","remote":"127.0.0.1:52314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-19T00:57:34.921947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.789754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T00:57:34.922489Z","caller":"traceutil/trace.go:171","msg":"trace[1980388570] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:25739; }","duration":"395.397988ms","start":"2026-05-19T00:57:34.52704Z","end":"2026-05-19T00:57:34.922438Z","steps":["trace[1980388570] 'agreement among raft nodes before linearized reading' (duration: 394.860275ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:57:34.922753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T00:57:34.527017Z","time spent":"395.631374ms","remote":"127.0.0.1:50918","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"warn","ts":"2026-05-19T00:57:35.183102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.198838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-05-19T00:57:35.183219Z","caller":"traceutil/trace.go:171","msg":"trace[2095244386] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25740; }","duration":"161.405403ms","start":"2026-05-19T00:57:35.021791Z","end":"2026-05-19T00:57:35.183196Z","steps":["trace[2095244386] 'range keys from in-memory index tree' (duration: 160.978293ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:57:35.18351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.922936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:128320"} {"level":"warn","ts":"2026-05-19T00:57:35.183513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.587347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-05-19T00:57:35.18357Z","caller":"traceutil/trace.go:171","msg":"trace[391967211] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:25740; }","duration":"157.014077ms","start":"2026-05-19T00:57:35.026542Z","end":"2026-05-19T00:57:35.183556Z","steps":["trace[391967211] 'range keys from in-memory index tree' (duration: 156.555447ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:57:35.183627Z","caller":"traceutil/trace.go:171","msg":"trace[1799235979] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25740; }","duration":"161.72316ms","start":"2026-05-19T00:57:35.021878Z","end":"2026-05-19T00:57:35.183601Z","steps":["trace[1799235979] 'range keys from in-memory index tree' (duration: 161.424264ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:57:35.183622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.516186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" ","response":"range_response_count:89 size:117469"} {"level":"info","ts":"2026-05-19T00:57:35.183695Z","caller":"traceutil/trace.go:171","msg":"trace[905243822] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:89; response_revision:25740; }","duration":"161.616448ms","start":"2026-05-19T00:57:35.022063Z","end":"2026-05-19T00:57:35.18368Z","steps":["trace[905243822] 'range keys from in-memory index tree' (duration: 160.987444ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:57:35.51416Z","caller":"traceutil/trace.go:171","msg":"trace[488200584] transaction","detail":"{read_only:false; response_revision:25741; number_of_response:1; }","duration":"325.098721ms","start":"2026-05-19T00:57:35.189001Z","end":"2026-05-19T00:57:35.514099Z","steps":["trace[488200584] 'process raft request' (duration: 323.685468ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:57:35.514186Z","caller":"traceutil/trace.go:171","msg":"trace[690081979] linearizableReadLoop","detail":"{readStateIndex:26606; appliedIndex:26605; }","duration":"324.726583ms","start":"2026-05-19T00:57:35.18934Z","end":"2026-05-19T00:57:35.514066Z","steps":["trace[690081979] 'read index received' (duration: 323.174006ms)","trace[690081979] 'applied index is now lower than readState.Index' (duration: 1.539466ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T00:57:35.514775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T00:57:35.188977Z","time spent":"325.579342ms","remote":"127.0.0.1:52314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T00:57:35.515023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.656554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" ","response":"range_response_count:89 size:117469"} {"level":"info","ts":"2026-05-19T00:57:35.515107Z","caller":"traceutil/trace.go:171","msg":"trace[1770040167] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:89; response_revision:25741; }","duration":"325.804688ms","start":"2026-05-19T00:57:35.189284Z","end":"2026-05-19T00:57:35.515089Z","steps":["trace[1770040167] 'agreement among raft nodes before linearized reading' (duration: 324.890616ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:57:35.515218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T00:57:35.189265Z","time spent":"325.9362ms","remote":"127.0.0.1:52262","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":89,"response size":117492,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" "} {"level":"info","ts":"2026-05-19T00:57:35.552013Z","caller":"traceutil/trace.go:171","msg":"trace[1249509128] transaction","detail":"{read_only:false; response_revision:25742; number_of_response:1; }","duration":"362.481609ms","start":"2026-05-19T00:57:35.189504Z","end":"2026-05-19T00:57:35.551986Z","steps":["trace[1249509128] 'process raft request' (duration: 361.921555ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:57:35.552357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T00:57:35.189496Z","time spent":"362.594521ms","remote":"127.0.0.1:52314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T00:57:35.552715Z","caller":"traceutil/trace.go:171","msg":"trace[493525298] transaction","detail":"{read_only:false; response_revision:25743; number_of_response:1; }","duration":"202.53991ms","start":"2026-05-19T00:57:35.350158Z","end":"2026-05-19T00:57:35.552698Z","steps":["trace[493525298] 'process raft request' (duration: 201.476933ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:57:35.55312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.826141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstores/\" range_end:\"/registry/ceph.rook.io/cephobjectstores0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-19T00:57:35.553169Z","caller":"traceutil/trace.go:171","msg":"trace[1853095377] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstores/; range_end:/registry/ceph.rook.io/cephobjectstores0; response_count:0; response_revision:25743; }","duration":"118.902052ms","start":"2026-05-19T00:57:35.434253Z","end":"2026-05-19T00:57:35.553155Z","steps":["trace[1853095377] 'agreement among raft nodes before linearized reading' (duration: 118.823921ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:57:35.55359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.753039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T00:57:35.553633Z","caller":"traceutil/trace.go:171","msg":"trace[733460202] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:25743; }","duration":"158.79937ms","start":"2026-05-19T00:57:35.394822Z","end":"2026-05-19T00:57:35.553621Z","steps":["trace[733460202] 'agreement among raft nodes before linearized reading' (duration: 158.728809ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:57:35.554113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.755518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:119"} {"level":"info","ts":"2026-05-19T00:57:35.554159Z","caller":"traceutil/trace.go:171","msg":"trace[1214839906] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:25743; }","duration":"363.834061ms","start":"2026-05-19T00:57:35.190313Z","end":"2026-05-19T00:57:35.554148Z","steps":["trace[1214839906] 'agreement among raft nodes before linearized reading' (duration: 363.702088ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:57:35.554599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T00:57:35.190296Z","time spent":"364.291142ms","remote":"127.0.0.1:52128","response type":"/etcdserverpb.KV/Range","request count":0,"request size":35,"response count":1,"response size":142,"request content":"key:\"/registry/ranges/servicenodeports\" "} {"level":"info","ts":"2026-05-19T00:57:35.854404Z","caller":"traceutil/trace.go:171","msg":"trace[1551732689] transaction","detail":"{read_only:false; response_revision:25744; number_of_response:1; }","duration":"261.530377ms","start":"2026-05-19T00:57:35.592839Z","end":"2026-05-19T00:57:35.85437Z","steps":["trace[1551732689] 'process raft request' (duration: 260.132063ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:57:35.918094Z","caller":"traceutil/trace.go:171","msg":"trace[289747978] linearizableReadLoop","detail":"{readStateIndex:26612; appliedIndex:26608; }","duration":"210.662625ms","start":"2026-05-19T00:57:35.707405Z","end":"2026-05-19T00:57:35.918068Z","steps":["trace[289747978] 'read index received' (duration: 145.756767ms)","trace[289747978] 'applied index is now lower than readState.Index' (duration: 64.904718ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T00:57:35.918523Z","caller":"traceutil/trace.go:171","msg":"trace[1689816835] transaction","detail":"{read_only:false; response_revision:25745; number_of_response:1; }","duration":"325.506661ms","start":"2026-05-19T00:57:35.593007Z","end":"2026-05-19T00:57:35.918514Z","steps":["trace[1689816835] 'process raft request' (duration: 324.765164ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:57:35.91863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T00:57:35.592976Z","time spent":"325.571612ms","remote":"127.0.0.1:52314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T00:57:35.918772Z","caller":"traceutil/trace.go:171","msg":"trace[732737989] transaction","detail":"{read_only:false; response_revision:25746; number_of_response:1; }","duration":"325.200574ms","start":"2026-05-19T00:57:35.593563Z","end":"2026-05-19T00:57:35.918764Z","steps":["trace[732737989] 'process raft request' (duration: 324.372254ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:57:35.918813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T00:57:35.593556Z","time spent":"325.239876ms","remote":"127.0.0.1:52314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T00:57:35.91908Z","caller":"traceutil/trace.go:171","msg":"trace[785660493] transaction","detail":"{read_only:false; response_revision:25747; number_of_response:1; }","duration":"318.196666ms","start":"2026-05-19T00:57:35.600877Z","end":"2026-05-19T00:57:35.919073Z","steps":["trace[785660493] 'process raft request' (duration: 317.15441ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:57:35.919117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T00:57:35.60085Z","time spent":"318.250607ms","remote":"127.0.0.1:52314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T00:57:35.919249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.834023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T00:57:35.919278Z","caller":"traceutil/trace.go:171","msg":"trace[1186993894] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:0; response_revision:25747; }","duration":"211.907675ms","start":"2026-05-19T00:57:35.707362Z","end":"2026-05-19T00:57:35.91927Z","steps":["trace[1186993894] 'agreement among raft nodes before linearized reading' (duration: 211.847714ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:57:35.91928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.959456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/schemareplications/\" range_end:\"/registry/rabbitmq.com/schemareplications0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T00:57:35.921467Z","caller":"traceutil/trace.go:171","msg":"trace[1244378002] range","detail":"{range_begin:/registry/rabbitmq.com/schemareplications/; range_end:/registry/rabbitmq.com/schemareplications0; response_count:0; response_revision:25747; }","duration":"121.200798ms","start":"2026-05-19T00:57:35.800205Z","end":"2026-05-19T00:57:35.921405Z","steps":["trace[1244378002] 'agreement among raft nodes before linearized reading' (duration: 118.591146ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:57:36.246484Z","caller":"traceutil/trace.go:171","msg":"trace[869350433] transaction","detail":"{read_only:false; response_revision:25748; number_of_response:1; }","duration":"205.607525ms","start":"2026-05-19T00:57:36.040847Z","end":"2026-05-19T00:57:36.246454Z","steps":["trace[869350433] 'process raft request' (duration: 186.146108ms)","trace[869350433] 'compare' (duration: 19.327833ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T00:57:36.269438Z","caller":"traceutil/trace.go:171","msg":"trace[2101358127] transaction","detail":"{read_only:false; response_revision:25749; number_of_response:1; }","duration":"224.711092ms","start":"2026-05-19T00:57:36.044706Z","end":"2026-05-19T00:57:36.269417Z","steps":["trace[2101358127] 'process raft request' (duration: 224.429246ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:57:36.269418Z","caller":"traceutil/trace.go:171","msg":"trace[945601778] transaction","detail":"{read_only:false; response_revision:25750; number_of_response:1; }","duration":"223.060333ms","start":"2026-05-19T00:57:36.046323Z","end":"2026-05-19T00:57:36.269383Z","steps":["trace[945601778] 'process raft request' (duration: 223.005561ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:57:36.853059Z","caller":"traceutil/trace.go:171","msg":"trace[1056074931] linearizableReadLoop","detail":"{readStateIndex:26617; appliedIndex:26616; }","duration":"253.033712ms","start":"2026-05-19T00:57:36.599999Z","end":"2026-05-19T00:57:36.853032Z","steps":["trace[1056074931] 'read index received' (duration: 252.809607ms)","trace[1056074931] 'applied index is now lower than readState.Index' (duration: 222.845µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T00:57:36.853187Z","caller":"traceutil/trace.go:171","msg":"trace[1984488797] transaction","detail":"{read_only:false; response_revision:25752; number_of_response:1; }","duration":"345.368077ms","start":"2026-05-19T00:57:36.507769Z","end":"2026-05-19T00:57:36.853138Z","steps":["trace[1984488797] 'process raft request' (duration: 345.04922ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:57:36.853388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.365789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T00:57:36.85347Z","caller":"traceutil/trace.go:171","msg":"trace[986365729] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:25752; }","duration":"253.504323ms","start":"2026-05-19T00:57:36.599949Z","end":"2026-05-19T00:57:36.853454Z","steps":["trace[986365729] 'agreement among raft nodes before linearized reading' (duration: 253.360609ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:57:36.853417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T00:57:36.507743Z","time spent":"345.553552ms","remote":"127.0.0.1:52314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T00:57:37.304116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.196477ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-19T00:57:37.30435Z","caller":"traceutil/trace.go:171","msg":"trace[928406835] linearizableReadLoop","detail":"{readStateIndex:26619; appliedIndex:26618; }","duration":"278.784349ms","start":"2026-05-19T00:57:37.025545Z","end":"2026-05-19T00:57:37.304329Z","steps":["trace[928406835] 'read index received' (duration: 36.771µs)","trace[928406835] 'applied index is now lower than readState.Index' (duration: 278.745628ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T00:57:37.304491Z","caller":"traceutil/trace.go:171","msg":"trace[234909474] transaction","detail":"{read_only:false; response_revision:25753; number_of_response:1; }","duration":"426.31633ms","start":"2026-05-19T00:57:36.878161Z","end":"2026-05-19T00:57:37.304478Z","steps":["trace[234909474] 'process raft request' (duration: 134.645091ms)","trace[234909474] 'compare' (duration: 291.009793ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T00:57:37.304628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T00:57:36.878134Z","time spent":"426.431693ms","remote":"127.0.0.1:52084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":121,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T00:57:37.304706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.144737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"warn","ts":"2026-05-19T00:57:37.304695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.323193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T00:57:37.304772Z","caller":"traceutil/trace.go:171","msg":"trace[1312115840] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25753; }","duration":"279.26064ms","start":"2026-05-19T00:57:37.025494Z","end":"2026-05-19T00:57:37.304755Z","steps":["trace[1312115840] 'agreement among raft nodes before linearized reading' (duration: 279.002254ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:57:37.304793Z","caller":"traceutil/trace.go:171","msg":"trace[1133413827] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:0; response_revision:25753; }","duration":"134.468686ms","start":"2026-05-19T00:57:37.1703Z","end":"2026-05-19T00:57:37.304769Z","steps":["trace[1133413827] 'agreement among raft nodes before linearized reading' (duration: 134.20015ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:57:37.304948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.98658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/roles/\" range_end:\"/registry/openstack.k-orc.cloud/roles0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T00:57:37.305008Z","caller":"traceutil/trace.go:171","msg":"trace[184593020] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/roles/; range_end:/registry/openstack.k-orc.cloud/roles0; response_count:0; response_revision:25753; }","duration":"103.052062ms","start":"2026-05-19T00:57:37.201938Z","end":"2026-05-19T00:57:37.30499Z","steps":["trace[184593020] 'agreement among raft nodes before linearized reading' (duration: 102.822296ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:57:37.537492Z","caller":"traceutil/trace.go:171","msg":"trace[371277112] transaction","detail":"{read_only:false; response_revision:25756; number_of_response:1; }","duration":"199.330543ms","start":"2026-05-19T00:57:37.338125Z","end":"2026-05-19T00:57:37.537456Z","steps":["trace[371277112] 'process raft request' (duration: 165.061181ms)","trace[371277112] 'compare' (duration: 33.76875ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T00:57:37.867091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.207022ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-19T00:57:37.867497Z","caller":"traceutil/trace.go:171","msg":"trace[2141218553] linearizableReadLoop","detail":"{readStateIndex:26625; appliedIndex:26622; }","duration":"300.720106ms","start":"2026-05-19T00:57:37.566762Z","end":"2026-05-19T00:57:37.867482Z","steps":["trace[2141218553] 'read index received' (duration: 143.075463ms)","trace[2141218553] 'applied index is now lower than readState.Index' (duration: 157.643263ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T00:57:37.867518Z","caller":"traceutil/trace.go:171","msg":"trace[616374920] transaction","detail":"{read_only:false; response_revision:25757; number_of_response:1; }","duration":"326.009653ms","start":"2026-05-19T00:57:37.541445Z","end":"2026-05-19T00:57:37.867454Z","steps":["trace[616374920] 'process raft request' (duration: 168.34197ms)","trace[616374920] 'compare' (duration: 156.195398ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T00:57:37.867911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T00:57:37.541422Z","time spent":"326.205427ms","remote":"127.0.0.1:52314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T00:57:37.868549Z","caller":"traceutil/trace.go:171","msg":"trace[135070631] transaction","detail":"{read_only:false; response_revision:25758; number_of_response:1; }","duration":"324.61947ms","start":"2026-05-19T00:57:37.543919Z","end":"2026-05-19T00:57:37.868539Z","steps":["trace[135070631] 'process raft request' (duration: 323.274377ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:57:37.868679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T00:57:37.543911Z","time spent":"324.687291ms","remote":"127.0.0.1:44310","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2793,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T00:57:37.869087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.323084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-05-19T00:57:37.869058Z","caller":"traceutil/trace.go:171","msg":"trace[1220664237] transaction","detail":"{read_only:false; response_revision:25759; number_of_response:1; }","duration":"307.864878ms","start":"2026-05-19T00:57:37.56114Z","end":"2026-05-19T00:57:37.869005Z","steps":["trace[1220664237] 'process raft request' (duration: 306.290979ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:57:37.869119Z","caller":"traceutil/trace.go:171","msg":"trace[285678516] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25759; }","duration":"302.368756ms","start":"2026-05-19T00:57:37.566739Z","end":"2026-05-19T00:57:37.869108Z","steps":["trace[285678516] 'agreement among raft nodes before linearized reading' (duration: 302.261113ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:57:37.869137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T00:57:37.56673Z","time spent":"302.403937ms","remote":"127.0.0.1:52314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-19T00:57:37.869229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T00:57:37.561128Z","time spent":"308.008901ms","remote":"127.0.0.1:52314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T00:57:37.878219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.324766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-19T00:57:37.878277Z","caller":"traceutil/trace.go:171","msg":"trace[1242866148] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:25760; }","duration":"102.435349ms","start":"2026-05-19T00:57:37.775829Z","end":"2026-05-19T00:57:37.878264Z","steps":["trace[1242866148] 'agreement among raft nodes before linearized reading' (duration: 102.335316ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:57:38.172383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.335144ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-19T00:57:38.172649Z","caller":"traceutil/trace.go:171","msg":"trace[1417315967] transaction","detail":"{read_only:false; response_revision:25761; number_of_response:1; }","duration":"294.1994ms","start":"2026-05-19T00:57:37.878382Z","end":"2026-05-19T00:57:38.172581Z","steps":["trace[1417315967] 'process raft request' (duration: 149.543099ms)","trace[1417315967] 'compare' (duration: 143.408772ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T00:57:38.1749Z","caller":"traceutil/trace.go:171","msg":"trace[1243818034] linearizableReadLoop","detail":"{readStateIndex:26631; appliedIndex:26626; }","duration":"246.608738ms","start":"2026-05-19T00:57:37.928275Z","end":"2026-05-19T00:57:38.174884Z","steps":["trace[1243818034] 'read index received' (duration: 99.596829ms)","trace[1243818034] 'applied index is now lower than readState.Index' (duration: 147.011129ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T00:57:38.17507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.793832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-19T00:57:38.175112Z","caller":"traceutil/trace.go:171","msg":"trace[203849047] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:25766; }","duration":"246.847124ms","start":"2026-05-19T00:57:37.928254Z","end":"2026-05-19T00:57:38.175101Z","steps":["trace[203849047] 'agreement among raft nodes before linearized reading' (duration: 246.70402ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:57:38.175396Z","caller":"traceutil/trace.go:171","msg":"trace[278779422] transaction","detail":"{read_only:false; response_revision:25762; number_of_response:1; }","duration":"292.193301ms","start":"2026-05-19T00:57:37.88319Z","end":"2026-05-19T00:57:38.175383Z","steps":["trace[278779422] 'process raft request' (duration: 291.371572ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:57:38.175664Z","caller":"traceutil/trace.go:171","msg":"trace[775176818] transaction","detail":"{read_only:false; response_revision:25763; number_of_response:1; }","duration":"249.926097ms","start":"2026-05-19T00:57:37.925726Z","end":"2026-05-19T00:57:38.175652Z","steps":["trace[775176818] 'process raft request' (duration: 248.950594ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:57:38.175815Z","caller":"traceutil/trace.go:171","msg":"trace[530241087] transaction","detail":"{read_only:false; response_revision:25764; number_of_response:1; }","duration":"249.596199ms","start":"2026-05-19T00:57:37.926209Z","end":"2026-05-19T00:57:38.175805Z","steps":["trace[530241087] 'process raft request' (duration: 248.533634ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:57:38.175952Z","caller":"traceutil/trace.go:171","msg":"trace[219325142] transaction","detail":"{read_only:false; response_revision:25765; number_of_response:1; }","duration":"249.647721ms","start":"2026-05-19T00:57:37.926295Z","end":"2026-05-19T00:57:38.175942Z","steps":["trace[219325142] 'process raft request' (duration: 248.502694ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:57:38.176092Z","caller":"traceutil/trace.go:171","msg":"trace[950481893] transaction","detail":"{read_only:false; response_revision:25766; number_of_response:1; }","duration":"158.426241ms","start":"2026-05-19T00:57:38.017653Z","end":"2026-05-19T00:57:38.176079Z","steps":["trace[950481893] 'process raft request' (duration: 157.183321ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:57:38.435752Z","caller":"traceutil/trace.go:171","msg":"trace[1587887749] transaction","detail":"{read_only:false; response_revision:25771; number_of_response:1; }","duration":"176.830363ms","start":"2026-05-19T00:57:38.258868Z","end":"2026-05-19T00:57:38.435699Z","steps":["trace[1587887749] 'process raft request' (duration: 149.132818ms)","trace[1587887749] 'compare' (duration: 27.49567ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T00:57:38.522062Z","caller":"traceutil/trace.go:171","msg":"trace[479749728] transaction","detail":"{read_only:false; response_revision:25772; number_of_response:1; }","duration":"259.063057ms","start":"2026-05-19T00:57:38.262965Z","end":"2026-05-19T00:57:38.522028Z","steps":["trace[479749728] 'process raft request' (duration: 258.808051ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:57:38.522117Z","caller":"traceutil/trace.go:171","msg":"trace[1400289648] transaction","detail":"{read_only:false; response_revision:25773; number_of_response:1; }","duration":"247.326364ms","start":"2026-05-19T00:57:38.274772Z","end":"2026-05-19T00:57:38.522098Z","steps":["trace[1400289648] 'process raft request' (duration: 247.16068ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:57:38.522393Z","caller":"traceutil/trace.go:171","msg":"trace[1666498928] linearizableReadLoop","detail":"{readStateIndex:26640; appliedIndex:26637; }","duration":"114.460666ms","start":"2026-05-19T00:57:38.407894Z","end":"2026-05-19T00:57:38.522355Z","steps":["trace[1666498928] 'read index received' (duration: 113.962095ms)","trace[1666498928] 'applied index is now lower than readState.Index' (duration: 497.131µs)"],"step_count":2} {"level":"info","ts":"2026-05-19T00:57:38.522519Z","caller":"traceutil/trace.go:171","msg":"trace[349450486] transaction","detail":"{read_only:false; response_revision:25774; number_of_response:1; }","duration":"245.322587ms","start":"2026-05-19T00:57:38.277185Z","end":"2026-05-19T00:57:38.522508Z","steps":["trace[349450486] 'process raft request' (duration: 244.803615ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:57:38.522645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.724723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T00:57:38.522739Z","caller":"traceutil/trace.go:171","msg":"trace[1232056418] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:25774; }","duration":"114.880597ms","start":"2026-05-19T00:57:38.407835Z","end":"2026-05-19T00:57:38.522715Z","steps":["trace[1232056418] 'agreement among raft nodes before linearized reading' (duration: 114.735834ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:57:40.299409Z","caller":"traceutil/trace.go:171","msg":"trace[1683959456] linearizableReadLoop","detail":"{readStateIndex:26705; appliedIndex:26704; }","duration":"116.204468ms","start":"2026-05-19T00:57:40.183182Z","end":"2026-05-19T00:57:40.299386Z","steps":["trace[1683959456] 'read index received' (duration: 115.976243ms)","trace[1683959456] 'applied index is now lower than readState.Index' (duration: 227.055µs)"],"step_count":2} {"level":"warn","ts":"2026-05-19T00:57:40.299638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.448653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-05-19T00:57:40.299672Z","caller":"traceutil/trace.go:171","msg":"trace[873152176] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25839; }","duration":"116.547026ms","start":"2026-05-19T00:57:40.183113Z","end":"2026-05-19T00:57:40.29966Z","steps":["trace[873152176] 'agreement among raft nodes before linearized reading' (duration: 116.369131ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:57:40.300011Z","caller":"traceutil/trace.go:171","msg":"trace[1451985547] transaction","detail":"{read_only:false; response_revision:25839; number_of_response:1; }","duration":"143.36304ms","start":"2026-05-19T00:57:40.156637Z","end":"2026-05-19T00:57:40.3Z","steps":["trace[1451985547] 'process raft request' (duration: 142.593212ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:57:40.366761Z","caller":"traceutil/trace.go:171","msg":"trace[404917341] transaction","detail":"{read_only:false; response_revision:25840; number_of_response:1; }","duration":"178.718697ms","start":"2026-05-19T00:57:40.188015Z","end":"2026-05-19T00:57:40.366734Z","steps":["trace[404917341] 'process raft request' (duration: 176.850763ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:57:40.366906Z","caller":"traceutil/trace.go:171","msg":"trace[111384273] transaction","detail":"{read_only:false; response_revision:25841; number_of_response:1; }","duration":"178.840001ms","start":"2026-05-19T00:57:40.188042Z","end":"2026-05-19T00:57:40.366882Z","steps":["trace[111384273] 'process raft request' (duration: 178.648436ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:57:40.367343Z","caller":"traceutil/trace.go:171","msg":"trace[825026059] transaction","detail":"{read_only:false; response_revision:25842; number_of_response:1; }","duration":"178.3183ms","start":"2026-05-19T00:57:40.189012Z","end":"2026-05-19T00:57:40.36733Z","steps":["trace[825026059] 'process raft request' (duration: 177.835957ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:57:42.530995Z","caller":"traceutil/trace.go:171","msg":"trace[1109237618] transaction","detail":"{read_only:false; response_revision:25859; number_of_response:1; }","duration":"157.718284ms","start":"2026-05-19T00:57:42.373241Z","end":"2026-05-19T00:57:42.530959Z","steps":["trace[1109237618] 'process raft request' (duration: 153.511203ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:57:42.531014Z","caller":"traceutil/trace.go:171","msg":"trace[2069929382] transaction","detail":"{read_only:false; response_revision:25860; number_of_response:1; }","duration":"157.661823ms","start":"2026-05-19T00:57:42.373309Z","end":"2026-05-19T00:57:42.53097Z","steps":["trace[2069929382] 'process raft request' (duration: 157.54154ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:57:42.531119Z","caller":"traceutil/trace.go:171","msg":"trace[703317497] transaction","detail":"{read_only:false; response_revision:25861; number_of_response:1; }","duration":"153.625646ms","start":"2026-05-19T00:57:42.377467Z","end":"2026-05-19T00:57:42.531092Z","steps":["trace[703317497] 'process raft request' (duration: 153.467073ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:57:42.68508Z","caller":"traceutil/trace.go:171","msg":"trace[48196583] transaction","detail":"{read_only:false; response_revision:25862; number_of_response:1; }","duration":"150.538803ms","start":"2026-05-19T00:57:42.534491Z","end":"2026-05-19T00:57:42.68503Z","steps":["trace[48196583] 'process raft request' (duration: 139.640521ms)","trace[48196583] 'compare' (duration: 10.698557ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T00:57:42.72487Z","caller":"traceutil/trace.go:171","msg":"trace[270868095] transaction","detail":"{read_only:false; response_revision:25863; number_of_response:1; }","duration":"189.395395ms","start":"2026-05-19T00:57:42.535439Z","end":"2026-05-19T00:57:42.724834Z","steps":["trace[270868095] 'process raft request' (duration: 189.113419ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:57:42.725055Z","caller":"traceutil/trace.go:171","msg":"trace[1163144312] linearizableReadLoop","detail":"{readStateIndex:26731; appliedIndex:26728; }","duration":"180.303797ms","start":"2026-05-19T00:57:42.544707Z","end":"2026-05-19T00:57:42.725011Z","steps":["trace[1163144312] 'read index received' (duration: 129.440466ms)","trace[1163144312] 'applied index is now lower than readState.Index' (duration: 50.860291ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T00:57:42.725131Z","caller":"traceutil/trace.go:171","msg":"trace[963421849] transaction","detail":"{read_only:false; response_revision:25864; number_of_response:1; }","duration":"189.20459ms","start":"2026-05-19T00:57:42.53591Z","end":"2026-05-19T00:57:42.725115Z","steps":["trace[963421849] 'process raft request' (duration: 188.847552ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:57:42.725417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.678885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-19T00:57:42.725471Z","caller":"traceutil/trace.go:171","msg":"trace[440292152] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25864; }","duration":"180.775017ms","start":"2026-05-19T00:57:42.544681Z","end":"2026-05-19T00:57:42.725456Z","steps":["trace[440292152] 'agreement among raft nodes before linearized reading' (duration: 180.536811ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:57:47.482241Z","caller":"traceutil/trace.go:171","msg":"trace[1513580082] transaction","detail":"{read_only:false; response_revision:25918; number_of_response:1; }","duration":"107.656553ms","start":"2026-05-19T00:57:47.374554Z","end":"2026-05-19T00:57:47.482211Z","steps":["trace[1513580082] 'process raft request' (duration: 107.437538ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:57:47.633118Z","caller":"traceutil/trace.go:171","msg":"trace[192784846] linearizableReadLoop","detail":"{readStateIndex:26787; appliedIndex:26786; }","duration":"115.803598ms","start":"2026-05-19T00:57:47.517286Z","end":"2026-05-19T00:57:47.63309Z","steps":["trace[192784846] 'read index received' (duration: 86.448324ms)","trace[192784846] 'applied index is now lower than readState.Index' (duration: 29.353734ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T00:57:47.633214Z","caller":"traceutil/trace.go:171","msg":"trace[1722556946] transaction","detail":"{read_only:false; response_revision:25919; number_of_response:1; }","duration":"237.297294ms","start":"2026-05-19T00:57:47.395876Z","end":"2026-05-19T00:57:47.633173Z","steps":["trace[1722556946] 'process raft request' (duration: 207.885769ms)","trace[1722556946] 'compare' (duration: 29.17827ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T00:57:47.633346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.023874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T00:57:47.63341Z","caller":"traceutil/trace.go:171","msg":"trace[341619533] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:25919; }","duration":"116.151256ms","start":"2026-05-19T00:57:47.517243Z","end":"2026-05-19T00:57:47.633394Z","steps":["trace[341619533] 'agreement among raft nodes before linearized reading' (duration: 116.018173ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:57:48.050506Z","caller":"traceutil/trace.go:171","msg":"trace[1300338030] transaction","detail":"{read_only:false; response_revision:25920; number_of_response:1; }","duration":"132.305814ms","start":"2026-05-19T00:57:47.917977Z","end":"2026-05-19T00:57:48.050283Z","steps":["trace[1300338030] 'process raft request' (duration: 132.039138ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:57:48.128695Z","caller":"traceutil/trace.go:171","msg":"trace[753308441] linearizableReadLoop","detail":"{readStateIndex:26789; appliedIndex:26787; }","duration":"202.192771ms","start":"2026-05-19T00:57:47.926463Z","end":"2026-05-19T00:57:48.128656Z","steps":["trace[753308441] 'read index received' (duration: 123.606875ms)","trace[753308441] 'applied index is now lower than readState.Index' (duration: 78.584666ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T00:57:48.128723Z","caller":"traceutil/trace.go:171","msg":"trace[2127112942] transaction","detail":"{read_only:false; response_revision:25921; number_of_response:1; }","duration":"206.713791ms","start":"2026-05-19T00:57:47.921968Z","end":"2026-05-19T00:57:48.128682Z","steps":["trace[2127112942] 'process raft request' (duration: 203.546675ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:57:48.128944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.455788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-05-19T00:57:48.128992Z","caller":"traceutil/trace.go:171","msg":"trace[942039874] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25921; }","duration":"202.5645ms","start":"2026-05-19T00:57:47.926419Z","end":"2026-05-19T00:57:48.128983Z","steps":["trace[942039874] 'agreement among raft nodes before linearized reading' (duration: 202.367065ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:58:09.77323Z","caller":"traceutil/trace.go:171","msg":"trace[1594681335] transaction","detail":"{read_only:false; response_revision:26109; number_of_response:1; }","duration":"140.239805ms","start":"2026-05-19T00:58:09.632955Z","end":"2026-05-19T00:58:09.773195Z","steps":["trace[1594681335] 'process raft request' (duration: 140.041191ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:58:10.098709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.110877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T00:58:10.098812Z","caller":"traceutil/trace.go:171","msg":"trace[1689056525] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:26110; }","duration":"191.2735ms","start":"2026-05-19T00:58:09.907516Z","end":"2026-05-19T00:58:10.09879Z","steps":["trace[1689056525] 'count revisions from in-memory index tree' (duration: 191.010905ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:58:10.098913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.699726ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-05-19T00:58:10.098999Z","caller":"traceutil/trace.go:171","msg":"trace[813408706] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26110; }","duration":"190.84428ms","start":"2026-05-19T00:58:09.908137Z","end":"2026-05-19T00:58:10.098982Z","steps":["trace[813408706] 'range keys from in-memory index tree' (duration: 190.465841ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:58:10.491669Z","caller":"traceutil/trace.go:171","msg":"trace[1818619564] linearizableReadLoop","detail":"{readStateIndex:26985; appliedIndex:26984; }","duration":"239.944608ms","start":"2026-05-19T00:58:10.251694Z","end":"2026-05-19T00:58:10.491638Z","steps":["trace[1818619564] 'read index received' (duration: 141.771902ms)","trace[1818619564] 'applied index is now lower than readState.Index' (duration: 98.171586ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T00:58:10.491719Z","caller":"traceutil/trace.go:171","msg":"trace[1994176445] transaction","detail":"{read_only:false; response_revision:26113; number_of_response:1; }","duration":"282.735875ms","start":"2026-05-19T00:58:10.208948Z","end":"2026-05-19T00:58:10.491684Z","steps":["trace[1994176445] 'process raft request' (duration: 184.6012ms)","trace[1994176445] 'compare' (duration: 97.819287ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T00:58:10.491889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.888258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinedrainrules/\" range_end:\"/registry/cluster.x-k8s.io/machinedrainrules0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T00:58:10.491925Z","caller":"traceutil/trace.go:171","msg":"trace[1212385824] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinedrainrules/; range_end:/registry/cluster.x-k8s.io/machinedrainrules0; response_count:0; response_revision:26113; }","duration":"247.960979ms","start":"2026-05-19T00:58:10.243951Z","end":"2026-05-19T00:58:10.491912Z","steps":["trace[1212385824] 'agreement among raft nodes before linearized reading' (duration: 247.840066ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:58:10.492171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.425246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T00:58:10.492203Z","caller":"traceutil/trace.go:171","msg":"trace[1315883387] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:26113; }","duration":"234.522188ms","start":"2026-05-19T00:58:10.257671Z","end":"2026-05-19T00:58:10.492193Z","steps":["trace[1315883387] 'agreement among raft nodes before linearized reading' (duration: 234.438146ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:58:10.492353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.767978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-05-19T00:58:10.492377Z","caller":"traceutil/trace.go:171","msg":"trace[1260357889] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26113; }","duration":"265.839409ms","start":"2026-05-19T00:58:10.226531Z","end":"2026-05-19T00:58:10.492371Z","steps":["trace[1260357889] 'agreement among raft nodes before linearized reading' (duration: 265.685696ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:58:10.815817Z","caller":"traceutil/trace.go:171","msg":"trace[612840724] transaction","detail":"{read_only:false; response_revision:26114; number_of_response:1; }","duration":"314.501757ms","start":"2026-05-19T00:58:10.501285Z","end":"2026-05-19T00:58:10.815787Z","steps":["trace[612840724] 'process raft request' (duration: 314.244691ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:58:10.816019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T00:58:10.501264Z","time spent":"314.648841ms","remote":"127.0.0.1:52314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T00:58:10.816515Z","caller":"traceutil/trace.go:171","msg":"trace[989475093] linearizableReadLoop","detail":"{readStateIndex:26986; appliedIndex:26985; }","duration":"312.911949ms","start":"2026-05-19T00:58:10.502825Z","end":"2026-05-19T00:58:10.815737Z","steps":["trace[989475093] 'read index received' (duration: 312.614572ms)","trace[989475093] 'applied index is now lower than readState.Index' (duration: 296.607µs)"],"step_count":2} {"level":"warn","ts":"2026-05-19T00:58:10.816499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.633556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-05-19T00:58:10.81665Z","caller":"traceutil/trace.go:171","msg":"trace[1583966338] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26114; }","duration":"313.7769ms","start":"2026-05-19T00:58:10.502801Z","end":"2026-05-19T00:58:10.816577Z","steps":["trace[1583966338] 'agreement among raft nodes before linearized reading' (duration: 313.452652ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:58:10.816705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T00:58:10.502792Z","time spent":"313.901263ms","remote":"127.0.0.1:52248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12237,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-05-19T00:58:10.969621Z","caller":"traceutil/trace.go:171","msg":"trace[1472089160] transaction","detail":"{read_only:false; response_revision:26115; number_of_response:1; }","duration":"405.827788ms","start":"2026-05-19T00:58:10.563718Z","end":"2026-05-19T00:58:10.969546Z","steps":["trace[1472089160] 'process raft request' (duration: 365.93032ms)","trace[1472089160] 'compare' (duration: 39.564969ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T00:58:10.969871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T00:58:10.563688Z","time spent":"406.044904ms","remote":"127.0.0.1:52314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T00:58:10.969886Z","caller":"traceutil/trace.go:171","msg":"trace[424456649] transaction","detail":"{read_only:false; response_revision:26117; number_of_response:1; }","duration":"335.158083ms","start":"2026-05-19T00:58:10.634694Z","end":"2026-05-19T00:58:10.969852Z","steps":["trace[424456649] 'process raft request' (duration: 334.765513ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:58:10.970095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T00:58:10.634686Z","time spent":"335.299206ms","remote":"127.0.0.1:52314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T00:58:10.970371Z","caller":"traceutil/trace.go:171","msg":"trace[1511348625] transaction","detail":"{read_only:false; response_revision:26118; number_of_response:1; }","duration":"168.426821ms","start":"2026-05-19T00:58:10.801929Z","end":"2026-05-19T00:58:10.970356Z","steps":["trace[1511348625] 'process raft request' (duration: 167.841687ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:58:10.970376Z","caller":"traceutil/trace.go:171","msg":"trace[1101441555] linearizableReadLoop","detail":"{readStateIndex:26990; appliedIndex:26986; }","duration":"153.796421ms","start":"2026-05-19T00:58:10.816551Z","end":"2026-05-19T00:58:10.970348Z","steps":["trace[1101441555] 'read index received' (duration: 113.232917ms)","trace[1101441555] 'applied index is now lower than readState.Index' (duration: 40.533553ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T00:58:10.970058Z","caller":"traceutil/trace.go:171","msg":"trace[1637297403] transaction","detail":"{read_only:false; response_revision:26116; number_of_response:1; }","duration":"340.046371ms","start":"2026-05-19T00:58:10.629698Z","end":"2026-05-19T00:58:10.969745Z","steps":["trace[1637297403] 'process raft request' (duration: 339.707732ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:58:10.97069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.695761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3308"} {"level":"warn","ts":"2026-05-19T00:58:10.970746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.182244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-19T00:58:10.970782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.533729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-05-19T00:58:10.970802Z","caller":"traceutil/trace.go:171","msg":"trace[1175305202] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:26118; }","duration":"393.241875ms","start":"2026-05-19T00:58:10.577547Z","end":"2026-05-19T00:58:10.970789Z","steps":["trace[1175305202] 'agreement among raft nodes before linearized reading' (duration: 393.148613ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:58:10.970853Z","caller":"traceutil/trace.go:171","msg":"trace[702024270] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26118; }","duration":"292.634781ms","start":"2026-05-19T00:58:10.678192Z","end":"2026-05-19T00:58:10.970827Z","steps":["trace[702024270] 'agreement among raft nodes before linearized reading' (duration: 292.447547ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:58:10.970848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T00:58:10.577524Z","time spent":"393.315358ms","remote":"127.0.0.1:51048","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true "} {"level":"warn","ts":"2026-05-19T00:58:10.970941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T00:58:10.629669Z","time spent":"340.921381ms","remote":"127.0.0.1:52314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T00:58:10.970743Z","caller":"traceutil/trace.go:171","msg":"trace[570663393] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:26118; }","duration":"204.810635ms","start":"2026-05-19T00:58:10.765916Z","end":"2026-05-19T00:58:10.970727Z","steps":["trace[570663393] 'agreement among raft nodes before linearized reading' (duration: 204.574759ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:58:11.178411Z","caller":"traceutil/trace.go:171","msg":"trace[2032716489] transaction","detail":"{read_only:false; response_revision:26119; number_of_response:1; }","duration":"334.011205ms","start":"2026-05-19T00:58:10.84437Z","end":"2026-05-19T00:58:11.178381Z","steps":["trace[2032716489] 'process raft request' (duration: 329.017555ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:58:11.178425Z","caller":"traceutil/trace.go:171","msg":"trace[1250609689] linearizableReadLoop","detail":"{readStateIndex:26992; appliedIndex:26990; }","duration":"207.944299ms","start":"2026-05-19T00:58:10.970455Z","end":"2026-05-19T00:58:11.178399Z","steps":["trace[1250609689] 'read index received' (duration: 203.082463ms)","trace[1250609689] 'applied index is now lower than readState.Index' (duration: 4.860376ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T00:58:11.178566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T00:58:10.844335Z","time spent":"334.147168ms","remote":"127.0.0.1:52314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T00:58:11.178658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.274577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-19T00:58:11.178692Z","caller":"traceutil/trace.go:171","msg":"trace[1770986003] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26120; }","duration":"323.365729ms","start":"2026-05-19T00:58:10.855316Z","end":"2026-05-19T00:58:11.178682Z","steps":["trace[1770986003] 'agreement among raft nodes before linearized reading' (duration: 323.204355ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:58:11.178726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T00:58:10.855297Z","time spent":"323.420121ms","remote":"127.0.0.1:52314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-19T00:58:11.17872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.343971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18417"} {"level":"info","ts":"2026-05-19T00:58:11.178773Z","caller":"traceutil/trace.go:171","msg":"trace[175224721] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:26120; }","duration":"356.424203ms","start":"2026-05-19T00:58:10.822336Z","end":"2026-05-19T00:58:11.17876Z","steps":["trace[175224721] 'agreement among raft nodes before linearized reading' (duration: 356.253609ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:58:11.178449Z","caller":"traceutil/trace.go:171","msg":"trace[479979589] transaction","detail":"{read_only:false; response_revision:26120; number_of_response:1; }","duration":"330.170553ms","start":"2026-05-19T00:58:10.84826Z","end":"2026-05-19T00:58:11.17843Z","steps":["trace[479979589] 'process raft request' (duration: 330.015709ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:58:11.178805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T00:58:10.822313Z","time spent":"356.484704ms","remote":"127.0.0.1:52240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":18440,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-05-19T00:58:11.178847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T00:58:10.848233Z","time spent":"330.587753ms","remote":"127.0.0.1:52314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-19T00:58:11.195543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.796418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"info","ts":"2026-05-19T00:58:11.195617Z","caller":"traceutil/trace.go:171","msg":"trace[1120291491] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:26121; }","duration":"181.111285ms","start":"2026-05-19T00:58:11.01449Z","end":"2026-05-19T00:58:11.195602Z","steps":["trace[1120291491] 'agreement among raft nodes before linearized reading' (duration: 180.738416ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:58:11.19562Z","caller":"traceutil/trace.go:171","msg":"trace[2051484828] transaction","detail":"{read_only:false; response_revision:26121; number_of_response:1; }","duration":"219.22022ms","start":"2026-05-19T00:58:10.976388Z","end":"2026-05-19T00:58:11.195608Z","steps":["trace[2051484828] 'process raft request' (duration: 218.654737ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:58:11.195649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.941052ms","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-19T00:58:11.195749Z","caller":"traceutil/trace.go:171","msg":"trace[202685535] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26121; }","duration":"101.104566ms","start":"2026-05-19T00:58:11.094628Z","end":"2026-05-19T00:58:11.195732Z","steps":["trace[202685535] 'agreement among raft nodes before linearized reading' (duration: 100.611384ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:58:11.19557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.869667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/rabbitmq-manila-default-user\" ","response":"range_response_count:1 size:1312"} {"level":"info","ts":"2026-05-19T00:58:11.195847Z","caller":"traceutil/trace.go:171","msg":"trace[853732626] range","detail":"{range_begin:/registry/secrets/openstack/rabbitmq-manila-default-user; range_end:; response_count:1; response_revision:26121; }","duration":"215.172933ms","start":"2026-05-19T00:58:10.980662Z","end":"2026-05-19T00:58:11.195835Z","steps":["trace[853732626] 'agreement among raft nodes before linearized reading' (duration: 214.62906ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:58:11.317313Z","caller":"traceutil/trace.go:171","msg":"trace[1135140784] linearizableReadLoop","detail":"{readStateIndex:26994; appliedIndex:26993; }","duration":"121.857075ms","start":"2026-05-19T00:58:11.195198Z","end":"2026-05-19T00:58:11.317055Z","steps":["trace[1135140784] 'read index received' (duration: 116.790093ms)","trace[1135140784] 'applied index is now lower than readState.Index' (duration: 5.065522ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T00:58:11.317392Z","caller":"traceutil/trace.go:171","msg":"trace[342629692] transaction","detail":"{read_only:false; response_revision:26122; number_of_response:1; }","duration":"133.507574ms","start":"2026-05-19T00:58:11.183813Z","end":"2026-05-19T00:58:11.31732Z","steps":["trace[342629692] 'process raft request' (duration: 128.209967ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:58:11.31756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.795725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5652"} {"level":"info","ts":"2026-05-19T00:58:11.317661Z","caller":"traceutil/trace.go:171","msg":"trace[580673813] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:26122; }","duration":"124.952209ms","start":"2026-05-19T00:58:11.192685Z","end":"2026-05-19T00:58:11.317637Z","steps":["trace[580673813] 'agreement among raft nodes before linearized reading' (duration: 124.671672ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:58:11.336369Z","caller":"traceutil/trace.go:171","msg":"trace[1154765465] transaction","detail":"{read_only:false; response_revision:26123; number_of_response:1; }","duration":"137.587321ms","start":"2026-05-19T00:58:11.198757Z","end":"2026-05-19T00:58:11.336344Z","steps":["trace[1154765465] 'process raft request' (duration: 137.393706ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:58:11.336433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.143828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/openstack/\" range_end:\"/registry/resourcequotas/openstack0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T00:58:11.336494Z","caller":"traceutil/trace.go:171","msg":"trace[2105467028] range","detail":"{range_begin:/registry/resourcequotas/openstack/; range_end:/registry/resourcequotas/openstack0; response_count:0; response_revision:26123; }","duration":"134.22922ms","start":"2026-05-19T00:58:11.202248Z","end":"2026-05-19T00:58:11.336477Z","steps":["trace[2105467028] 'agreement among raft nodes before linearized reading' (duration: 134.109867ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:58:11.54627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.979204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/servers/\" range_end:\"/registry/openstack.k-orc.cloud/servers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T00:58:11.546386Z","caller":"traceutil/trace.go:171","msg":"trace[1994190661] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/servers/; range_end:/registry/openstack.k-orc.cloud/servers0; response_count:0; response_revision:26123; }","duration":"114.166369ms","start":"2026-05-19T00:58:11.432192Z","end":"2026-05-19T00:58:11.546359Z","steps":["trace[1994190661] 'count revisions from in-memory index tree' (duration: 113.866511ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:58:11.893893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.925775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T00:58:11.89397Z","caller":"traceutil/trace.go:171","msg":"trace[1142687144] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:26124; }","duration":"177.068429ms","start":"2026-05-19T00:58:11.716886Z","end":"2026-05-19T00:58:11.893955Z","steps":["trace[1142687144] 'range keys from in-memory index tree' (duration: 176.824403ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:58:11.894283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.558273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-glance-server-0\" ","response":"range_response_count:1 size:1858"} {"level":"info","ts":"2026-05-19T00:58:11.894317Z","caller":"traceutil/trace.go:171","msg":"trace[1793753807] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-glance-server-0; range_end:; response_count:1; response_revision:26124; }","duration":"215.618944ms","start":"2026-05-19T00:58:11.678687Z","end":"2026-05-19T00:58:11.894306Z","steps":["trace[1793753807] 'range keys from in-memory index tree' (duration: 215.44896ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:58:11.894285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.541373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-05-19T00:58:11.894398Z","caller":"traceutil/trace.go:171","msg":"trace[2106943700] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26124; }","duration":"115.660336ms","start":"2026-05-19T00:58:11.778701Z","end":"2026-05-19T00:58:11.894361Z","steps":["trace[2106943700] 'range keys from in-memory index tree' (duration: 115.40977ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:58:12.08742Z","caller":"traceutil/trace.go:171","msg":"trace[450008965] linearizableReadLoop","detail":"{readStateIndex:26997; appliedIndex:26996; }","duration":"187.058589ms","start":"2026-05-19T00:58:11.900334Z","end":"2026-05-19T00:58:12.087393Z","steps":["trace[450008965] 'read index received' (duration: 186.809962ms)","trace[450008965] 'applied index is now lower than readState.Index' (duration: 247.417µs)"],"step_count":2} {"level":"warn","ts":"2026-05-19T00:58:12.087569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.201713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T00:58:12.087574Z","caller":"traceutil/trace.go:171","msg":"trace[1087504872] transaction","detail":"{read_only:false; response_revision:26125; number_of_response:1; }","duration":"189.232602ms","start":"2026-05-19T00:58:11.898292Z","end":"2026-05-19T00:58:12.087524Z","steps":["trace[1087504872] 'process raft request' (duration: 188.873212ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:58:12.087613Z","caller":"traceutil/trace.go:171","msg":"trace[1036285220] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:26125; }","duration":"187.269584ms","start":"2026-05-19T00:58:11.90033Z","end":"2026-05-19T00:58:12.0876Z","steps":["trace[1036285220] 'agreement among raft nodes before linearized reading' (duration: 187.169302ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:58:12.256022Z","caller":"traceutil/trace.go:171","msg":"trace[828223925] linearizableReadLoop","detail":"{readStateIndex:26998; appliedIndex:26997; }","duration":"163.657308ms","start":"2026-05-19T00:58:12.092336Z","end":"2026-05-19T00:58:12.255993Z","steps":["trace[828223925] 'read index received' (duration: 163.130655ms)","trace[828223925] 'applied index is now lower than readState.Index' (duration: 524.903µs)"],"step_count":2} {"level":"warn","ts":"2026-05-19T00:58:12.2562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.844282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T00:58:12.25629Z","caller":"traceutil/trace.go:171","msg":"trace[1351018803] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:26126; }","duration":"163.985755ms","start":"2026-05-19T00:58:12.092288Z","end":"2026-05-19T00:58:12.256274Z","steps":["trace[1351018803] 'agreement among raft nodes before linearized reading' (duration: 163.814531ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:58:12.256632Z","caller":"traceutil/trace.go:171","msg":"trace[562155469] transaction","detail":"{read_only:false; response_revision:26126; number_of_response:1; }","duration":"280.914831ms","start":"2026-05-19T00:58:11.975139Z","end":"2026-05-19T00:58:12.256054Z","steps":["trace[562155469] 'process raft request' (duration: 280.45386ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:58:12.526516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.198687ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-05-19T00:58:12.527072Z","caller":"traceutil/trace.go:171","msg":"trace[503312743] linearizableReadLoop","detail":"{readStateIndex:27000; appliedIndex:26998; }","duration":"256.888424ms","start":"2026-05-19T00:58:12.270165Z","end":"2026-05-19T00:58:12.527054Z","steps":["trace[503312743] 'read index received' (duration: 66.351µs)","trace[503312743] 'applied index is now lower than readState.Index' (duration: 256.820602ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T00:58:12.52721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.029768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T00:58:12.52727Z","caller":"traceutil/trace.go:171","msg":"trace[819571130] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:26127; }","duration":"257.13998ms","start":"2026-05-19T00:58:12.270117Z","end":"2026-05-19T00:58:12.527257Z","steps":["trace[819571130] 'agreement among raft nodes before linearized reading' (duration: 256.983367ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:58:12.527251Z","caller":"traceutil/trace.go:171","msg":"trace[330817968] transaction","detail":"{read_only:false; response_revision:26127; number_of_response:1; }","duration":"310.89841ms","start":"2026-05-19T00:58:12.216189Z","end":"2026-05-19T00:58:12.527087Z","steps":["trace[330817968] 'process raft request' (duration: 310.443879ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:58:12.527481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T00:58:12.216163Z","time spent":"311.192108ms","remote":"127.0.0.1:52314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T00:58:12.92786Z","caller":"traceutil/trace.go:171","msg":"trace[1980272170] transaction","detail":"{read_only:false; response_revision:26129; number_of_response:1; }","duration":"102.370957ms","start":"2026-05-19T00:58:12.825464Z","end":"2026-05-19T00:58:12.927835Z","steps":["trace[1980272170] 'process raft request' (duration: 102.13253ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:58:14.043423Z","caller":"traceutil/trace.go:171","msg":"trace[1848712485] transaction","detail":"{read_only:false; response_revision:26142; number_of_response:1; }","duration":"167.500299ms","start":"2026-05-19T00:58:13.875884Z","end":"2026-05-19T00:58:14.043385Z","steps":["trace[1848712485] 'process raft request' (duration: 110.723247ms)","trace[1848712485] 'compare' (duration: 56.586357ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T00:58:14.116786Z","caller":"traceutil/trace.go:171","msg":"trace[261377598] transaction","detail":"{read_only:false; response_revision:26143; number_of_response:1; }","duration":"230.747496ms","start":"2026-05-19T00:58:13.885987Z","end":"2026-05-19T00:58:14.116735Z","steps":["trace[261377598] 'process raft request' (duration: 230.48495ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:58:15.075879Z","caller":"traceutil/trace.go:171","msg":"trace[1142486603] linearizableReadLoop","detail":"{readStateIndex:27024; appliedIndex:27023; }","duration":"105.366077ms","start":"2026-05-19T00:58:14.970473Z","end":"2026-05-19T00:58:15.075839Z","steps":["trace[1142486603] 'read index received' (duration: 105.301606ms)","trace[1142486603] 'applied index is now lower than readState.Index' (duration: 62.421µs)"],"step_count":2} {"level":"warn","ts":"2026-05-19T00:58:15.076107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.604964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T00:58:15.076166Z","caller":"traceutil/trace.go:171","msg":"trace[541762967] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26150; }","duration":"105.733737ms","start":"2026-05-19T00:58:14.970417Z","end":"2026-05-19T00:58:15.076151Z","steps":["trace[541762967] 'agreement among raft nodes before linearized reading' (duration: 105.573943ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:58:15.07666Z","caller":"traceutil/trace.go:171","msg":"trace[136171163] transaction","detail":"{read_only:false; response_revision:26150; number_of_response:1; }","duration":"139.901287ms","start":"2026-05-19T00:58:14.93674Z","end":"2026-05-19T00:58:15.076641Z","steps":["trace[136171163] 'process raft request' (duration: 138.865542ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:58:17.276776Z","caller":"traceutil/trace.go:171","msg":"trace[1761207436] transaction","detail":"{read_only:false; response_revision:26164; number_of_response:1; }","duration":"189.585339ms","start":"2026-05-19T00:58:17.087158Z","end":"2026-05-19T00:58:17.276743Z","steps":["trace[1761207436] 'process raft request' (duration: 189.347604ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:58:17.337899Z","caller":"traceutil/trace.go:171","msg":"trace[1046274750] transaction","detail":"{read_only:false; response_revision:26165; number_of_response:1; }","duration":"247.792606ms","start":"2026-05-19T00:58:17.089987Z","end":"2026-05-19T00:58:17.33778Z","steps":["trace[1046274750] 'process raft request' (duration: 247.52982ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:58:17.338169Z","caller":"traceutil/trace.go:171","msg":"trace[1230766608] transaction","detail":"{read_only:false; response_revision:26166; number_of_response:1; }","duration":"246.627968ms","start":"2026-05-19T00:58:17.091515Z","end":"2026-05-19T00:58:17.338143Z","steps":["trace[1230766608] 'process raft request' (duration: 246.212158ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:58:17.338344Z","caller":"traceutil/trace.go:171","msg":"trace[912138700] transaction","detail":"{read_only:false; response_revision:26167; number_of_response:1; }","duration":"246.666149ms","start":"2026-05-19T00:58:17.091671Z","end":"2026-05-19T00:58:17.338337Z","steps":["trace[912138700] 'process raft request' (duration: 246.254509ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:58:17.338279Z","caller":"traceutil/trace.go:171","msg":"trace[1902858076] transaction","detail":"{read_only:false; response_revision:26168; number_of_response:1; }","duration":"246.119786ms","start":"2026-05-19T00:58:17.092102Z","end":"2026-05-19T00:58:17.338222Z","steps":["trace[1902858076] 'process raft request' (duration: 245.986753ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:58:17.33855Z","caller":"traceutil/trace.go:171","msg":"trace[938070218] transaction","detail":"{read_only:false; response_revision:26169; number_of_response:1; }","duration":"141.339471ms","start":"2026-05-19T00:58:17.197185Z","end":"2026-05-19T00:58:17.338524Z","steps":["trace[938070218] 'process raft request' (duration: 140.977613ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:58:17.338597Z","caller":"traceutil/trace.go:171","msg":"trace[1407042886] linearizableReadLoop","detail":"{readStateIndex:27043; appliedIndex:27038; }","duration":"238.271088ms","start":"2026-05-19T00:58:17.100286Z","end":"2026-05-19T00:58:17.338557Z","steps":["trace[1407042886] 'read index received' (duration: 176.207569ms)","trace[1407042886] 'applied index is now lower than readState.Index' (duration: 62.061429ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T00:58:17.338651Z","caller":"traceutil/trace.go:171","msg":"trace[1675439621] transaction","detail":"{read_only:false; response_revision:26170; number_of_response:1; }","duration":"130.377188ms","start":"2026-05-19T00:58:17.208265Z","end":"2026-05-19T00:58:17.338642Z","steps":["trace[1675439621] 'process raft request' (duration: 130.151403ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:58:17.338733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.435121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T00:58:17.338789Z","caller":"traceutil/trace.go:171","msg":"trace[449697752] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters0; response_count:0; response_revision:26170; }","duration":"238.526414ms","start":"2026-05-19T00:58:17.10023Z","end":"2026-05-19T00:58:17.338757Z","steps":["trace[449697752] 'agreement among raft nodes before linearized reading' (duration: 238.449282ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:58:17.338948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.384527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-05-19T00:58:17.339022Z","caller":"traceutil/trace.go:171","msg":"trace[889629687] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26170; }","duration":"122.51043ms","start":"2026-05-19T00:58:17.216494Z","end":"2026-05-19T00:58:17.339005Z","steps":["trace[889629687] 'agreement among raft nodes before linearized reading' (duration: 122.263775ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:58:19.693776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.61554ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-19T00:58:19.693885Z","caller":"traceutil/trace.go:171","msg":"trace[404314697] linearizableReadLoop","detail":"{readStateIndex:27064; appliedIndex:27063; }","duration":"227.970101ms","start":"2026-05-19T00:58:19.465895Z","end":"2026-05-19T00:58:19.693865Z","steps":["trace[404314697] 'read index received' (duration: 3.143616ms)","trace[404314697] 'applied index is now lower than readState.Index' (duration: 224.825075ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T00:58:19.694031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.135875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T00:58:19.694071Z","caller":"traceutil/trace.go:171","msg":"trace[2100416337] transaction","detail":"{read_only:false; response_revision:26189; number_of_response:1; }","duration":"315.88548ms","start":"2026-05-19T00:58:19.378155Z","end":"2026-05-19T00:58:19.69404Z","steps":["trace[2100416337] 'process raft request' (duration: 90.922652ms)","trace[2100416337] 'compare' (duration: 224.2061ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T00:58:19.694084Z","caller":"traceutil/trace.go:171","msg":"trace[2044851971] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26189; }","duration":"228.224988ms","start":"2026-05-19T00:58:19.465846Z","end":"2026-05-19T00:58:19.694071Z","steps":["trace[2044851971] 'agreement among raft nodes before linearized reading' (duration: 228.081144ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:58:19.694151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-19T00:58:19.378131Z","time spent":"315.983033ms","remote":"127.0.0.1:52314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-19T00:58:20.676025Z","caller":"traceutil/trace.go:171","msg":"trace[1886012541] transaction","detail":"{read_only:false; response_revision:26194; number_of_response:1; }","duration":"101.212028ms","start":"2026-05-19T00:58:20.574755Z","end":"2026-05-19T00:58:20.675967Z","steps":["trace[1886012541] 'process raft request' (duration: 75.351618ms)","trace[1886012541] 'compare' (duration: 25.493202ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T00:58:21.388019Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-00000000000069c7.wal"} {"level":"info","ts":"2026-05-19T00:58:36.782635Z","caller":"traceutil/trace.go:171","msg":"trace[591264482] transaction","detail":"{read_only:false; response_revision:26326; number_of_response:1; }","duration":"132.009197ms","start":"2026-05-19T00:58:36.650596Z","end":"2026-05-19T00:58:36.782605Z","steps":["trace[591264482] 'process raft request' (duration: 131.745091ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:58:37.049866Z","caller":"traceutil/trace.go:171","msg":"trace[1495499005] linearizableReadLoop","detail":"{readStateIndex:27207; appliedIndex:27206; }","duration":"217.786176ms","start":"2026-05-19T00:58:36.832052Z","end":"2026-05-19T00:58:37.049838Z","steps":["trace[1495499005] 'read index received' (duration: 120.208564ms)","trace[1495499005] 'applied index is now lower than readState.Index' (duration: 97.575852ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T00:58:37.049901Z","caller":"traceutil/trace.go:171","msg":"trace[1762163324] transaction","detail":"{read_only:false; response_revision:26328; number_of_response:1; }","duration":"218.612886ms","start":"2026-05-19T00:58:36.83125Z","end":"2026-05-19T00:58:37.049863Z","steps":["trace[1762163324] 'process raft request' (duration: 121.108977ms)","trace[1762163324] 'compare' (duration: 97.311825ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T00:58:37.050303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.518987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/servergroups/\" range_end:\"/registry/openstack.k-orc.cloud/servergroups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T00:58:37.050431Z","caller":"traceutil/trace.go:171","msg":"trace[1094472384] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/servergroups/; range_end:/registry/openstack.k-orc.cloud/servergroups0; response_count:0; response_revision:26328; }","duration":"154.697253ms","start":"2026-05-19T00:58:36.895698Z","end":"2026-05-19T00:58:37.050395Z","steps":["trace[1094472384] 'agreement among raft nodes before linearized reading' (duration: 154.391745ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:58:37.050302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.230216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18417"} {"level":"info","ts":"2026-05-19T00:58:37.050511Z","caller":"traceutil/trace.go:171","msg":"trace[1035618810] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:26328; }","duration":"218.479753ms","start":"2026-05-19T00:58:36.832013Z","end":"2026-05-19T00:58:37.050493Z","steps":["trace[1035618810] 'agreement among raft nodes before linearized reading' (duration: 217.94813ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:58:37.540059Z","caller":"traceutil/trace.go:171","msg":"trace[589613797] transaction","detail":"{read_only:false; response_revision:26329; number_of_response:1; }","duration":"166.372942ms","start":"2026-05-19T00:58:37.373657Z","end":"2026-05-19T00:58:37.54003Z","steps":["trace[589613797] 'process raft request' (duration: 166.205679ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:58:37.695459Z","caller":"traceutil/trace.go:171","msg":"trace[2094048188] transaction","detail":"{read_only:false; response_revision:26330; number_of_response:1; }","duration":"251.935546ms","start":"2026-05-19T00:58:37.443494Z","end":"2026-05-19T00:58:37.69543Z","steps":["trace[2094048188] 'process raft request' (duration: 251.67716ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:58:37.695547Z","caller":"traceutil/trace.go:171","msg":"trace[381348137] transaction","detail":"{read_only:false; response_revision:26332; number_of_response:1; }","duration":"249.888366ms","start":"2026-05-19T00:58:37.445626Z","end":"2026-05-19T00:58:37.695514Z","steps":["trace[381348137] 'process raft request' (duration: 249.752053ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:58:37.695538Z","caller":"traceutil/trace.go:171","msg":"trace[1006301411] transaction","detail":"{read_only:false; response_revision:26331; number_of_response:1; }","duration":"251.908905ms","start":"2026-05-19T00:58:37.443591Z","end":"2026-05-19T00:58:37.6955Z","steps":["trace[1006301411] 'process raft request' (duration: 251.731601ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:58:37.695516Z","caller":"traceutil/trace.go:171","msg":"trace[1338036698] transaction","detail":"{read_only:false; response_revision:26333; number_of_response:1; }","duration":"249.514578ms","start":"2026-05-19T00:58:37.445984Z","end":"2026-05-19T00:58:37.695499Z","steps":["trace[1338036698] 'process raft request' (duration: 249.427816ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:58:37.69582Z","caller":"traceutil/trace.go:171","msg":"trace[1306142021] transaction","detail":"{read_only:false; response_revision:26334; number_of_response:1; }","duration":"249.497137ms","start":"2026-05-19T00:58:37.446309Z","end":"2026-05-19T00:58:37.695807Z","steps":["trace[1306142021] 'process raft request' (duration: 249.147399ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:58:37.695843Z","caller":"traceutil/trace.go:171","msg":"trace[1535014123] linearizableReadLoop","detail":"{readStateIndex:27214; appliedIndex:27207; }","duration":"240.886211ms","start":"2026-05-19T00:58:37.454924Z","end":"2026-05-19T00:58:37.69581Z","steps":["trace[1535014123] 'read index received' (duration: 85.035751ms)","trace[1535014123] 'applied index is now lower than readState.Index' (duration: 155.84931ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T00:58:37.695921Z","caller":"traceutil/trace.go:171","msg":"trace[935388139] transaction","detail":"{read_only:false; response_revision:26335; number_of_response:1; }","duration":"244.888396ms","start":"2026-05-19T00:58:37.451023Z","end":"2026-05-19T00:58:37.695911Z","steps":["trace[935388139] 'process raft request' (duration: 244.656711ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:58:37.696109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.163327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-05-19T00:58:37.696134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.920111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-05-19T00:58:37.696154Z","caller":"traceutil/trace.go:171","msg":"trace[1653060715] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26335; }","duration":"241.247039ms","start":"2026-05-19T00:58:37.454894Z","end":"2026-05-19T00:58:37.696141Z","steps":["trace[1653060715] 'agreement among raft nodes before linearized reading' (duration: 241.070085ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:58:37.696153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.819642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1782"} {"level":"info","ts":"2026-05-19T00:58:37.696184Z","caller":"traceutil/trace.go:171","msg":"trace[159507816] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26335; }","duration":"241.000714ms","start":"2026-05-19T00:58:37.455167Z","end":"2026-05-19T00:58:37.696168Z","steps":["trace[159507816] 'agreement among raft nodes before linearized reading' (duration: 240.85935ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:58:37.696196Z","caller":"traceutil/trace.go:171","msg":"trace[773710447] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:26335; }","duration":"108.909504ms","start":"2026-05-19T00:58:37.587274Z","end":"2026-05-19T00:58:37.696183Z","steps":["trace[773710447] 'agreement among raft nodes before linearized reading' (duration: 108.75219ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T00:58:37.901745Z","caller":"traceutil/trace.go:171","msg":"trace[783113529] linearizableReadLoop","detail":"{readStateIndex:27216; appliedIndex:27214; }","duration":"200.218374ms","start":"2026-05-19T00:58:37.701499Z","end":"2026-05-19T00:58:37.901717Z","steps":["trace[783113529] 'read index received' (duration: 5.149684ms)","trace[783113529] 'applied index is now lower than readState.Index' (duration: 195.06743ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T00:58:37.901807Z","caller":"traceutil/trace.go:171","msg":"trace[1773486894] transaction","detail":"{read_only:false; response_revision:26337; number_of_response:1; }","duration":"200.367898ms","start":"2026-05-19T00:58:37.701406Z","end":"2026-05-19T00:58:37.901774Z","steps":["trace[1773486894] 'process raft request' (duration: 127.91615ms)","trace[1773486894] 'compare' (duration: 72.193182ms)"],"step_count":2} {"level":"warn","ts":"2026-05-19T00:58:37.901959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.433529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-19T00:58:37.902026Z","caller":"traceutil/trace.go:171","msg":"trace[864947287] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:26337; }","duration":"200.558763ms","start":"2026-05-19T00:58:37.701447Z","end":"2026-05-19T00:58:37.902005Z","steps":["trace[864947287] 'agreement among raft nodes before linearized reading' (duration: 200.424909ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:58:37.902024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.236378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-05-19T00:58:37.902483Z","caller":"traceutil/trace.go:171","msg":"trace[139302187] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26337; }","duration":"108.75922ms","start":"2026-05-19T00:58:37.793712Z","end":"2026-05-19T00:58:37.902471Z","steps":["trace[139302187] 'agreement among raft nodes before linearized reading' (duration: 108.196146ms)"],"step_count":1} {"level":"warn","ts":"2026-05-19T00:58:38.153046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.068735ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-19T00:58:38.153185Z","caller":"traceutil/trace.go:171","msg":"trace[1638738996] transaction","detail":"{read_only:false; response_revision:26338; number_of_response:1; }","duration":"245.907031ms","start":"2026-05-19T00:58:37.907251Z","end":"2026-05-19T00:58:38.153158Z","steps":["trace[1638738996] 'process raft request' (duration: 104.606211ms)","trace[1638738996] 'compare' (duration: 140.661055ms)"],"step_count":2} {"level":"info","ts":"2026-05-19T00:58:49.737472Z","caller":"traceutil/trace.go:171","msg":"trace[1945862098] transaction","detail":"{read_only:false; response_revision:26560; number_of_response:1; }","duration":"137.686774ms","start":"2026-05-19T00:58:49.599722Z","end":"2026-05-19T00:58:49.737409Z","steps":["trace[1945862098] 'process raft request' (duration: 137.264214ms)"],"step_count":1} {"level":"info","ts":"2026-05-19T01:01:33.69917Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25125} {"level":"info","ts":"2026-05-19T01:01:33.839884Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25125,"took":"136.194689ms","hash":2751238503,"current-db-size-bytes":58265600,"current-db-size":"58 MB","current-db-size-in-use-bytes":38236160,"current-db-size-in-use":"38 MB"} {"level":"info","ts":"2026-05-19T01:01:33.839946Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2751238503,"revision":25125,"compact-revision":21920} {"level":"info","ts":"2026-05-19T01:03:04.782522Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"d716dd02ccafa30","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-19T01:03:04.788145Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-05-19T01:03:04.788328Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-05-19T01:06:33.704333Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28160} {"level":"info","ts":"2026-05-19T01:06:33.863864Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28160,"took":"154.451177ms","hash":2136699307,"current-db-size-bytes":58265600,"current-db-size":"58 MB","current-db-size-in-use-bytes":33169408,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-05-19T01:06:33.86396Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2136699307,"revision":28160,"compact-revision":25125} {"level":"info","ts":"2026-05-19T01:11:33.7108Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30671} {"level":"info","ts":"2026-05-19T01:11:33.86583Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30671,"took":"144.925881ms","hash":1355712614,"current-db-size-bytes":58265600,"current-db-size":"58 MB","current-db-size-in-use-bytes":30527488,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-19T01:11:33.865919Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1355712614,"revision":30671,"compact-revision":28160} {"level":"info","ts":"2026-05-19T01:16:33.71785Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32935} {"level":"info","ts":"2026-05-19T01:16:33.871213Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32935,"took":"148.332004ms","hash":1842954647,"current-db-size-bytes":59305984,"current-db-size":"59 MB","current-db-size-in-use-bytes":30326784,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-19T01:16:33.871294Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1842954647,"revision":32935,"compact-revision":30671} {"level":"info","ts":"2026-05-19T01:21:33.724409Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35209} {"level":"info","ts":"2026-05-19T01:21:33.901558Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35209,"took":"171.365567ms","hash":93611244,"current-db-size-bytes":59305984,"current-db-size":"59 MB","current-db-size-in-use-bytes":31801344,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-19T01:21:33.901659Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":93611244,"revision":35209,"compact-revision":32935} {"level":"info","ts":"2026-05-19T01:24:23.981009Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"d716dd02ccafa30","local-member-applied-index":40005,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-19T01:24:23.986792Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40005} {"level":"info","ts":"2026-05-19T01:24:23.986936Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35005} {"level":"info","ts":"2026-05-19T01:26:33.730893Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37487} {"level":"info","ts":"2026-05-19T01:26:33.85783Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37487,"took":"123.685252ms","hash":1570688843,"current-db-size-bytes":59305984,"current-db-size":"59 MB","current-db-size-in-use-bytes":32370688,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-19T01:26:33.857914Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1570688843,"revision":37487,"compact-revision":35209}