{"level":"warn","ts":"2026-08-12T11:06:39.802275Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-12T11:06:39.802421Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.90:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.90:2380","--initial-cluster=instance=https://199.19.213.90:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.90:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.90:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-08-12T11:06:39.802513Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-12T11:06:39.802531Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.90:2380"]} {"level":"info","ts":"2026-08-12T11:06:39.802553Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-08-12T11:06:39.803369Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.90:2379"]} {"level":"info","ts":"2026-08-12T11:06:39.803452Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.90:2380"],"listen-peer-urls":["https://199.19.213.90:2380"],"advertise-client-urls":["https://199.19.213.90:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.90:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.90:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-08-12T11:06:39.806143Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.345754ms"} {"level":"info","ts":"2026-08-12T11:06:39.812024Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"2b21fdc9c735cc27","cluster-id":"77163f3b44f8f1db"} {"level":"info","ts":"2026-08-12T11:06:39.81219Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b21fdc9c735cc27 switched to configuration voters=()"} {"level":"info","ts":"2026-08-12T11:06:39.812255Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b21fdc9c735cc27 became follower at term 0"} {"level":"info","ts":"2026-08-12T11:06:39.812286Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 2b21fdc9c735cc27 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-12T11:06:39.812305Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b21fdc9c735cc27 became follower at term 1"} {"level":"info","ts":"2026-08-12T11:06:39.812392Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b21fdc9c735cc27 switched to configuration voters=(3108044260934798375)"} {"level":"warn","ts":"2026-08-12T11:06:39.815941Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-12T11:06:39.817576Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-12T11:06:39.818847Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-08-12T11:06:39.821056Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"2b21fdc9c735cc27","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-12T11:06:39.821252Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"2b21fdc9c735cc27","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-12T11:06:39.821327Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-08-12T11:06:39.821454Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-08-12T11:06:39.821475Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-08-12T11:06:39.8253Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b21fdc9c735cc27 switched to configuration voters=(3108044260934798375)"} {"level":"info","ts":"2026-08-12T11:06:39.825567Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"77163f3b44f8f1db","local-member-id":"2b21fdc9c735cc27","added-peer-id":"2b21fdc9c735cc27","added-peer-peer-urls":["https://199.19.213.90:2380"]} {"level":"info","ts":"2026-08-12T11:06:39.827555Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-08-12T11:06:39.827662Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.90:2380"} {"level":"info","ts":"2026-08-12T11:06:39.827709Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.90:2380"} {"level":"info","ts":"2026-08-12T11:06:39.827913Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"2b21fdc9c735cc27","initial-advertise-peer-urls":["https://199.19.213.90:2380"],"listen-peer-urls":["https://199.19.213.90:2380"],"advertise-client-urls":["https://199.19.213.90:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.90:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-12T11:06:39.827969Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-12T11:06:40.413051Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b21fdc9c735cc27 is starting a new election at term 1"} {"level":"info","ts":"2026-08-12T11:06:40.413157Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b21fdc9c735cc27 became pre-candidate at term 1"} {"level":"info","ts":"2026-08-12T11:06:40.413197Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b21fdc9c735cc27 received MsgPreVoteResp from 2b21fdc9c735cc27 at term 1"} {"level":"info","ts":"2026-08-12T11:06:40.413217Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b21fdc9c735cc27 became candidate at term 2"} {"level":"info","ts":"2026-08-12T11:06:40.413229Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b21fdc9c735cc27 received MsgVoteResp from 2b21fdc9c735cc27 at term 2"} {"level":"info","ts":"2026-08-12T11:06:40.413244Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b21fdc9c735cc27 became leader at term 2"} {"level":"info","ts":"2026-08-12T11:06:40.413258Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 2b21fdc9c735cc27 elected leader 2b21fdc9c735cc27 at term 2"} {"level":"info","ts":"2026-08-12T11:06:40.414427Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"2b21fdc9c735cc27","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.90:2379]}","request-path":"/0/members/2b21fdc9c735cc27/attributes","cluster-id":"77163f3b44f8f1db","publish-timeout":"7s"} {"level":"info","ts":"2026-08-12T11:06:40.4148Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-12T11:06:40.415251Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-12T11:06:40.41568Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-12T11:06:40.41576Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"77163f3b44f8f1db","local-member-id":"2b21fdc9c735cc27","cluster-version":"3.5"} {"level":"info","ts":"2026-08-12T11:06:40.415771Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-12T11:06:40.41585Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-12T11:06:40.416015Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-12T11:06:40.416056Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-12T11:06:40.419287Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-12T11:06:40.419706Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.90:2379"} {"level":"info","ts":"2026-08-12T11:07:27.556597Z","caller":"traceutil/trace.go:171","msg":"trace[1977409102] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"180.651746ms","start":"2026-08-12T11:07:27.375919Z","end":"2026-08-12T11:07:27.55657Z","steps":["trace[1977409102] 'process raft request' (duration: 179.124968ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:07:27.804972Z","caller":"traceutil/trace.go:171","msg":"trace[721742125] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"208.611985ms","start":"2026-08-12T11:07:27.596329Z","end":"2026-08-12T11:07:27.804941Z","steps":["trace[721742125] 'process raft request' (duration: 208.388861ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:07:27.835729Z","caller":"traceutil/trace.go:171","msg":"trace[759816106] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"211.126813ms","start":"2026-08-12T11:07:27.624566Z","end":"2026-08-12T11:07:27.835693Z","steps":["trace[759816106] 'process raft request' (duration: 210.916409ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:07:28.444124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.962557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-08-12T11:07:28.444212Z","caller":"traceutil/trace.go:171","msg":"trace[1333686694] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:604; }","duration":"315.11569ms","start":"2026-08-12T11:07:28.129076Z","end":"2026-08-12T11:07:28.444192Z","steps":["trace[1333686694] 'range keys from in-memory index tree' (duration: 314.746513ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:07:28.44426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:07:28.129043Z","time spent":"315.206692ms","remote":"127.0.0.1:47504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-12T11:07:28.444088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.877732ms","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-08-12T11:07:28.444369Z","caller":"traceutil/trace.go:171","msg":"trace[1912366059] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:604; }","duration":"319.203398ms","start":"2026-08-12T11:07:28.125147Z","end":"2026-08-12T11:07:28.44435Z","steps":["trace[1912366059] 'range keys from in-memory index tree' (duration: 318.73441ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:07:28.444426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:07:28.125125Z","time spent":"319.28928ms","remote":"127.0.0.1:47504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-12T11:07:28.449634Z","caller":"traceutil/trace.go:171","msg":"trace[1339237375] linearizableReadLoop","detail":"{readStateIndex:638; appliedIndex:637; }","duration":"198.480395ms","start":"2026-08-12T11:07:28.251128Z","end":"2026-08-12T11:07:28.449608Z","steps":["trace[1339237375] 'read index received' (duration: 197.782472ms)","trace[1339237375] 'applied index is now lower than readState.Index' (duration: 696.863µs)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:07:28.449677Z","caller":"traceutil/trace.go:171","msg":"trace[159651746] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"215.268422ms","start":"2026-08-12T11:07:28.23438Z","end":"2026-08-12T11:07:28.449648Z","steps":["trace[159651746] 'process raft request' (duration: 214.681361ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:07:28.44985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.710299ms","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-08-12T11:07:28.449895Z","caller":"traceutil/trace.go:171","msg":"trace[425647903] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:605; }","duration":"198.80231ms","start":"2026-08-12T11:07:28.251082Z","end":"2026-08-12T11:07:28.449885Z","steps":["trace[425647903] 'agreement among raft nodes before linearized reading' (duration: 198.647557ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:07:42.944775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.286289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-12T11:07:42.944832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.500035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-rt4fk\" ","response":"range_response_count:1 size:3964"} {"level":"info","ts":"2026-08-12T11:07:42.944884Z","caller":"traceutil/trace.go:171","msg":"trace[2142329728] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:758; }","duration":"200.447102ms","start":"2026-08-12T11:07:42.744417Z","end":"2026-08-12T11:07:42.944864Z","steps":["trace[2142329728] 'count revisions from in-memory index tree' (duration: 200.137865ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:07:42.944916Z","caller":"traceutil/trace.go:171","msg":"trace[840100419] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-rt4fk; range_end:; response_count:1; response_revision:758; }","duration":"262.624387ms","start":"2026-08-12T11:07:42.682271Z","end":"2026-08-12T11:07:42.944895Z","steps":["trace[840100419] 'range keys from in-memory index tree' (duration: 262.232629ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:08:59.087351Z","caller":"traceutil/trace.go:171","msg":"trace[2107205013] linearizableReadLoop","detail":"{readStateIndex:1913; appliedIndex:1912; }","duration":"116.887231ms","start":"2026-08-12T11:08:58.970437Z","end":"2026-08-12T11:08:59.087324Z","steps":["trace[2107205013] 'read index received' (duration: 116.84044ms)","trace[2107205013] 'applied index is now lower than readState.Index' (duration: 45.461µs)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:08:59.087421Z","caller":"traceutil/trace.go:171","msg":"trace[1753940160] transaction","detail":"{read_only:false; response_revision:1832; number_of_response:1; }","duration":"266.902018ms","start":"2026-08-12T11:08:58.820483Z","end":"2026-08-12T11:08:59.087385Z","steps":["trace[1753940160] 'process raft request' (duration: 266.672154ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:08:59.087678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.191566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-08-12T11:08:59.08776Z","caller":"traceutil/trace.go:171","msg":"trace[1037558100] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1832; }","duration":"117.3701ms","start":"2026-08-12T11:08:58.97037Z","end":"2026-08-12T11:08:59.08774Z","steps":["trace[1037558100] 'agreement among raft nodes before linearized reading' (duration: 117.070144ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:08:59.219465Z","caller":"traceutil/trace.go:171","msg":"trace[783536665] linearizableReadLoop","detail":"{readStateIndex:1914; appliedIndex:1913; }","duration":"132.004657ms","start":"2026-08-12T11:08:59.087435Z","end":"2026-08-12T11:08:59.21944Z","steps":["trace[783536665] 'read index received' (duration: 59.349493ms)","trace[783536665] 'applied index is now lower than readState.Index' (duration: 72.652954ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:08:59.219502Z","caller":"traceutil/trace.go:171","msg":"trace[885559281] transaction","detail":"{read_only:false; response_revision:1833; number_of_response:1; }","duration":"127.45213ms","start":"2026-08-12T11:08:59.092025Z","end":"2026-08-12T11:08:59.219477Z","steps":["trace[885559281] 'process raft request' (duration: 127.313798ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:08:59.219706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.968569ms","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-08-12T11:08:59.219757Z","caller":"traceutil/trace.go:171","msg":"trace[1470335346] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1833; }","duration":"249.070621ms","start":"2026-08-12T11:08:58.970675Z","end":"2026-08-12T11:08:59.219746Z","steps":["trace[1470335346] 'agreement among raft nodes before linearized reading' (duration: 248.851297ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:08:59.219813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.636422ms","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-08-12T11:08:59.219869Z","caller":"traceutil/trace.go:171","msg":"trace[479664171] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1833; }","duration":"248.709453ms","start":"2026-08-12T11:08:58.971144Z","end":"2026-08-12T11:08:59.219853Z","steps":["trace[479664171] 'agreement among raft nodes before linearized reading' (duration: 248.562431ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:08:59.219878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.354161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-12T11:08:59.219918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.566858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-aa6a1172-9380-4852-b6f3-f2e44e7b7bf8\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-08-12T11:08:59.21999Z","caller":"traceutil/trace.go:171","msg":"trace[1087159923] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:1833; }","duration":"198.489423ms","start":"2026-08-12T11:08:59.021467Z","end":"2026-08-12T11:08:59.219956Z","steps":["trace[1087159923] 'agreement among raft nodes before linearized reading' (duration: 198.264659ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:08:59.219995Z","caller":"traceutil/trace.go:171","msg":"trace[1571212039] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-aa6a1172-9380-4852-b6f3-f2e44e7b7bf8; range_end:; response_count:1; response_revision:1833; }","duration":"167.672181ms","start":"2026-08-12T11:08:59.052305Z","end":"2026-08-12T11:08:59.219977Z","steps":["trace[1571212039] 'agreement among raft nodes before linearized reading' (duration: 167.482157ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:08:59.495617Z","caller":"traceutil/trace.go:171","msg":"trace[123929289] transaction","detail":"{read_only:false; response_revision:1835; number_of_response:1; }","duration":"269.924797ms","start":"2026-08-12T11:08:59.225664Z","end":"2026-08-12T11:08:59.495589Z","steps":["trace[123929289] 'process raft request' (duration: 190.80597ms)","trace[123929289] 'compare' (duration: 78.818881ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:08:59.495692Z","caller":"traceutil/trace.go:171","msg":"trace[1136315631] transaction","detail":"{read_only:false; response_revision:1836; number_of_response:1; }","duration":"267.755465ms","start":"2026-08-12T11:08:59.227919Z","end":"2026-08-12T11:08:59.495674Z","steps":["trace[1136315631] 'process raft request' (duration: 267.599342ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:08:59.495734Z","caller":"traceutil/trace.go:171","msg":"trace[1126326448] linearizableReadLoop","detail":"{readStateIndex:1918; appliedIndex:1916; }","duration":"236.428663ms","start":"2026-08-12T11:08:59.259279Z","end":"2026-08-12T11:08:59.495708Z","steps":["trace[1126326448] 'read index received' (duration: 157.200194ms)","trace[1126326448] 'applied index is now lower than readState.Index' (duration: 79.223659ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T11:08:59.495885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.579926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-aa6a1172-9380-4852-b6f3-f2e44e7b7bf8\" ","response":"range_response_count:1 size:3746"} {"level":"info","ts":"2026-08-12T11:08:59.495969Z","caller":"traceutil/trace.go:171","msg":"trace[1398668406] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-aa6a1172-9380-4852-b6f3-f2e44e7b7bf8; range_end:; response_count:1; response_revision:1836; }","duration":"236.696757ms","start":"2026-08-12T11:08:59.259251Z","end":"2026-08-12T11:08:59.495947Z","steps":["trace[1398668406] 'agreement among raft nodes before linearized reading' (duration: 236.563985ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:08:59.496026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.611236ms","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-08-12T11:08:59.496079Z","caller":"traceutil/trace.go:171","msg":"trace[2046227648] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1836; }","duration":"115.687968ms","start":"2026-08-12T11:08:59.380373Z","end":"2026-08-12T11:08:59.496061Z","steps":["trace[2046227648] 'agreement among raft nodes before linearized reading' (duration: 115.510355ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:09:09.258804Z","caller":"traceutil/trace.go:171","msg":"trace[1000973357] transaction","detail":"{read_only:false; response_revision:1926; number_of_response:1; }","duration":"101.014131ms","start":"2026-08-12T11:09:09.157753Z","end":"2026-08-12T11:09:09.258767Z","steps":["trace[1000973357] 'process raft request' (duration: 100.301988ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:09:43.843138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.340214ms","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-08-12T11:09:43.843251Z","caller":"traceutil/trace.go:171","msg":"trace[773313009] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2131; }","duration":"130.507179ms","start":"2026-08-12T11:09:43.712723Z","end":"2026-08-12T11:09:43.84323Z","steps":["trace[773313009] 'range keys from in-memory index tree' (duration: 130.186002ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:09:49.695502Z","caller":"traceutil/trace.go:171","msg":"trace[26217530] transaction","detail":"{read_only:false; response_revision:2162; number_of_response:1; }","duration":"205.532438ms","start":"2026-08-12T11:09:49.489943Z","end":"2026-08-12T11:09:49.695476Z","steps":["trace[26217530] 'process raft request' (duration: 205.356074ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:09:49.708546Z","caller":"traceutil/trace.go:171","msg":"trace[1049138645] linearizableReadLoop","detail":"{readStateIndex:2257; appliedIndex:2255; }","duration":"129.04362ms","start":"2026-08-12T11:09:49.579487Z","end":"2026-08-12T11:09:49.70853Z","steps":["trace[1049138645] 'read index received' (duration: 115.873412ms)","trace[1049138645] 'applied index is now lower than readState.Index' (duration: 13.169268ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:09:49.708602Z","caller":"traceutil/trace.go:171","msg":"trace[1548887084] transaction","detail":"{read_only:false; response_revision:2163; number_of_response:1; }","duration":"172.666255ms","start":"2026-08-12T11:09:49.535906Z","end":"2026-08-12T11:09:49.708572Z","steps":["trace[1548887084] 'process raft request' (duration: 172.541402ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:09:49.708737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.233854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-08-12T11:09:49.70878Z","caller":"traceutil/trace.go:171","msg":"trace[998881775] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2163; }","duration":"129.324815ms","start":"2026-08-12T11:09:49.579442Z","end":"2026-08-12T11:09:49.708767Z","steps":["trace[998881775] 'agreement among raft nodes before linearized reading' (duration: 129.181363ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:09:49.708784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.082503ms","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-08-12T11:09:49.708838Z","caller":"traceutil/trace.go:171","msg":"trace[1158118116] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2163; }","duration":"127.164504ms","start":"2026-08-12T11:09:49.581661Z","end":"2026-08-12T11:09:49.708825Z","steps":["trace[1158118116] 'agreement among raft nodes before linearized reading' (duration: 127.010552ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:09:49.914756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.568837ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-12T11:09:49.914874Z","caller":"traceutil/trace.go:171","msg":"trace[2115102116] linearizableReadLoop","detail":"{readStateIndex:2259; appliedIndex:2258; }","duration":"145.91729ms","start":"2026-08-12T11:09:49.76894Z","end":"2026-08-12T11:09:49.914858Z","steps":["trace[2115102116] 'read index received' (duration: 29.592849ms)","trace[2115102116] 'applied index is now lower than readState.Index' (duration: 116.323131ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:09:49.914915Z","caller":"traceutil/trace.go:171","msg":"trace[1753111238] transaction","detail":"{read_only:false; response_revision:2165; number_of_response:1; }","duration":"201.784816ms","start":"2026-08-12T11:09:49.713095Z","end":"2026-08-12T11:09:49.91488Z","steps":["trace[1753111238] 'process raft request' (duration: 85.490526ms)","trace[1753111238] 'compare' (duration: 115.389984ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T11:09:49.914989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.033312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T11:09:49.915067Z","caller":"traceutil/trace.go:171","msg":"trace[377259243] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2165; }","duration":"146.154324ms","start":"2026-08-12T11:09:49.76889Z","end":"2026-08-12T11:09:49.915045Z","steps":["trace[377259243] 'agreement among raft nodes before linearized reading' (duration: 146.018712ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:13:41.082853Z","caller":"traceutil/trace.go:171","msg":"trace[1609608818] transaction","detail":"{read_only:false; response_revision:3792; number_of_response:1; }","duration":"113.000362ms","start":"2026-08-12T11:13:40.969807Z","end":"2026-08-12T11:13:41.082807Z","steps":["trace[1609608818] 'process raft request' (duration: 112.768248ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:15:05.147532Z","caller":"traceutil/trace.go:171","msg":"trace[835596813] transaction","detail":"{read_only:false; response_revision:4894; number_of_response:1; }","duration":"154.169039ms","start":"2026-08-12T11:15:04.993317Z","end":"2026-08-12T11:15:05.147486Z","steps":["trace[835596813] 'process raft request' (duration: 153.918025ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:15:05.147574Z","caller":"traceutil/trace.go:171","msg":"trace[368315259] transaction","detail":"{read_only:false; response_revision:4895; number_of_response:1; }","duration":"154.171349ms","start":"2026-08-12T11:15:04.993362Z","end":"2026-08-12T11:15:05.147533Z","steps":["trace[368315259] 'process raft request' (duration: 154.036687ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:15:05.147586Z","caller":"traceutil/trace.go:171","msg":"trace[1704312278] transaction","detail":"{read_only:false; response_revision:4897; number_of_response:1; }","duration":"153.288371ms","start":"2026-08-12T11:15:04.994257Z","end":"2026-08-12T11:15:05.147545Z","steps":["trace[1704312278] 'process raft request' (duration: 153.246591ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:15:05.147701Z","caller":"traceutil/trace.go:171","msg":"trace[919321611] transaction","detail":"{read_only:false; response_revision:4896; number_of_response:1; }","duration":"154.262451ms","start":"2026-08-12T11:15:04.99342Z","end":"2026-08-12T11:15:05.147683Z","steps":["trace[919321611] 'process raft request' (duration: 154.043467ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:15:05.150642Z","caller":"traceutil/trace.go:171","msg":"trace[110777487] transaction","detail":"{read_only:false; response_revision:4898; number_of_response:1; }","duration":"114.198384ms","start":"2026-08-12T11:15:05.036426Z","end":"2026-08-12T11:15:05.150624Z","steps":["trace[110777487] 'process raft request' (duration: 114.098403ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:15:07.502971Z","caller":"traceutil/trace.go:171","msg":"trace[926501718] transaction","detail":"{read_only:false; response_revision:4972; number_of_response:1; }","duration":"105.186062ms","start":"2026-08-12T11:15:07.397762Z","end":"2026-08-12T11:15:07.502948Z","steps":["trace[926501718] 'process raft request' (duration: 105.045179ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:15:20.67862Z","caller":"traceutil/trace.go:171","msg":"trace[1746877824] transaction","detail":"{read_only:false; response_revision:5240; number_of_response:1; }","duration":"193.772687ms","start":"2026-08-12T11:15:20.484819Z","end":"2026-08-12T11:15:20.678592Z","steps":["trace[1746877824] 'process raft request' (duration: 193.601534ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:15:28.422036Z","caller":"traceutil/trace.go:171","msg":"trace[771391342] linearizableReadLoop","detail":"{readStateIndex:5622; appliedIndex:5621; }","duration":"172.568662ms","start":"2026-08-12T11:15:28.249447Z","end":"2026-08-12T11:15:28.422015Z","steps":["trace[771391342] 'read index received' (duration: 172.385458ms)","trace[771391342] 'applied index is now lower than readState.Index' (duration: 182.564µs)"],"step_count":2} {"level":"warn","ts":"2026-08-12T11:15:28.42223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.776936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1781"} {"level":"info","ts":"2026-08-12T11:15:28.42226Z","caller":"traceutil/trace.go:171","msg":"trace[340591688] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:5409; }","duration":"172.875038ms","start":"2026-08-12T11:15:28.249375Z","end":"2026-08-12T11:15:28.42225Z","steps":["trace[340591688] 'agreement among raft nodes before linearized reading' (duration: 172.728625ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:15:28.422175Z","caller":"traceutil/trace.go:171","msg":"trace[112897406] transaction","detail":"{read_only:false; response_revision:5409; number_of_response:1; }","duration":"198.272803ms","start":"2026-08-12T11:15:28.223859Z","end":"2026-08-12T11:15:28.422131Z","steps":["trace[112897406] 'process raft request' (duration: 198.031588ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:15:28.580019Z","caller":"traceutil/trace.go:171","msg":"trace[17052058] transaction","detail":"{read_only:false; response_revision:5411; number_of_response:1; }","duration":"151.676352ms","start":"2026-08-12T11:15:28.428313Z","end":"2026-08-12T11:15:28.57999Z","steps":["trace[17052058] 'process raft request' (duration: 141.356394ms)","trace[17052058] 'compare' (duration: 10.019772ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:15:28.580318Z","caller":"traceutil/trace.go:171","msg":"trace[1277605968] transaction","detail":"{read_only:false; response_revision:5412; number_of_response:1; }","duration":"151.913236ms","start":"2026-08-12T11:15:28.428358Z","end":"2026-08-12T11:15:28.580271Z","steps":["trace[1277605968] 'process raft request' (duration: 151.55999ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:15:28.581297Z","caller":"traceutil/trace.go:171","msg":"trace[1766648230] transaction","detail":"{read_only:false; response_revision:5413; number_of_response:1; }","duration":"152.517148ms","start":"2026-08-12T11:15:28.428741Z","end":"2026-08-12T11:15:28.581258Z","steps":["trace[1766648230] 'process raft request' (duration: 151.412647ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:15:28.581482Z","caller":"traceutil/trace.go:171","msg":"trace[505199166] transaction","detail":"{read_only:false; response_revision:5414; number_of_response:1; }","duration":"152.052659ms","start":"2026-08-12T11:15:28.429419Z","end":"2026-08-12T11:15:28.581471Z","steps":["trace[505199166] 'process raft request' (duration: 151.719082ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:15:28.582111Z","caller":"traceutil/trace.go:171","msg":"trace[931173341] transaction","detail":"{read_only:false; response_revision:5416; number_of_response:1; }","duration":"149.46104ms","start":"2026-08-12T11:15:28.43263Z","end":"2026-08-12T11:15:28.582091Z","steps":["trace[931173341] 'process raft request' (duration: 149.379709ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:15:28.582099Z","caller":"traceutil/trace.go:171","msg":"trace[382163857] transaction","detail":"{read_only:false; response_revision:5415; number_of_response:1; }","duration":"151.286854ms","start":"2026-08-12T11:15:28.430783Z","end":"2026-08-12T11:15:28.58207Z","steps":["trace[382163857] 'process raft request' (duration: 150.60792ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:16:40.465047Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2960} {"level":"info","ts":"2026-08-12T11:16:40.527726Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2960,"took":"61.234932ms","hash":1501843849,"current-db-size-bytes":57401344,"current-db-size":"57 MB","current-db-size-in-use-bytes":33931264,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-08-12T11:16:40.527825Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1501843849,"revision":2960,"compact-revision":-1} {"level":"info","ts":"2026-08-12T11:21:39.486398Z","caller":"traceutil/trace.go:171","msg":"trace[508936270] linearizableReadLoop","detail":"{readStateIndex:8555; appliedIndex:8554; }","duration":"233.11466ms","start":"2026-08-12T11:21:39.253253Z","end":"2026-08-12T11:21:39.486368Z","steps":["trace[508936270] 'read index received' (duration: 232.847825ms)","trace[508936270] 'applied index is now lower than readState.Index' (duration: 265.065µs)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:21:39.486564Z","caller":"traceutil/trace.go:171","msg":"trace[27057854] transaction","detail":"{read_only:false; response_revision:8253; number_of_response:1; }","duration":"251.94438ms","start":"2026-08-12T11:21:39.234581Z","end":"2026-08-12T11:21:39.486525Z","steps":["trace[27057854] 'process raft request' (duration: 251.597413ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:21:39.486751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.412561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1988"} {"level":"info","ts":"2026-08-12T11:21:39.48682Z","caller":"traceutil/trace.go:171","msg":"trace[1514875505] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:8253; }","duration":"181.536093ms","start":"2026-08-12T11:21:39.305264Z","end":"2026-08-12T11:21:39.4868Z","steps":["trace[1514875505] 'agreement among raft nodes before linearized reading' (duration: 181.315599ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:21:39.486836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.556168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-stv2r\" ","response":"range_response_count:1 size:6520"} {"level":"warn","ts":"2026-08-12T11:21:39.48687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.277863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/probes/\" range_end:\"/registry/monitoring.coreos.com/probes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T11:21:39.486935Z","caller":"traceutil/trace.go:171","msg":"trace[479927883] range","detail":"{range_begin:/registry/pods/openstack/keepalived-stv2r; range_end:; response_count:1; response_revision:8253; }","duration":"233.715681ms","start":"2026-08-12T11:21:39.253196Z","end":"2026-08-12T11:21:39.486911Z","steps":["trace[479927883] 'agreement among raft nodes before linearized reading' (duration: 233.382925ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:21:39.486988Z","caller":"traceutil/trace.go:171","msg":"trace[1630464080] range","detail":"{range_begin:/registry/monitoring.coreos.com/probes/; range_end:/registry/monitoring.coreos.com/probes0; response_count:0; response_revision:8253; }","duration":"140.491868ms","start":"2026-08-12T11:21:39.346468Z","end":"2026-08-12T11:21:39.48696Z","steps":["trace[1630464080] 'agreement among raft nodes before linearized reading' (duration: 140.204193ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:21:39.671588Z","caller":"traceutil/trace.go:171","msg":"trace[55625854] linearizableReadLoop","detail":"{readStateIndex:8556; appliedIndex:8555; }","duration":"149.738914ms","start":"2026-08-12T11:21:39.521829Z","end":"2026-08-12T11:21:39.671568Z","steps":["trace[55625854] 'read index received' (duration: 71.203742ms)","trace[55625854] 'applied index is now lower than readState.Index' (duration: 78.534462ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:21:39.671672Z","caller":"traceutil/trace.go:171","msg":"trace[1914409021] transaction","detail":"{read_only:false; response_revision:8254; number_of_response:1; }","duration":"176.888473ms","start":"2026-08-12T11:21:39.494722Z","end":"2026-08-12T11:21:39.671611Z","steps":["trace[1914409021] 'process raft request' (duration: 98.29069ms)","trace[1914409021] 'compare' (duration: 78.444281ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T11:21:39.671745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.892827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-12T11:21:39.671808Z","caller":"traceutil/trace.go:171","msg":"trace[1933285529] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:8254; }","duration":"150.02147ms","start":"2026-08-12T11:21:39.521769Z","end":"2026-08-12T11:21:39.67179Z","steps":["trace[1933285529] 'agreement among raft nodes before linearized reading' (duration: 149.899278ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:21:40.474469Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6073} {"level":"info","ts":"2026-08-12T11:21:40.573743Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6073,"took":"97.020586ms","hash":4161001107,"current-db-size-bytes":57401344,"current-db-size":"57 MB","current-db-size-in-use-bytes":23314432,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-08-12T11:21:40.573832Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4161001107,"revision":6073,"compact-revision":2960} {"level":"info","ts":"2026-08-12T11:22:20.907423Z","caller":"traceutil/trace.go:171","msg":"trace[687568568] transaction","detail":"{read_only:false; response_revision:8533; number_of_response:1; }","duration":"113.541081ms","start":"2026-08-12T11:22:20.793852Z","end":"2026-08-12T11:22:20.907393Z","steps":["trace[687568568] 'process raft request' (duration: 113.372488ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:23:11.548994Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002472.wal"} {"level":"info","ts":"2026-08-12T11:24:24.914924Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"2b21fdc9c735cc27","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-12T11:24:24.921295Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-08-12T11:24:24.921411Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-08-12T11:26:40.48077Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8265} {"level":"info","ts":"2026-08-12T11:26:40.556879Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8265,"took":"73.70079ms","hash":3228324277,"current-db-size-bytes":57401344,"current-db-size":"57 MB","current-db-size-in-use-bytes":26456064,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-08-12T11:26:40.556954Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3228324277,"revision":8265,"compact-revision":6073} {"level":"info","ts":"2026-08-12T11:31:40.486618Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10754} {"level":"info","ts":"2026-08-12T11:31:40.572118Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10754,"took":"82.790774ms","hash":2656579775,"current-db-size-bytes":57401344,"current-db-size":"57 MB","current-db-size-in-use-bytes":26992640,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-08-12T11:31:40.572183Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2656579775,"revision":10754,"compact-revision":8265} {"level":"info","ts":"2026-08-12T11:33:49.754705Z","caller":"traceutil/trace.go:171","msg":"trace[1083856068] transaction","detail":"{read_only:false; response_revision:13771; number_of_response:1; }","duration":"116.374537ms","start":"2026-08-12T11:33:49.638299Z","end":"2026-08-12T11:33:49.754674Z","steps":["trace[1083856068] 'process raft request' (duration: 116.107192ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:34:06.041266Z","caller":"traceutil/trace.go:171","msg":"trace[1320568317] linearizableReadLoop","detail":"{readStateIndex:14357; appliedIndex:14356; }","duration":"205.167768ms","start":"2026-08-12T11:34:05.836072Z","end":"2026-08-12T11:34:06.04124Z","steps":["trace[1320568317] 'read index received' (duration: 204.987465ms)","trace[1320568317] 'applied index is now lower than readState.Index' (duration: 179.363µs)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:34:06.041385Z","caller":"traceutil/trace.go:171","msg":"trace[583763228] transaction","detail":"{read_only:false; response_revision:13870; number_of_response:1; }","duration":"234.427097ms","start":"2026-08-12T11:34:05.806897Z","end":"2026-08-12T11:34:06.041324Z","steps":["trace[583763228] 'process raft request' (duration: 234.187622ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:34:06.041513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.410694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T11:34:06.041563Z","caller":"traceutil/trace.go:171","msg":"trace[1372127498] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:13870; }","duration":"205.525915ms","start":"2026-08-12T11:34:05.836025Z","end":"2026-08-12T11:34:06.041551Z","steps":["trace[1372127498] 'agreement among raft nodes before linearized reading' (duration: 205.417993ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:34:06.041777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.185143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-08-12T11:34:06.041818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.715605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/challenges/\" range_end:\"/registry/acme.cert-manager.io/challenges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-12T11:34:06.041855Z","caller":"traceutil/trace.go:171","msg":"trace[1194623939] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:13870; }","duration":"117.312375ms","start":"2026-08-12T11:34:05.924526Z","end":"2026-08-12T11:34:06.041838Z","steps":["trace[1194623939] 'agreement among raft nodes before linearized reading' (duration: 117.110891ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:34:06.041869Z","caller":"traceutil/trace.go:171","msg":"trace[644945517] range","detail":"{range_begin:/registry/acme.cert-manager.io/challenges/; range_end:/registry/acme.cert-manager.io/challenges0; response_count:0; response_revision:13870; }","duration":"111.803496ms","start":"2026-08-12T11:34:05.93005Z","end":"2026-08-12T11:34:06.041853Z","steps":["trace[644945517] 'agreement among raft nodes before linearized reading' (duration: 111.674814ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:34:06.042075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.032231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-08-12T11:34:06.042158Z","caller":"traceutil/trace.go:171","msg":"trace[864858110] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:13870; }","duration":"112.158844ms","start":"2026-08-12T11:34:05.929979Z","end":"2026-08-12T11:34:06.042138Z","steps":["trace[864858110] 'agreement among raft nodes before linearized reading' (duration: 111.95596ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:34:06.229767Z","caller":"traceutil/trace.go:171","msg":"trace[738583521] linearizableReadLoop","detail":"{readStateIndex:14358; appliedIndex:14357; }","duration":"183.388278ms","start":"2026-08-12T11:34:06.046353Z","end":"2026-08-12T11:34:06.229741Z","steps":["trace[738583521] 'read index received' (duration: 152.470338ms)","trace[738583521] 'applied index is now lower than readState.Index' (duration: 30.91687ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T11:34:06.229978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.605823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-08-12T11:34:06.229952Z","caller":"traceutil/trace.go:171","msg":"trace[504340990] transaction","detail":"{read_only:false; response_revision:13871; number_of_response:1; }","duration":"183.97488ms","start":"2026-08-12T11:34:06.045927Z","end":"2026-08-12T11:34:06.229901Z","steps":["trace[504340990] 'process raft request' (duration: 152.901317ms)","trace[504340990] 'compare' (duration: 30.776908ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:34:06.230018Z","caller":"traceutil/trace.go:171","msg":"trace[896059395] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:13871; }","duration":"183.685015ms","start":"2026-08-12T11:34:06.046322Z","end":"2026-08-12T11:34:06.230007Z","steps":["trace[896059395] 'agreement among raft nodes before linearized reading' (duration: 183.511221ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:35:53.824904Z","caller":"traceutil/trace.go:171","msg":"trace[993009533] transaction","detail":"{read_only:false; response_revision:15115; number_of_response:1; }","duration":"132.76169ms","start":"2026-08-12T11:35:53.691255Z","end":"2026-08-12T11:35:53.824017Z","steps":["trace[993009533] 'process raft request' (duration: 123.993061ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:35:54.37687Z","caller":"traceutil/trace.go:171","msg":"trace[1494424452] transaction","detail":"{read_only:false; response_revision:15118; number_of_response:1; }","duration":"103.825582ms","start":"2026-08-12T11:35:54.273012Z","end":"2026-08-12T11:35:54.376838Z","steps":["trace[1494424452] 'process raft request' (duration: 96.270327ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:36:40.493605Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12827} {"level":"info","ts":"2026-08-12T11:36:40.574015Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12827,"took":"77.957212ms","hash":4203352636,"current-db-size-bytes":57401344,"current-db-size":"57 MB","current-db-size-in-use-bytes":30773248,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-12T11:36:40.574076Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4203352636,"revision":12827,"compact-revision":10754} {"level":"info","ts":"2026-08-12T11:41:37.412095Z","caller":"traceutil/trace.go:171","msg":"trace[1712657478] transaction","detail":"{read_only:false; response_revision:17746; number_of_response:1; }","duration":"143.650431ms","start":"2026-08-12T11:41:37.268415Z","end":"2026-08-12T11:41:37.412065Z","steps":["trace[1712657478] 'process raft request' (duration: 116.883465ms)","trace[1712657478] 'compare' (duration: 26.654424ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:41:37.770574Z","caller":"traceutil/trace.go:171","msg":"trace[1704381235] transaction","detail":"{read_only:false; response_revision:17747; number_of_response:1; }","duration":"217.355162ms","start":"2026-08-12T11:41:37.553192Z","end":"2026-08-12T11:41:37.770547Z","steps":["trace[1704381235] 'process raft request' (duration: 217.181889ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:41:38.097317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.220697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-08-12T11:41:38.097408Z","caller":"traceutil/trace.go:171","msg":"trace[1145647656] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:17748; }","duration":"137.34641ms","start":"2026-08-12T11:41:37.960042Z","end":"2026-08-12T11:41:38.097389Z","steps":["trace[1145647656] 'range keys from in-memory index tree' (duration: 137.093455ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:41:38.097431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.409511ms","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-08-12T11:41:38.0975Z","caller":"traceutil/trace.go:171","msg":"trace[636707756] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:17748; }","duration":"137.518003ms","start":"2026-08-12T11:41:37.959967Z","end":"2026-08-12T11:41:38.097485Z","steps":["trace[636707756] 'range keys from in-memory index tree' (duration: 137.230028ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:41:38.314008Z","caller":"traceutil/trace.go:171","msg":"trace[2096004284] transaction","detail":"{read_only:false; response_revision:17749; number_of_response:1; }","duration":"350.776517ms","start":"2026-08-12T11:41:37.963202Z","end":"2026-08-12T11:41:38.313979Z","steps":["trace[2096004284] 'process raft request' (duration: 350.597574ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:41:38.314196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:41:37.963189Z","time spent":"350.905079ms","remote":"127.0.0.1:47504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T11:41:38.334293Z","caller":"traceutil/trace.go:171","msg":"trace[845094407] transaction","detail":"{read_only:false; response_revision:17751; number_of_response:1; }","duration":"232.081477ms","start":"2026-08-12T11:41:38.10219Z","end":"2026-08-12T11:41:38.334271Z","steps":["trace[845094407] 'process raft request' (duration: 231.981805ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:41:38.33431Z","caller":"traceutil/trace.go:171","msg":"trace[1579130548] linearizableReadLoop","detail":"{readStateIndex:18361; appliedIndex:18360; }","duration":"278.29039ms","start":"2026-08-12T11:41:38.055948Z","end":"2026-08-12T11:41:38.334239Z","steps":["trace[1579130548] 'read index received' (duration: 258.255872ms)","trace[1579130548] 'applied index is now lower than readState.Index' (duration: 20.033848ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:41:38.334339Z","caller":"traceutil/trace.go:171","msg":"trace[2069836766] transaction","detail":"{read_only:false; response_revision:17752; number_of_response:1; }","duration":"231.819572ms","start":"2026-08-12T11:41:38.102493Z","end":"2026-08-12T11:41:38.334313Z","steps":["trace[2069836766] 'process raft request' (duration: 231.71235ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:41:38.334522Z","caller":"traceutil/trace.go:171","msg":"trace[902278032] transaction","detail":"{read_only:false; response_revision:17750; number_of_response:1; }","duration":"326.463389ms","start":"2026-08-12T11:41:38.008042Z","end":"2026-08-12T11:41:38.334506Z","steps":["trace[902278032] 'process raft request' (duration: 325.956279ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:41:38.334632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:41:38.008012Z","time spent":"326.56004ms","remote":"127.0.0.1:47504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T11:41:38.336068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.928991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-p759r\" ","response":"range_response_count:1 size:20751"} {"level":"info","ts":"2026-08-12T11:41:38.336146Z","caller":"traceutil/trace.go:171","msg":"trace[2107185297] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-p759r; range_end:; response_count:1; response_revision:17752; }","duration":"280.221486ms","start":"2026-08-12T11:41:38.055907Z","end":"2026-08-12T11:41:38.336129Z","steps":["trace[2107185297] 'agreement among raft nodes before linearized reading' (duration: 278.479493ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:41:40.503316Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15418} {"level":"info","ts":"2026-08-12T11:41:40.602049Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15418,"took":"95.663105ms","hash":3332820403,"current-db-size-bytes":57401344,"current-db-size":"57 MB","current-db-size-in-use-bytes":32890880,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-08-12T11:41:40.602109Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3332820403,"revision":15418,"compact-revision":12827} {"level":"info","ts":"2026-08-12T11:44:56.347078Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"2b21fdc9c735cc27","local-member-applied-index":20003,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-12T11:44:56.353161Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20003} {"level":"info","ts":"2026-08-12T11:44:56.353294Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15003} {"level":"info","ts":"2026-08-12T11:46:40.5094Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17782} {"level":"info","ts":"2026-08-12T11:46:40.608744Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17782,"took":"94.191877ms","hash":1607412256,"current-db-size-bytes":57401344,"current-db-size":"57 MB","current-db-size-in-use-bytes":34336768,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-08-12T11:46:40.6088Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1607412256,"revision":17782,"compact-revision":15418} {"level":"info","ts":"2026-08-12T11:47:05.470027Z","caller":"traceutil/trace.go:171","msg":"trace[1796408081] transaction","detail":"{read_only:false; response_revision:20246; number_of_response:1; }","duration":"123.615225ms","start":"2026-08-12T11:47:05.346375Z","end":"2026-08-12T11:47:05.46999Z","steps":["trace[1796408081] 'process raft request' (duration: 123.38183ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:47:15.666169Z","caller":"traceutil/trace.go:171","msg":"trace[305639764] transaction","detail":"{read_only:false; response_revision:20301; number_of_response:1; }","duration":"114.571011ms","start":"2026-08-12T11:47:15.55158Z","end":"2026-08-12T11:47:15.666151Z","steps":["trace[305639764] 'process raft request' (duration: 114.246525ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:47:15.667017Z","caller":"traceutil/trace.go:171","msg":"trace[1666361825] transaction","detail":"{read_only:false; response_revision:20302; number_of_response:1; }","duration":"115.156902ms","start":"2026-08-12T11:47:15.551837Z","end":"2026-08-12T11:47:15.666994Z","steps":["trace[1666361825] 'process raft request' (duration: 114.808865ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:47:21.224227Z","caller":"traceutil/trace.go:171","msg":"trace[1650112848] transaction","detail":"{read_only:false; response_revision:20328; number_of_response:1; }","duration":"256.912117ms","start":"2026-08-12T11:47:20.96728Z","end":"2026-08-12T11:47:21.224192Z","steps":["trace[1650112848] 'process raft request' (duration: 256.568111ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:47:21.655504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.748816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-c3fd24eb-fda4-435d-98d2-e8d22d2dfb53\" ","response":"range_response_count:1 size:1284"} {"level":"info","ts":"2026-08-12T11:47:21.655585Z","caller":"traceutil/trace.go:171","msg":"trace[2038788606] range","detail":"{range_begin:/registry/persistentvolumes/pvc-c3fd24eb-fda4-435d-98d2-e8d22d2dfb53; range_end:; response_count:1; response_revision:20329; }","duration":"126.858659ms","start":"2026-08-12T11:47:21.5287Z","end":"2026-08-12T11:47:21.655559Z","steps":["trace[2038788606] 'range keys from in-memory index tree' (duration: 126.577023ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:47:21.655818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.55495ms","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-08-12T11:47:21.655876Z","caller":"traceutil/trace.go:171","msg":"trace[1189738217] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:20329; }","duration":"101.659512ms","start":"2026-08-12T11:47:21.554201Z","end":"2026-08-12T11:47:21.655861Z","steps":["trace[1189738217] 'range keys from in-memory index tree' (duration: 101.360246ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:47:21.798759Z","caller":"traceutil/trace.go:171","msg":"trace[160228127] transaction","detail":"{read_only:false; response_revision:20330; number_of_response:1; }","duration":"138.287518ms","start":"2026-08-12T11:47:21.660443Z","end":"2026-08-12T11:47:21.79873Z","steps":["trace[160228127] 'process raft request' (duration: 138.134165ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:47:22.083611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.613732ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-12T11:47:22.083883Z","caller":"traceutil/trace.go:171","msg":"trace[2089742276] linearizableReadLoop","detail":"{readStateIndex:21034; appliedIndex:21030; }","duration":"316.303982ms","start":"2026-08-12T11:47:21.767561Z","end":"2026-08-12T11:47:22.083865Z","steps":["trace[2089742276] 'read index received' (duration: 31.12372ms)","trace[2089742276] 'applied index is now lower than readState.Index' (duration: 285.179022ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:47:22.083888Z","caller":"traceutil/trace.go:171","msg":"trace[1800362984] transaction","detail":"{read_only:false; response_revision:20331; number_of_response:1; }","duration":"399.910956ms","start":"2026-08-12T11:47:21.683936Z","end":"2026-08-12T11:47:22.083847Z","steps":["trace[1800362984] 'process raft request' (duration: 243.967457ms)","trace[1800362984] 'compare' (duration: 155.484119ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:47:22.083943Z","caller":"traceutil/trace.go:171","msg":"trace[2019644593] transaction","detail":"{read_only:false; response_revision:20333; number_of_response:1; }","duration":"388.501046ms","start":"2026-08-12T11:47:21.69542Z","end":"2026-08-12T11:47:22.083921Z","steps":["trace[2019644593] 'process raft request' (duration: 388.403534ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:47:22.083945Z","caller":"traceutil/trace.go:171","msg":"trace[869234575] transaction","detail":"{read_only:false; response_revision:20332; number_of_response:1; }","duration":"388.777981ms","start":"2026-08-12T11:47:21.695139Z","end":"2026-08-12T11:47:22.083917Z","steps":["trace[869234575] 'process raft request' (duration: 388.597978ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:47:22.083998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.441445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-12T11:47:22.08403Z","caller":"traceutil/trace.go:171","msg":"trace[575164888] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20333; }","duration":"316.509026ms","start":"2026-08-12T11:47:21.76751Z","end":"2026-08-12T11:47:22.084019Z","steps":["trace[575164888] 'agreement among raft nodes before linearized reading' (duration: 316.454725ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:47:22.084036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:47:21.683901Z","time spent":"400.053208ms","remote":"127.0.0.1:47504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T11:47:22.084061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:47:21.767491Z","time spent":"316.564277ms","remote":"127.0.0.1:47252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-12T11:47:22.084064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:47:21.695415Z","time spent":"388.586577ms","remote":"127.0.0.1:47504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T11:47:22.084086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:47:21.695121Z","time spent":"388.886443ms","remote":"127.0.0.1:47504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T11:47:22.282375Z","caller":"traceutil/trace.go:171","msg":"trace[1998375905] linearizableReadLoop","detail":"{readStateIndex:21036; appliedIndex:21034; }","duration":"161.338452ms","start":"2026-08-12T11:47:22.121009Z","end":"2026-08-12T11:47:22.282348Z","steps":["trace[1998375905] 'read index received' (duration: 2.653021ms)","trace[1998375905] 'applied index is now lower than readState.Index' (duration: 158.684561ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:47:22.282457Z","caller":"traceutil/trace.go:171","msg":"trace[1342837086] transaction","detail":"{read_only:false; response_revision:20335; number_of_response:1; }","duration":"193.486393ms","start":"2026-08-12T11:47:22.088937Z","end":"2026-08-12T11:47:22.282424Z","steps":["trace[1342837086] 'process raft request' (duration: 140.344297ms)","trace[1342837086] 'compare' (duration: 52.944382ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T11:47:22.282621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.588018ms","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-08-12T11:47:22.282667Z","caller":"traceutil/trace.go:171","msg":"trace[1008811708] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:20335; }","duration":"161.672119ms","start":"2026-08-12T11:47:22.120981Z","end":"2026-08-12T11:47:22.282653Z","steps":["trace[1008811708] 'agreement among raft nodes before linearized reading' (duration: 161.510945ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:51:05.358002Z","caller":"traceutil/trace.go:171","msg":"trace[414887659] transaction","detail":"{read_only:false; response_revision:22697; number_of_response:1; }","duration":"221.221509ms","start":"2026-08-12T11:51:05.13676Z","end":"2026-08-12T11:51:05.357982Z","steps":["trace[414887659] 'process raft request' (duration: 220.954543ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:51:05.357987Z","caller":"traceutil/trace.go:171","msg":"trace[560955687] linearizableReadLoop","detail":"{readStateIndex:23469; appliedIndex:23468; }","duration":"121.435703ms","start":"2026-08-12T11:51:05.23651Z","end":"2026-08-12T11:51:05.357946Z","steps":["trace[560955687] 'read index received' (duration: 121.163327ms)","trace[560955687] 'applied index is now lower than readState.Index' (duration: 270.836µs)"],"step_count":2} {"level":"warn","ts":"2026-08-12T11:51:05.358228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.727669ms","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-08-12T11:51:05.358326Z","caller":"traceutil/trace.go:171","msg":"trace[516934476] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:22697; }","duration":"121.833311ms","start":"2026-08-12T11:51:05.236438Z","end":"2026-08-12T11:51:05.358272Z","steps":["trace[516934476] 'agreement among raft nodes before linearized reading' (duration: 121.611967ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:51:40.515958Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20120} {"level":"info","ts":"2026-08-12T11:51:40.622311Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20120,"took":"102.716962ms","hash":4027263884,"current-db-size-bytes":57401344,"current-db-size":"57 MB","current-db-size-in-use-bytes":42332160,"current-db-size-in-use":"42 MB"} {"level":"info","ts":"2026-08-12T11:51:40.622357Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4027263884,"revision":20120,"compact-revision":17782} {"level":"warn","ts":"2026-08-12T11:54:31.899584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.245092ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-12T11:54:31.899888Z","caller":"traceutil/trace.go:171","msg":"trace[658703149] transaction","detail":"{read_only:false; response_revision:24784; number_of_response:1; }","duration":"120.7103ms","start":"2026-08-12T11:54:31.779159Z","end":"2026-08-12T11:54:31.89987Z","steps":["trace[658703149] 'process raft request' (duration: 120.665899ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:54:31.900241Z","caller":"traceutil/trace.go:171","msg":"trace[195801257] transaction","detail":"{read_only:false; response_revision:24782; number_of_response:1; }","duration":"230.509469ms","start":"2026-08-12T11:54:31.669719Z","end":"2026-08-12T11:54:31.900228Z","steps":["trace[195801257] 'process raft request' (duration: 111.539283ms)","trace[195801257] 'compare' (duration: 118.023517ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:54:31.90038Z","caller":"traceutil/trace.go:171","msg":"trace[1049587293] linearizableReadLoop","detail":"{readStateIndex:25608; appliedIndex:25607; }","duration":"219.123747ms","start":"2026-08-12T11:54:31.681246Z","end":"2026-08-12T11:54:31.900369Z","steps":["trace[1049587293] 'read index received' (duration: 100.027759ms)","trace[1049587293] 'applied index is now lower than readState.Index' (duration: 119.095028ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T11:54:31.900454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.196529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-12T11:54:31.900486Z","caller":"traceutil/trace.go:171","msg":"trace[1666629043] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:24784; }","duration":"219.25589ms","start":"2026-08-12T11:54:31.681221Z","end":"2026-08-12T11:54:31.900477Z","steps":["trace[1666629043] 'agreement among raft nodes before linearized reading' (duration: 219.189289ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:54:31.90062Z","caller":"traceutil/trace.go:171","msg":"trace[958704991] transaction","detail":"{read_only:false; response_revision:24783; number_of_response:1; }","duration":"183.640714ms","start":"2026-08-12T11:54:31.71697Z","end":"2026-08-12T11:54:31.900611Z","steps":["trace[958704991] 'process raft request' (duration: 182.775627ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:54:32.158828Z","caller":"traceutil/trace.go:171","msg":"trace[277518695] linearizableReadLoop","detail":"{readStateIndex:25612; appliedIndex:25610; }","duration":"163.935723ms","start":"2026-08-12T11:54:31.994869Z","end":"2026-08-12T11:54:32.158805Z","steps":["trace[277518695] 'read index received' (duration: 100.443618ms)","trace[277518695] 'applied index is now lower than readState.Index' (duration: 63.488985ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T11:54:32.159035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.167848ms","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-08-12T11:54:32.159075Z","caller":"traceutil/trace.go:171","msg":"trace[765460931] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24786; }","duration":"164.259339ms","start":"2026-08-12T11:54:31.994806Z","end":"2026-08-12T11:54:32.159066Z","steps":["trace[765460931] 'agreement among raft nodes before linearized reading' (duration: 164.089996ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:54:32.159232Z","caller":"traceutil/trace.go:171","msg":"trace[122043552] transaction","detail":"{read_only:false; response_revision:24785; number_of_response:1; }","duration":"367.890588ms","start":"2026-08-12T11:54:31.791278Z","end":"2026-08-12T11:54:32.159169Z","steps":["trace[122043552] 'process raft request' (duration: 304.023606ms)","trace[122043552] 'compare' (duration: 62.980815ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:54:32.159311Z","caller":"traceutil/trace.go:171","msg":"trace[1886706119] transaction","detail":"{read_only:false; response_revision:24786; number_of_response:1; }","duration":"274.935745ms","start":"2026-08-12T11:54:31.884361Z","end":"2026-08-12T11:54:32.159297Z","steps":["trace[1886706119] 'process raft request' (duration: 274.366054ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:54:32.159503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:54:31.791247Z","time spent":"368.104022ms","remote":"127.0.0.1:47504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T11:54:35.497573Z","caller":"traceutil/trace.go:171","msg":"trace[1728111530] transaction","detail":"{read_only:false; response_revision:24817; number_of_response:1; }","duration":"133.70556ms","start":"2026-08-12T11:54:35.363839Z","end":"2026-08-12T11:54:35.497545Z","steps":["trace[1728111530] 'process raft request' (duration: 133.527017ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:54:35.808794Z","caller":"traceutil/trace.go:171","msg":"trace[1037757014] transaction","detail":"{read_only:false; response_revision:24818; number_of_response:1; }","duration":"147.016947ms","start":"2026-08-12T11:54:35.661757Z","end":"2026-08-12T11:54:35.808774Z","steps":["trace[1037757014] 'process raft request' (duration: 146.552089ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:54:36.078958Z","caller":"traceutil/trace.go:171","msg":"trace[479460274] transaction","detail":"{read_only:false; response_revision:24819; number_of_response:1; }","duration":"225.187424ms","start":"2026-08-12T11:54:35.85375Z","end":"2026-08-12T11:54:36.078938Z","steps":["trace[479460274] 'process raft request' (duration: 225.024731ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:54:36.217575Z","caller":"traceutil/trace.go:171","msg":"trace[1290526697] transaction","detail":"{read_only:false; response_revision:24820; number_of_response:1; }","duration":"299.023159ms","start":"2026-08-12T11:54:35.91852Z","end":"2026-08-12T11:54:36.217543Z","steps":["trace[1290526697] 'process raft request' (duration: 274.20222ms)","trace[1290526697] 'compare' (duration: 24.467592ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:54:36.217588Z","caller":"traceutil/trace.go:171","msg":"trace[565479063] transaction","detail":"{read_only:false; response_revision:24821; number_of_response:1; }","duration":"299.009859ms","start":"2026-08-12T11:54:35.918543Z","end":"2026-08-12T11:54:36.217553Z","steps":["trace[565479063] 'process raft request' (duration: 298.868827ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:54:36.21775Z","caller":"traceutil/trace.go:171","msg":"trace[1351692223] linearizableReadLoop","detail":"{readStateIndex:25648; appliedIndex:25646; }","duration":"122.2811ms","start":"2026-08-12T11:54:36.095446Z","end":"2026-08-12T11:54:36.217727Z","steps":["trace[1351692223] 'read index received' (duration: 97.295787ms)","trace[1351692223] 'applied index is now lower than readState.Index' (duration: 24.984113ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T11:54:36.217967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.498784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-12T11:54:36.218009Z","caller":"traceutil/trace.go:171","msg":"trace[945245944] range","detail":"{range_begin:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/; range_end:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0; response_count:0; response_revision:24823; }","duration":"122.590346ms","start":"2026-08-12T11:54:36.095408Z","end":"2026-08-12T11:54:36.217998Z","steps":["trace[945245944] 'agreement among raft nodes before linearized reading' (duration: 122.506624ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:54:36.548562Z","caller":"traceutil/trace.go:171","msg":"trace[130173103] transaction","detail":"{read_only:false; response_revision:24824; number_of_response:1; }","duration":"293.010704ms","start":"2026-08-12T11:54:36.255525Z","end":"2026-08-12T11:54:36.548535Z","steps":["trace[130173103] 'process raft request' (duration: 292.854411ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:54:36.672233Z","caller":"traceutil/trace.go:171","msg":"trace[1500809193] linearizableReadLoop","detail":"{readStateIndex:25652; appliedIndex:25651; }","duration":"244.518297ms","start":"2026-08-12T11:54:36.427682Z","end":"2026-08-12T11:54:36.6722Z","steps":["trace[1500809193] 'read index received' (duration: 121.377891ms)","trace[1500809193] 'applied index is now lower than readState.Index' (duration: 123.138616ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:54:36.672398Z","caller":"traceutil/trace.go:171","msg":"trace[1840534688] transaction","detail":"{read_only:false; response_revision:24825; number_of_response:1; }","duration":"256.779984ms","start":"2026-08-12T11:54:36.415606Z","end":"2026-08-12T11:54:36.672386Z","steps":["trace[1840534688] 'process raft request' (duration: 254.413968ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:54:36.672579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.170162ms","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-08-12T11:54:36.67264Z","caller":"traceutil/trace.go:171","msg":"trace[436619347] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24825; }","duration":"215.268153ms","start":"2026-08-12T11:54:36.457357Z","end":"2026-08-12T11:54:36.672625Z","steps":["trace[436619347] 'agreement among raft nodes before linearized reading' (duration: 215.08419ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:54:36.67276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.065148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-08-12T11:54:36.672799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.440157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-08-12T11:54:36.672828Z","caller":"traceutil/trace.go:171","msg":"trace[1210492559] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24825; }","duration":"156.510169ms","start":"2026-08-12T11:54:36.516312Z","end":"2026-08-12T11:54:36.672822Z","steps":["trace[1210492559] 'agreement among raft nodes before linearized reading' (duration: 156.444628ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:54:36.672838Z","caller":"traceutil/trace.go:171","msg":"trace[226498761] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24825; }","duration":"245.17283ms","start":"2026-08-12T11:54:36.427647Z","end":"2026-08-12T11:54:36.67282Z","steps":["trace[226498761] 'agreement among raft nodes before linearized reading' (duration: 245.032127ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:54:36.923681Z","caller":"traceutil/trace.go:171","msg":"trace[1829009629] transaction","detail":"{read_only:false; response_revision:24830; number_of_response:1; }","duration":"106.77129ms","start":"2026-08-12T11:54:36.816796Z","end":"2026-08-12T11:54:36.923568Z","steps":["trace[1829009629] 'process raft request' (duration: 105.549666ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:54:37.289538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.366412ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-12T11:54:37.289645Z","caller":"traceutil/trace.go:171","msg":"trace[1494074354] linearizableReadLoop","detail":"{readStateIndex:25658; appliedIndex:25657; }","duration":"232.798592ms","start":"2026-08-12T11:54:37.05683Z","end":"2026-08-12T11:54:37.289628Z","steps":["trace[1494074354] 'read index received' (duration: 84.253717ms)","trace[1494074354] 'applied index is now lower than readState.Index' (duration: 148.543685ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T11:54:37.289774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.936235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachines/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachines0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-12T11:54:37.289758Z","caller":"traceutil/trace.go:171","msg":"trace[2023561604] transaction","detail":"{read_only:false; response_revision:24831; number_of_response:1; }","duration":"363.31991ms","start":"2026-08-12T11:54:36.926395Z","end":"2026-08-12T11:54:37.289715Z","steps":["trace[2023561604] 'process raft request' (duration: 214.693763ms)","trace[2023561604] 'compare' (duration: 148.235248ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:54:37.289831Z","caller":"traceutil/trace.go:171","msg":"trace[1752798106] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackmachines/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackmachines0; response_count:0; response_revision:24831; }","duration":"233.016816ms","start":"2026-08-12T11:54:37.056782Z","end":"2026-08-12T11:54:37.289799Z","steps":["trace[1752798106] 'agreement among raft nodes before linearized reading' (duration: 232.944715ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:54:37.289856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.565541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/subnets/\" range_end:\"/registry/openstack.k-orc.cloud/subnets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-12T11:54:37.289898Z","caller":"traceutil/trace.go:171","msg":"trace[535771384] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/subnets/; range_end:/registry/openstack.k-orc.cloud/subnets0; response_count:0; response_revision:24831; }","duration":"212.660523ms","start":"2026-08-12T11:54:37.077225Z","end":"2026-08-12T11:54:37.289885Z","steps":["trace[535771384] 'agreement among raft nodes before linearized reading' (duration: 212.582622ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:54:37.289904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:54:36.926373Z","time spent":"363.452633ms","remote":"127.0.0.1:47504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":544,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T11:54:37.290181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.759873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-12T11:54:37.29021Z","caller":"traceutil/trace.go:171","msg":"trace[1393744389] range","detail":"{range_begin:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/; range_end:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0; response_count:0; response_revision:24831; }","duration":"201.806124ms","start":"2026-08-12T11:54:37.088394Z","end":"2026-08-12T11:54:37.2902Z","steps":["trace[1393744389] 'agreement among raft nodes before linearized reading' (duration: 201.769503ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:54:41.67224Z","caller":"traceutil/trace.go:171","msg":"trace[1101473058] transaction","detail":"{read_only:false; response_revision:24863; number_of_response:1; }","duration":"126.382578ms","start":"2026-08-12T11:54:41.545829Z","end":"2026-08-12T11:54:41.672212Z","steps":["trace[1101473058] 'process raft request' (duration: 126.199784ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:54:42.004136Z","caller":"traceutil/trace.go:171","msg":"trace[216037449] linearizableReadLoop","detail":"{readStateIndex:25692; appliedIndex:25691; }","duration":"145.358943ms","start":"2026-08-12T11:54:41.858749Z","end":"2026-08-12T11:54:42.004108Z","steps":["trace[216037449] 'read index received' (duration: 145.229011ms)","trace[216037449] 'applied index is now lower than readState.Index' (duration: 128.792µs)"],"step_count":2} {"level":"warn","ts":"2026-08-12T11:54:42.004321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.550689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-12T11:54:42.004362Z","caller":"traceutil/trace.go:171","msg":"trace[1494996268] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24864; }","duration":"145.64468ms","start":"2026-08-12T11:54:41.858706Z","end":"2026-08-12T11:54:42.00435Z","steps":["trace[1494996268] 'agreement among raft nodes before linearized reading' (duration: 145.552098ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:54:42.004797Z","caller":"traceutil/trace.go:171","msg":"trace[1583885830] transaction","detail":"{read_only:false; response_revision:24864; number_of_response:1; }","duration":"170.633363ms","start":"2026-08-12T11:54:41.834127Z","end":"2026-08-12T11:54:42.004761Z","steps":["trace[1583885830] 'process raft request' (duration: 169.787266ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:54:42.246221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.754925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:129026"} {"level":"info","ts":"2026-08-12T11:54:42.246306Z","caller":"traceutil/trace.go:171","msg":"trace[367787482] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:24864; }","duration":"183.870668ms","start":"2026-08-12T11:54:42.062415Z","end":"2026-08-12T11:54:42.246286Z","steps":["trace[367787482] 'range keys from in-memory index tree' (duration: 183.533681ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:54:42.246877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.902219ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-12T11:54:42.247049Z","caller":"traceutil/trace.go:171","msg":"trace[1851334437] transaction","detail":"{read_only:false; response_revision:24865; number_of_response:1; }","duration":"179.636126ms","start":"2026-08-12T11:54:42.067391Z","end":"2026-08-12T11:54:42.247028Z","steps":["trace[1851334437] 'process raft request' (duration: 40.454642ms)","trace[1851334437] 'compare' (duration: 138.525162ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:54:42.663088Z","caller":"traceutil/trace.go:171","msg":"trace[1767850125] transaction","detail":"{read_only:false; response_revision:24869; number_of_response:1; }","duration":"411.5224ms","start":"2026-08-12T11:54:42.251536Z","end":"2026-08-12T11:54:42.663058Z","steps":["trace[1767850125] 'process raft request' (duration: 411.425858ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:54:42.663247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:54:42.251508Z","time spent":"411.659523ms","remote":"127.0.0.1:47504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T11:54:42.6634Z","caller":"traceutil/trace.go:171","msg":"trace[366870975] transaction","detail":"{read_only:false; response_revision:24867; number_of_response:1; }","duration":"422.620954ms","start":"2026-08-12T11:54:42.240764Z","end":"2026-08-12T11:54:42.663385Z","steps":["trace[366870975] 'process raft request' (duration: 422.043343ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:54:42.663487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:54:42.240732Z","time spent":"422.711936ms","remote":"127.0.0.1:47504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T11:54:42.663583Z","caller":"traceutil/trace.go:171","msg":"trace[923276054] transaction","detail":"{read_only:false; response_revision:24866; number_of_response:1; }","duration":"426.870157ms","start":"2026-08-12T11:54:42.236669Z","end":"2026-08-12T11:54:42.663539Z","steps":["trace[923276054] 'process raft request' (duration: 382.723475ms)","trace[923276054] 'compare' (duration: 43.154543ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:54:42.663742Z","caller":"traceutil/trace.go:171","msg":"trace[907726261] transaction","detail":"{read_only:false; response_revision:24868; number_of_response:1; }","duration":"422.126455ms","start":"2026-08-12T11:54:42.24158Z","end":"2026-08-12T11:54:42.663707Z","steps":["trace[907726261] 'process raft request' (duration: 421.32918ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:54:42.663833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:54:42.236631Z","time spent":"427.071191ms","remote":"127.0.0.1:47504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T11:54:42.663911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:54:42.24157Z","time spent":"422.254217ms","remote":"127.0.0.1:47504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T11:54:42.663905Z","caller":"traceutil/trace.go:171","msg":"trace[316105983] linearizableReadLoop","detail":"{readStateIndex:25696; appliedIndex:25693; }","duration":"415.033037ms","start":"2026-08-12T11:54:42.248847Z","end":"2026-08-12T11:54:42.66388Z","steps":["trace[316105983] 'read index received' (duration: 370.565949ms)","trace[316105983] 'applied index is now lower than readState.Index' (duration: 44.465098ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:54:42.663917Z","caller":"traceutil/trace.go:171","msg":"trace[409674184] transaction","detail":"{read_only:false; response_revision:24870; number_of_response:1; }","duration":"411.986178ms","start":"2026-08-12T11:54:42.251901Z","end":"2026-08-12T11:54:42.663887Z","steps":["trace[409674184] 'process raft request' (duration: 411.111892ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:54:42.664073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.106025ms","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":"warn","ts":"2026-08-12T11:54:42.664093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.361731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/clusterclasses/\" range_end:\"/registry/cluster.x-k8s.io/clusterclasses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-12T11:54:42.664088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:54:42.251885Z","time spent":"412.114631ms","remote":"127.0.0.1:47504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":605,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T11:54:42.664879Z","caller":"traceutil/trace.go:171","msg":"trace[1801092048] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:24870; }","duration":"181.201397ms","start":"2026-08-12T11:54:42.482908Z","end":"2026-08-12T11:54:42.664109Z","steps":["trace[1801092048] 'agreement among raft nodes before linearized reading' (duration: 181.130705ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:54:42.665299Z","caller":"traceutil/trace.go:171","msg":"trace[1425051965] range","detail":"{range_begin:/registry/cluster.x-k8s.io/clusterclasses/; range_end:/registry/cluster.x-k8s.io/clusterclasses0; response_count:0; response_revision:24870; }","duration":"235.452403ms","start":"2026-08-12T11:54:42.428662Z","end":"2026-08-12T11:54:42.664114Z","steps":["trace[1425051965] 'agreement among raft nodes before linearized reading' (duration: 235.356551ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:54:42.666641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.828251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" ","response":"range_response_count:90 size:117592"} {"level":"info","ts":"2026-08-12T11:54:42.666687Z","caller":"traceutil/trace.go:171","msg":"trace[1705116824] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:90; response_revision:24870; }","duration":"417.898483ms","start":"2026-08-12T11:54:42.248769Z","end":"2026-08-12T11:54:42.666668Z","steps":["trace[1705116824] 'agreement among raft nodes before linearized reading' (duration: 415.201781ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:54:42.666713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:54:42.248748Z","time spent":"417.957154ms","remote":"127.0.0.1:47452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":90,"response size":117614,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" "} {"level":"info","ts":"2026-08-12T11:54:42.822643Z","caller":"traceutil/trace.go:171","msg":"trace[902002659] linearizableReadLoop","detail":"{readStateIndex:25699; appliedIndex:25698; }","duration":"144.263614ms","start":"2026-08-12T11:54:42.678356Z","end":"2026-08-12T11:54:42.822619Z","steps":["trace[902002659] 'read index received' (duration: 144.05822ms)","trace[902002659] 'applied index is now lower than readState.Index' (duration: 204.444µs)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:54:42.822751Z","caller":"traceutil/trace.go:171","msg":"trace[531637526] transaction","detail":"{read_only:false; response_revision:24871; number_of_response:1; }","duration":"145.962016ms","start":"2026-08-12T11:54:42.676779Z","end":"2026-08-12T11:54:42.822741Z","steps":["trace[531637526] 'process raft request' (duration: 145.625319ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:54:42.822932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.55474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:129026"} {"level":"info","ts":"2026-08-12T11:54:42.82301Z","caller":"traceutil/trace.go:171","msg":"trace[679130617] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:24871; }","duration":"144.664722ms","start":"2026-08-12T11:54:42.678326Z","end":"2026-08-12T11:54:42.82299Z","steps":["trace[679130617] 'agreement among raft nodes before linearized reading' (duration: 144.423947ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:54:42.833799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.1259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-08-12T11:54:42.833859Z","caller":"traceutil/trace.go:171","msg":"trace[469904478] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24872; }","duration":"104.225732ms","start":"2026-08-12T11:54:42.729617Z","end":"2026-08-12T11:54:42.833843Z","steps":["trace[469904478] 'agreement among raft nodes before linearized reading' (duration: 104.085679ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:54:42.834109Z","caller":"traceutil/trace.go:171","msg":"trace[581640864] transaction","detail":"{read_only:false; response_revision:24872; number_of_response:1; }","duration":"141.98621ms","start":"2026-08-12T11:54:42.692114Z","end":"2026-08-12T11:54:42.8341Z","steps":["trace[581640864] 'process raft request' (duration: 141.48199ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:54:42.834369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.676782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-08-12T11:54:42.834402Z","caller":"traceutil/trace.go:171","msg":"trace[800370405] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24872; }","duration":"101.748042ms","start":"2026-08-12T11:54:42.732646Z","end":"2026-08-12T11:54:42.834394Z","steps":["trace[800370405] 'agreement among raft nodes before linearized reading' (duration: 101.666921ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:54:42.834528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.04466ms","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-08-12T11:54:42.834553Z","caller":"traceutil/trace.go:171","msg":"trace[1122981139] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24872; }","duration":"102.07961ms","start":"2026-08-12T11:54:42.732468Z","end":"2026-08-12T11:54:42.834548Z","steps":["trace[1122981139] 'agreement among raft nodes before linearized reading' (duration: 102.015459ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:54:42.834678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.465328ms","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-08-12T11:54:42.834706Z","caller":"traceutil/trace.go:171","msg":"trace[2002063348] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24872; }","duration":"102.513328ms","start":"2026-08-12T11:54:42.732185Z","end":"2026-08-12T11:54:42.834698Z","steps":["trace[2002063348] 'agreement among raft nodes before linearized reading' (duration: 102.449507ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:54:43.135587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.552715ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-12T11:54:43.13576Z","caller":"traceutil/trace.go:171","msg":"trace[1292458331] transaction","detail":"{read_only:false; response_revision:24873; number_of_response:1; }","duration":"295.42204ms","start":"2026-08-12T11:54:42.840311Z","end":"2026-08-12T11:54:43.135733Z","steps":["trace[1292458331] 'process raft request' (duration: 131.61976ms)","trace[1292458331] 'compare' (duration: 163.376882ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:54:43.212895Z","caller":"traceutil/trace.go:171","msg":"trace[1225203126] transaction","detail":"{read_only:false; response_revision:24875; number_of_response:1; }","duration":"372.307804ms","start":"2026-08-12T11:54:42.840555Z","end":"2026-08-12T11:54:43.212862Z","steps":["trace[1225203126] 'process raft request' (duration: 372.223532ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:54:43.213102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:54:42.840524Z","time spent":"372.464297ms","remote":"127.0.0.1:47504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T11:54:43.213168Z","caller":"traceutil/trace.go:171","msg":"trace[1799011060] transaction","detail":"{read_only:false; response_revision:24876; number_of_response:1; }","duration":"372.535708ms","start":"2026-08-12T11:54:42.840604Z","end":"2026-08-12T11:54:43.213139Z","steps":["trace[1799011060] 'process raft request' (duration: 372.214982ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:54:43.213294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:54:42.840578Z","time spent":"372.65002ms","remote":"127.0.0.1:47504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T11:54:43.213316Z","caller":"traceutil/trace.go:171","msg":"trace[1025239280] transaction","detail":"{read_only:false; response_revision:24874; number_of_response:1; }","duration":"372.773043ms","start":"2026-08-12T11:54:42.840513Z","end":"2026-08-12T11:54:43.213286Z","steps":["trace[1025239280] 'process raft request' (duration: 372.10352ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:54:43.213477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:54:42.840505Z","time spent":"372.893705ms","remote":"127.0.0.1:47504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T11:54:43.21352Z","caller":"traceutil/trace.go:171","msg":"trace[222404465] transaction","detail":"{read_only:false; response_revision:24877; number_of_response:1; }","duration":"273.269302ms","start":"2026-08-12T11:54:42.940226Z","end":"2026-08-12T11:54:43.213495Z","steps":["trace[222404465] 'process raft request' (duration: 272.820223ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:54:43.273709Z","caller":"traceutil/trace.go:171","msg":"trace[149623508] linearizableReadLoop","detail":"{readStateIndex:25706; appliedIndex:25704; }","duration":"105.826452ms","start":"2026-08-12T11:54:43.167834Z","end":"2026-08-12T11:54:43.273661Z","steps":["trace[149623508] 'read index received' (duration: 45.565129ms)","trace[149623508] 'applied index is now lower than readState.Index' (duration: 60.259723ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:54:43.273755Z","caller":"traceutil/trace.go:171","msg":"trace[372609770] transaction","detail":"{read_only:false; response_revision:24878; number_of_response:1; }","duration":"146.716912ms","start":"2026-08-12T11:54:43.126993Z","end":"2026-08-12T11:54:43.27371Z","steps":["trace[372609770] 'process raft request' (duration: 144.440818ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:54:43.273866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.011355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtime.cluster.x-k8s.io/extensionconfigs/\" range_end:\"/registry/runtime.cluster.x-k8s.io/extensionconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-12T11:54:43.273939Z","caller":"traceutil/trace.go:171","msg":"trace[1021582838] range","detail":"{range_begin:/registry/runtime.cluster.x-k8s.io/extensionconfigs/; range_end:/registry/runtime.cluster.x-k8s.io/extensionconfigs0; response_count:0; response_revision:24878; }","duration":"106.132968ms","start":"2026-08-12T11:54:43.167786Z","end":"2026-08-12T11:54:43.273919Z","steps":["trace[1021582838] 'agreement among raft nodes before linearized reading' (duration: 105.991445ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:54:43.581201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.612918ms","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-08-12T11:54:43.581268Z","caller":"traceutil/trace.go:171","msg":"trace[196044450] range","detail":"{range_begin:/registry/rabbitmq.com/schemareplications/; range_end:/registry/rabbitmq.com/schemareplications0; response_count:0; response_revision:24878; }","duration":"175.72378ms","start":"2026-08-12T11:54:43.405524Z","end":"2026-08-12T11:54:43.581248Z","steps":["trace[196044450] 'count revisions from in-memory index tree' (duration: 175.467945ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:54:43.924372Z","caller":"traceutil/trace.go:171","msg":"trace[167266091] transaction","detail":"{read_only:false; response_revision:24880; number_of_response:1; }","duration":"156.873417ms","start":"2026-08-12T11:54:43.767471Z","end":"2026-08-12T11:54:43.924345Z","steps":["trace[167266091] 'process raft request' (duration: 131.231532ms)","trace[167266091] 'compare' (duration: 25.343969ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:54:43.924369Z","caller":"traceutil/trace.go:171","msg":"trace[369946058] linearizableReadLoop","detail":"{readStateIndex:25709; appliedIndex:25708; }","duration":"155.96854ms","start":"2026-08-12T11:54:43.768369Z","end":"2026-08-12T11:54:43.924337Z","steps":["trace[369946058] 'read index received' (duration: 130.164722ms)","trace[369946058] 'applied index is now lower than readState.Index' (duration: 25.802078ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T11:54:43.924571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.184764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-12T11:54:43.924643Z","caller":"traceutil/trace.go:171","msg":"trace[2051731210] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24880; }","duration":"156.317486ms","start":"2026-08-12T11:54:43.768309Z","end":"2026-08-12T11:54:43.924626Z","steps":["trace[2051731210] 'agreement among raft nodes before linearized reading' (duration: 156.120773ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:54:44.21155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.153748ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-12T11:54:44.211697Z","caller":"traceutil/trace.go:171","msg":"trace[644356508] transaction","detail":"{read_only:false; response_revision:24881; number_of_response:1; }","duration":"201.246233ms","start":"2026-08-12T11:54:44.010422Z","end":"2026-08-12T11:54:44.211668Z","steps":["trace[644356508] 'process raft request' (duration: 66.87023ms)","trace[644356508] 'compare' (duration: 133.962354ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:54:44.927425Z","caller":"traceutil/trace.go:171","msg":"trace[1539430959] transaction","detail":"{read_only:false; response_revision:24883; number_of_response:1; }","duration":"249.875481ms","start":"2026-08-12T11:54:44.677526Z","end":"2026-08-12T11:54:44.927401Z","steps":["trace[1539430959] 'process raft request' (duration: 233.024636ms)","trace[1539430959] 'compare' (duration: 16.501319ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:54:44.927551Z","caller":"traceutil/trace.go:171","msg":"trace[899705856] transaction","detail":"{read_only:false; response_revision:24885; number_of_response:1; }","duration":"248.950614ms","start":"2026-08-12T11:54:44.67859Z","end":"2026-08-12T11:54:44.927541Z","steps":["trace[899705856] 'process raft request' (duration: 248.653518ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:54:44.9276Z","caller":"traceutil/trace.go:171","msg":"trace[1474371877] linearizableReadLoop","detail":"{readStateIndex:25714; appliedIndex:25710; }","duration":"240.052442ms","start":"2026-08-12T11:54:44.687539Z","end":"2026-08-12T11:54:44.927591Z","steps":["trace[1474371877] 'read index received' (duration: 71.076722ms)","trace[1474371877] 'applied index is now lower than readState.Index' (duration: 168.97479ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:54:44.927668Z","caller":"traceutil/trace.go:171","msg":"trace[2015526555] transaction","detail":"{read_only:false; response_revision:24884; number_of_response:1; }","duration":"249.210639ms","start":"2026-08-12T11:54:44.678451Z","end":"2026-08-12T11:54:44.927661Z","steps":["trace[2015526555] 'process raft request' (duration: 248.760471ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:54:44.927746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.194924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-12T11:54:44.927769Z","caller":"traceutil/trace.go:171","msg":"trace[738875482] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:24886; }","duration":"240.258326ms","start":"2026-08-12T11:54:44.687503Z","end":"2026-08-12T11:54:44.927762Z","steps":["trace[738875482] 'agreement among raft nodes before linearized reading' (duration: 240.200835ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:54:44.927935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.61358ms","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-08-12T11:54:44.927953Z","caller":"traceutil/trace.go:171","msg":"trace[1992281675] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24886; }","duration":"101.691042ms","start":"2026-08-12T11:54:44.826256Z","end":"2026-08-12T11:54:44.927947Z","steps":["trace[1992281675] 'agreement among raft nodes before linearized reading' (duration: 101.610541ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:54:45.400766Z","caller":"traceutil/trace.go:171","msg":"trace[758534267] linearizableReadLoop","detail":"{readStateIndex:25717; appliedIndex:25716; }","duration":"183.178285ms","start":"2026-08-12T11:54:45.217552Z","end":"2026-08-12T11:54:45.400731Z","steps":["trace[758534267] 'read index received' (duration: 182.92891ms)","trace[758534267] 'applied index is now lower than readState.Index' (duration: 247.714µs)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:54:45.400923Z","caller":"traceutil/trace.go:171","msg":"trace[317970070] transaction","detail":"{read_only:false; response_revision:24888; number_of_response:1; }","duration":"255.52221ms","start":"2026-08-12T11:54:45.14533Z","end":"2026-08-12T11:54:45.400852Z","steps":["trace[317970070] 'process raft request' (duration: 255.131962ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:54:45.401092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.510031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-08-12T11:54:45.401146Z","caller":"traceutil/trace.go:171","msg":"trace[620901047] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24888; }","duration":"183.638513ms","start":"2026-08-12T11:54:45.217492Z","end":"2026-08-12T11:54:45.401131Z","steps":["trace[620901047] 'agreement among raft nodes before linearized reading' (duration: 183.413179ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:54:45.811676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.828916ms","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-08-12T11:54:45.811825Z","caller":"traceutil/trace.go:171","msg":"trace[1309547556] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:24888; }","duration":"129.020109ms","start":"2026-08-12T11:54:45.682779Z","end":"2026-08-12T11:54:45.811799Z","steps":["trace[1309547556] 'range keys from in-memory index tree' (duration: 128.804865ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:54:45.812394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.773ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-12T11:54:45.812615Z","caller":"traceutil/trace.go:171","msg":"trace[1262251429] transaction","detail":"{read_only:false; response_revision:24889; number_of_response:1; }","duration":"593.518382ms","start":"2026-08-12T11:54:45.21906Z","end":"2026-08-12T11:54:45.812578Z","steps":["trace[1262251429] 'process raft request' (duration: 315.313794ms)","trace[1262251429] 'compare' (duration: 277.466244ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:54:45.812736Z","caller":"traceutil/trace.go:171","msg":"trace[674122227] linearizableReadLoop","detail":"{readStateIndex:25718; appliedIndex:25717; }","duration":"411.813786ms","start":"2026-08-12T11:54:45.400866Z","end":"2026-08-12T11:54:45.81268Z","steps":["trace[674122227] 'read index received' (duration: 133.520276ms)","trace[674122227] 'applied index is now lower than readState.Index' (duration: 278.28945ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T11:54:45.812793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:54:45.219026Z","time spent":"593.671395ms","remote":"127.0.0.1:47504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T11:54:45.812944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"594.770805ms","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-08-12T11:54:45.813025Z","caller":"traceutil/trace.go:171","msg":"trace[952265961] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24889; }","duration":"594.881678ms","start":"2026-08-12T11:54:45.218122Z","end":"2026-08-12T11:54:45.813004Z","steps":["trace[952265961] 'agreement among raft nodes before linearized reading' (duration: 594.644873ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:54:45.812947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"594.47178ms","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-08-12T11:54:45.813076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:54:45.218113Z","time spent":"594.949159ms","remote":"127.0.0.1:47504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-12T11:54:45.813124Z","caller":"traceutil/trace.go:171","msg":"trace[809667750] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24889; }","duration":"594.676874ms","start":"2026-08-12T11:54:45.218423Z","end":"2026-08-12T11:54:45.813099Z","steps":["trace[809667750] 'agreement among raft nodes before linearized reading' (duration: 594.261646ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:54:45.813091Z","caller":"traceutil/trace.go:171","msg":"trace[1213935779] transaction","detail":"{read_only:false; response_revision:24890; number_of_response:1; }","duration":"407.537232ms","start":"2026-08-12T11:54:45.40548Z","end":"2026-08-12T11:54:45.813018Z","steps":["trace[1213935779] 'process raft request' (duration: 407.062763ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:54:45.813174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:54:45.218409Z","time spent":"594.751205ms","remote":"127.0.0.1:47504","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-08-12T11:54:45.813274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:54:45.405449Z","time spent":"407.747816ms","remote":"127.0.0.1:47504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T11:54:46.062752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.440853ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-12T11:54:46.062964Z","caller":"traceutil/trace.go:171","msg":"trace[1278541887] transaction","detail":"{read_only:false; response_revision:24893; number_of_response:1; }","duration":"244.514898ms","start":"2026-08-12T11:54:45.818429Z","end":"2026-08-12T11:54:46.062944Z","steps":["trace[1278541887] 'process raft request' (duration: 244.452027ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:54:46.063057Z","caller":"traceutil/trace.go:171","msg":"trace[21361135] transaction","detail":"{read_only:false; response_revision:24892; number_of_response:1; }","duration":"244.65615ms","start":"2026-08-12T11:54:45.818363Z","end":"2026-08-12T11:54:46.063019Z","steps":["trace[21361135] 'process raft request' (duration: 94.862991ms)","trace[21361135] 'compare' (duration: 149.29542ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:54:46.429617Z","caller":"traceutil/trace.go:171","msg":"trace[1759545434] linearizableReadLoop","detail":"{readStateIndex:25723; appliedIndex:25722; }","duration":"254.628763ms","start":"2026-08-12T11:54:46.174962Z","end":"2026-08-12T11:54:46.429591Z","steps":["trace[1759545434] 'read index received' (duration: 237.152886ms)","trace[1759545434] 'applied index is now lower than readState.Index' (duration: 17.474697ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:54:46.429684Z","caller":"traceutil/trace.go:171","msg":"trace[1228807331] transaction","detail":"{read_only:false; response_revision:24894; number_of_response:1; }","duration":"339.571592ms","start":"2026-08-12T11:54:46.090086Z","end":"2026-08-12T11:54:46.429658Z","steps":["trace[1228807331] 'process raft request' (duration: 321.945161ms)","trace[1228807331] 'compare' (duration: 17.138201ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T11:54:46.429806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:54:46.090062Z","time spent":"339.676523ms","remote":"127.0.0.1:47504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":627,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T11:54:46.429822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.838247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterbackups/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterbackups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-12T11:54:46.429911Z","caller":"traceutil/trace.go:171","msg":"trace[1158775186] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterbackups/; range_end:/registry/pxc.percona.com/perconaxtradbclusterbackups0; response_count:0; response_revision:24894; }","duration":"254.97453ms","start":"2026-08-12T11:54:46.174915Z","end":"2026-08-12T11:54:46.429889Z","steps":["trace[1158775186] 'agreement among raft nodes before linearized reading' (duration: 254.759916ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:54:46.484184Z","caller":"traceutil/trace.go:171","msg":"trace[1314561874] transaction","detail":"{read_only:false; response_revision:24895; number_of_response:1; }","duration":"266.979222ms","start":"2026-08-12T11:54:46.217177Z","end":"2026-08-12T11:54:46.484156Z","steps":["trace[1314561874] 'process raft request' (duration: 266.818918ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:54:47.067712Z","caller":"traceutil/trace.go:171","msg":"trace[1211294034] transaction","detail":"{read_only:false; response_revision:24897; number_of_response:1; }","duration":"136.060806ms","start":"2026-08-12T11:54:46.931624Z","end":"2026-08-12T11:54:47.067684Z","steps":["trace[1211294034] 'process raft request' (duration: 135.793321ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:54:47.384952Z","caller":"traceutil/trace.go:171","msg":"trace[1523461729] transaction","detail":"{read_only:false; response_revision:24898; number_of_response:1; }","duration":"452.52995ms","start":"2026-08-12T11:54:46.932388Z","end":"2026-08-12T11:54:47.384918Z","steps":["trace[1523461729] 'process raft request' (duration: 441.593199ms)","trace[1523461729] 'compare' (duration: 10.515703ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T11:54:47.38514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.09384ms","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-08-12T11:54:47.385177Z","caller":"traceutil/trace.go:171","msg":"trace[249953396] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24900; }","duration":"413.191191ms","start":"2026-08-12T11:54:46.97198Z","end":"2026-08-12T11:54:47.385171Z","steps":["trace[249953396] 'agreement among raft nodes before linearized reading' (duration: 413.039178ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:54:47.38514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:54:46.93236Z","time spent":"452.707554ms","remote":"127.0.0.1:47504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T11:54:47.385223Z","caller":"traceutil/trace.go:171","msg":"trace[1240054048] transaction","detail":"{read_only:false; response_revision:24900; number_of_response:1; }","duration":"452.46711ms","start":"2026-08-12T11:54:46.93275Z","end":"2026-08-12T11:54:47.385217Z","steps":["trace[1240054048] 'process raft request' (duration: 452.154464ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:54:47.385287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:54:46.932717Z","time spent":"452.52417ms","remote":"127.0.0.1:47504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T11:54:47.384983Z","caller":"traceutil/trace.go:171","msg":"trace[71837431] linearizableReadLoop","detail":"{readStateIndex:25729; appliedIndex:25725; }","duration":"412.937897ms","start":"2026-08-12T11:54:46.972026Z","end":"2026-08-12T11:54:47.384964Z","steps":["trace[71837431] 'read index received' (duration: 95.38613ms)","trace[71837431] 'applied index is now lower than readState.Index' (duration: 317.550897ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T11:54:47.3852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:54:46.971958Z","time spent":"413.236082ms","remote":"127.0.0.1:47504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-08-12T11:54:47.385588Z","caller":"traceutil/trace.go:171","msg":"trace[1828309832] transaction","detail":"{read_only:false; response_revision:24899; number_of_response:1; }","duration":"452.442769ms","start":"2026-08-12T11:54:46.932742Z","end":"2026-08-12T11:54:47.385185Z","steps":["trace[1828309832] 'process raft request' (duration: 452.072732ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:54:47.385638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.17054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-12T11:54:47.385716Z","caller":"traceutil/trace.go:171","msg":"trace[478752377] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:24900; }","duration":"147.308933ms","start":"2026-08-12T11:54:47.238385Z","end":"2026-08-12T11:54:47.385694Z","steps":["trace[478752377] 'agreement among raft nodes before linearized reading' (duration: 147.164959ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:54:47.385615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.627978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-12T11:54:47.385854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:54:46.932715Z","time spent":"452.98065ms","remote":"127.0.0.1:47504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T11:54:47.385868Z","caller":"traceutil/trace.go:171","msg":"trace[1147611168] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:24900; }","duration":"360.938614ms","start":"2026-08-12T11:54:47.024912Z","end":"2026-08-12T11:54:47.385851Z","steps":["trace[1147611168] 'agreement among raft nodes before linearized reading' (duration: 360.621337ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:54:47.385962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:54:47.024887Z","time spent":"361.058787ms","remote":"127.0.0.1:48852","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true "} {"level":"info","ts":"2026-08-12T11:54:47.642717Z","caller":"traceutil/trace.go:171","msg":"trace[1270700754] linearizableReadLoop","detail":"{readStateIndex:25730; appliedIndex:25729; }","duration":"237.536753ms","start":"2026-08-12T11:54:47.405152Z","end":"2026-08-12T11:54:47.642689Z","steps":["trace[1270700754] 'read index received' (duration: 186.370156ms)","trace[1270700754] 'applied index is now lower than readState.Index' (duration: 51.165417ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:54:47.64286Z","caller":"traceutil/trace.go:171","msg":"trace[1163220292] transaction","detail":"{read_only:false; response_revision:24901; number_of_response:1; }","duration":"252.897709ms","start":"2026-08-12T11:54:47.389932Z","end":"2026-08-12T11:54:47.642829Z","steps":["trace[1163220292] 'process raft request' (duration: 195.660475ms)","trace[1163220292] 'compare' (duration: 56.811166ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T11:54:47.642987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.819979ms","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-08-12T11:54:47.643033Z","caller":"traceutil/trace.go:171","msg":"trace[1001157902] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24901; }","duration":"237.920981ms","start":"2026-08-12T11:54:47.405096Z","end":"2026-08-12T11:54:47.643017Z","steps":["trace[1001157902] 'agreement among raft nodes before linearized reading' (duration: 237.697747ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:54:47.90934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.599971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-12T11:54:47.909446Z","caller":"traceutil/trace.go:171","msg":"trace[1459838546] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24902; }","duration":"142.765383ms","start":"2026-08-12T11:54:47.766662Z","end":"2026-08-12T11:54:47.909428Z","steps":["trace[1459838546] 'range keys from in-memory index tree' (duration: 142.504089ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:54:47.911057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.82546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-08-12T11:54:47.911122Z","caller":"traceutil/trace.go:171","msg":"trace[1014446514] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:24902; }","duration":"108.952982ms","start":"2026-08-12T11:54:47.802156Z","end":"2026-08-12T11:54:47.911109Z","steps":["trace[1014446514] 'count revisions from in-memory index tree' (duration: 108.711337ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:54:48.440132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.838963ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-12T11:54:48.440262Z","caller":"traceutil/trace.go:171","msg":"trace[575346908] transaction","detail":"{read_only:false; response_revision:24904; number_of_response:1; }","duration":"619.551244ms","start":"2026-08-12T11:54:47.820681Z","end":"2026-08-12T11:54:48.440232Z","steps":["trace[575346908] 'process raft request' (duration: 416.527237ms)","trace[575346908] 'compare' (duration: 202.63552ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:54:48.440299Z","caller":"traceutil/trace.go:171","msg":"trace[805723152] linearizableReadLoop","detail":"{readStateIndex:25733; appliedIndex:25732; }","duration":"529.203981ms","start":"2026-08-12T11:54:47.911081Z","end":"2026-08-12T11:54:48.440285Z","steps":["trace[805723152] 'read index received' (duration: 326.263266ms)","trace[805723152] 'applied index is now lower than readState.Index' (duration: 202.939595ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T11:54:48.440354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:54:47.820668Z","time spent":"619.641235ms","remote":"127.0.0.1:47504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T11:54:48.440595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"529.483866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-12T11:54:48.440678Z","caller":"traceutil/trace.go:171","msg":"trace[496592551] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24904; }","duration":"529.620178ms","start":"2026-08-12T11:54:47.911037Z","end":"2026-08-12T11:54:48.440657Z","steps":["trace[496592551] 'agreement among raft nodes before linearized reading' (duration: 529.305092ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:54:48.440726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:54:47.911019Z","time spent":"529.69751ms","remote":"127.0.0.1:47248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-12T11:54:48.44073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.334282ms","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-08-12T11:54:48.44072Z","caller":"traceutil/trace.go:171","msg":"trace[856056973] transaction","detail":"{read_only:false; response_revision:24905; number_of_response:1; }","duration":"524.830976ms","start":"2026-08-12T11:54:47.915855Z","end":"2026-08-12T11:54:48.440686Z","steps":["trace[856056973] 'process raft request' (duration: 524.392737ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:54:48.440809Z","caller":"traceutil/trace.go:171","msg":"trace[1594471891] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24905; }","duration":"374.459564ms","start":"2026-08-12T11:54:48.066325Z","end":"2026-08-12T11:54:48.440785Z","steps":["trace[1594471891] 'agreement among raft nodes before linearized reading' (duration: 374.20214ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:54:48.440891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:54:48.066301Z","time spent":"374.579677ms","remote":"127.0.0.1:47504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-12T11:54:48.440943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:54:47.91584Z","time spent":"524.969669ms","remote":"127.0.0.1:47504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T11:54:48.44115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.805972ms","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-08-12T11:54:48.441246Z","caller":"traceutil/trace.go:171","msg":"trace[986746878] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24905; }","duration":"373.978905ms","start":"2026-08-12T11:54:48.067245Z","end":"2026-08-12T11:54:48.441224Z","steps":["trace[986746878] 'agreement among raft nodes before linearized reading' (duration: 373.301362ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:54:48.441303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:54:48.067219Z","time spent":"374.073817ms","remote":"127.0.0.1:47504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-12T11:54:48.589056Z","caller":"traceutil/trace.go:171","msg":"trace[317320958] transaction","detail":"{read_only:false; response_revision:24907; number_of_response:1; }","duration":"143.251634ms","start":"2026-08-12T11:54:48.445782Z","end":"2026-08-12T11:54:48.589034Z","steps":["trace[317320958] 'process raft request' (duration: 141.9888ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:54:48.589455Z","caller":"traceutil/trace.go:171","msg":"trace[574021936] transaction","detail":"{read_only:false; response_revision:24908; number_of_response:1; }","duration":"100.127773ms","start":"2026-08-12T11:54:48.489259Z","end":"2026-08-12T11:54:48.589387Z","steps":["trace[574021936] 'process raft request' (duration: 99.687524ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:54:56.283791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.73572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-12T11:54:56.283897Z","caller":"traceutil/trace.go:171","msg":"trace[1051377018] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0; response_count:0; response_revision:25021; }","duration":"120.890272ms","start":"2026-08-12T11:54:56.16299Z","end":"2026-08-12T11:54:56.283881Z","steps":["trace[1051377018] 'count revisions from in-memory index tree' (duration: 120.659308ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:54:56.284028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.527207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-471edfbb-d56e-45ab-999d-13319029a717\" ","response":"range_response_count:1 size:3643"} {"level":"info","ts":"2026-08-12T11:54:56.284117Z","caller":"traceutil/trace.go:171","msg":"trace[1514772242] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-471edfbb-d56e-45ab-999d-13319029a717; range_end:; response_count:1; response_revision:25021; }","duration":"146.68293ms","start":"2026-08-12T11:54:56.137414Z","end":"2026-08-12T11:54:56.284097Z","steps":["trace[1514772242] 'range keys from in-memory index tree' (duration: 146.311003ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:54:56.652374Z","caller":"traceutil/trace.go:171","msg":"trace[593569845] transaction","detail":"{read_only:false; response_revision:25022; number_of_response:1; }","duration":"361.020945ms","start":"2026-08-12T11:54:56.291322Z","end":"2026-08-12T11:54:56.652343Z","steps":["trace[593569845] 'process raft request' (duration: 360.848952ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:54:56.652554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:54:56.291301Z","time spent":"361.155267ms","remote":"127.0.0.1:47448","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3742,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T11:54:56.890638Z","caller":"traceutil/trace.go:171","msg":"trace[1372788343] transaction","detail":"{read_only:false; response_revision:25023; number_of_response:1; }","duration":"449.727278ms","start":"2026-08-12T11:54:56.440877Z","end":"2026-08-12T11:54:56.890604Z","steps":["trace[1372788343] 'process raft request' (duration: 449.418891ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:54:56.890796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:54:56.440849Z","time spent":"449.86428ms","remote":"127.0.0.1:47504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":628,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T11:54:56.891067Z","caller":"traceutil/trace.go:171","msg":"trace[1774513220] transaction","detail":"{read_only:false; response_revision:25024; number_of_response:1; }","duration":"423.876588ms","start":"2026-08-12T11:54:56.467175Z","end":"2026-08-12T11:54:56.891051Z","steps":["trace[1774513220] 'process raft request' (duration: 423.303337ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:54:56.891156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:54:56.467145Z","time spent":"423.95661ms","remote":"127.0.0.1:47504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T11:54:56.891157Z","caller":"traceutil/trace.go:171","msg":"trace[2114174434] transaction","detail":"{read_only:false; response_revision:25025; number_of_response:1; }","duration":"261.815302ms","start":"2026-08-12T11:54:56.62933Z","end":"2026-08-12T11:54:56.891145Z","steps":["trace[2114174434] 'process raft request' (duration: 261.407365ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:54:56.89137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.219098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-08-12T11:54:56.891415Z","caller":"traceutil/trace.go:171","msg":"trace[1823193664] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25025; }","duration":"420.294689ms","start":"2026-08-12T11:54:56.47111Z","end":"2026-08-12T11:54:56.891405Z","steps":["trace[1823193664] 'agreement among raft nodes before linearized reading' (duration: 420.148646ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:54:56.89145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:54:56.471099Z","time spent":"420.342541ms","remote":"127.0.0.1:47504","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":"info","ts":"2026-08-12T11:54:56.891543Z","caller":"traceutil/trace.go:171","msg":"trace[153776950] linearizableReadLoop","detail":"{readStateIndex:25855; appliedIndex:25852; }","duration":"420.382012ms","start":"2026-08-12T11:54:56.471148Z","end":"2026-08-12T11:54:56.89153Z","steps":["trace[153776950] 'read index received' (duration: 181.067693ms)","trace[153776950] 'applied index is now lower than readState.Index' (duration: 238.725887ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T11:54:57.199732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.783182ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-12T11:54:57.199936Z","caller":"traceutil/trace.go:171","msg":"trace[146363797] transaction","detail":"{read_only:false; response_revision:25026; number_of_response:1; }","duration":"345.244691ms","start":"2026-08-12T11:54:56.854643Z","end":"2026-08-12T11:54:57.199887Z","steps":["trace[146363797] 'process raft request' (duration: 197.204504ms)","trace[146363797] 'compare' (duration: 147.363614ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:54:57.200008Z","caller":"traceutil/trace.go:171","msg":"trace[385585265] transaction","detail":"{read_only:false; response_revision:25027; number_of_response:1; }","duration":"304.7342ms","start":"2026-08-12T11:54:56.895255Z","end":"2026-08-12T11:54:57.199989Z","steps":["trace[385585265] 'process raft request' (duration: 304.592987ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:54:57.200078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:54:56.895228Z","time spent":"304.816811ms","remote":"127.0.0.1:47504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T11:54:57.200003Z","caller":"traceutil/trace.go:171","msg":"trace[344081243] linearizableReadLoop","detail":"{readStateIndex:25857; appliedIndex:25856; }","duration":"308.38566ms","start":"2026-08-12T11:54:56.891591Z","end":"2026-08-12T11:54:57.199977Z","steps":["trace[344081243] 'read index received' (duration: 160.238181ms)","trace[344081243] 'applied index is now lower than readState.Index' (duration: 148.144879ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T11:54:57.200151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:54:56.854613Z","time spent":"345.427355ms","remote":"127.0.0.1:47504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T11:54:57.200235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.343571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-12T11:54:57.200275Z","caller":"traceutil/trace.go:171","msg":"trace[1734093945] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25027; }","duration":"432.427303ms","start":"2026-08-12T11:54:56.767836Z","end":"2026-08-12T11:54:57.200264Z","steps":["trace[1734093945] 'agreement among raft nodes before linearized reading' (duration: 432.320701ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:54:57.200316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:54:56.767812Z","time spent":"432.496264ms","remote":"127.0.0.1:47252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-12T11:54:57.200471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"572.270922ms","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-08-12T11:54:57.200506Z","caller":"traceutil/trace.go:171","msg":"trace[552799314] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25027; }","duration":"572.357992ms","start":"2026-08-12T11:54:56.628137Z","end":"2026-08-12T11:54:57.200495Z","steps":["trace[552799314] 'agreement among raft nodes before linearized reading' (duration: 572.198149ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:54:57.200528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:54:56.628113Z","time spent":"572.409774ms","remote":"127.0.0.1:47504","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-08-12T11:54:57.200523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"674.909032ms","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-08-12T11:54:57.20058Z","caller":"traceutil/trace.go:171","msg":"trace[972652727] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25027; }","duration":"675.022394ms","start":"2026-08-12T11:54:56.525542Z","end":"2026-08-12T11:54:57.200565Z","steps":["trace[972652727] 'agreement among raft nodes before linearized reading' (duration: 674.81484ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:54:57.200592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.786122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpools/\" range_end:\"/registry/ceph.rook.io/cephblockpools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-12T11:54:57.200623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:54:56.525519Z","time spent":"675.094136ms","remote":"127.0.0.1:47504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-12T11:54:57.20063Z","caller":"traceutil/trace.go:171","msg":"trace[830410677] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpools/; range_end:/registry/ceph.rook.io/cephblockpools0; response_count:0; response_revision:25027; }","duration":"444.864014ms","start":"2026-08-12T11:54:56.755756Z","end":"2026-08-12T11:54:57.20062Z","steps":["trace[830410677] 'agreement among raft nodes before linearized reading' (duration: 444.794552ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:54:57.200665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:54:56.755702Z","time spent":"444.954885ms","remote":"127.0.0.1:51466","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephblockpools/\" range_end:\"/registry/ceph.rook.io/cephblockpools0\" count_only:true "} {"level":"warn","ts":"2026-08-12T11:54:57.201992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.488554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-12T11:54:57.202057Z","caller":"traceutil/trace.go:171","msg":"trace[1256215729] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:25028; }","duration":"178.597837ms","start":"2026-08-12T11:54:57.023444Z","end":"2026-08-12T11:54:57.202041Z","steps":["trace[1256215729] 'agreement among raft nodes before linearized reading' (duration: 178.475994ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:54:57.202059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.232835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3309"} {"level":"info","ts":"2026-08-12T11:54:57.202144Z","caller":"traceutil/trace.go:171","msg":"trace[302471243] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:25028; }","duration":"282.351668ms","start":"2026-08-12T11:54:56.919768Z","end":"2026-08-12T11:54:57.20212Z","steps":["trace[302471243] 'agreement among raft nodes before linearized reading' (duration: 282.116294ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:54:57.202249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.307054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-471edfbb-d56e-45ab-999d-13319029a717\" ","response":"range_response_count:1 size:3760"} {"level":"info","ts":"2026-08-12T11:54:57.202288Z","caller":"traceutil/trace.go:171","msg":"trace[161840188] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-471edfbb-d56e-45ab-999d-13319029a717; range_end:; response_count:1; response_revision:25028; }","duration":"243.394926ms","start":"2026-08-12T11:54:56.958884Z","end":"2026-08-12T11:54:57.202279Z","steps":["trace[161840188] 'agreement among raft nodes before linearized reading' (duration: 243.303544ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:54:57.202496Z","caller":"traceutil/trace.go:171","msg":"trace[1701950286] transaction","detail":"{read_only:false; response_revision:25028; number_of_response:1; }","duration":"103.808313ms","start":"2026-08-12T11:54:57.098533Z","end":"2026-08-12T11:54:57.202341Z","steps":["trace[1701950286] 'process raft request' (duration: 103.168181ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:54:57.471733Z","caller":"traceutil/trace.go:171","msg":"trace[712790632] transaction","detail":"{read_only:false; response_revision:25029; number_of_response:1; }","duration":"266.42931ms","start":"2026-08-12T11:54:57.205273Z","end":"2026-08-12T11:54:57.471703Z","steps":["trace[712790632] 'process raft request' (duration: 230.277283ms)","trace[712790632] 'compare' (duration: 35.871142ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T11:54:57.736071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.990641ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-12T11:54:57.736311Z","caller":"traceutil/trace.go:171","msg":"trace[2012221143] transaction","detail":"{read_only:false; response_revision:25031; number_of_response:1; }","duration":"477.653346ms","start":"2026-08-12T11:54:57.258637Z","end":"2026-08-12T11:54:57.73629Z","steps":["trace[2012221143] 'process raft request' (duration: 477.547114ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:54:57.736313Z","caller":"traceutil/trace.go:171","msg":"trace[1163674615] transaction","detail":"{read_only:false; response_revision:25030; number_of_response:1; }","duration":"530.873822ms","start":"2026-08-12T11:54:57.205396Z","end":"2026-08-12T11:54:57.736269Z","steps":["trace[1163674615] 'process raft request' (duration: 391.596166ms)","trace[1163674615] 'compare' (duration: 138.798617ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T11:54:57.736392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:54:57.258622Z","time spent":"477.736637ms","remote":"127.0.0.1:47504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T11:54:57.736496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:54:57.205373Z","time spent":"531.024795ms","remote":"127.0.0.1:47504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T11:54:57.736559Z","caller":"traceutil/trace.go:171","msg":"trace[1540152641] transaction","detail":"{read_only:false; response_revision:25033; number_of_response:1; }","duration":"317.143018ms","start":"2026-08-12T11:54:57.419396Z","end":"2026-08-12T11:54:57.736539Z","steps":["trace[1540152641] 'process raft request' (duration: 317.036606ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:54:57.736614Z","caller":"traceutil/trace.go:171","msg":"trace[883742161] linearizableReadLoop","detail":"{readStateIndex:25861; appliedIndex:25859; }","duration":"525.466938ms","start":"2026-08-12T11:54:57.211124Z","end":"2026-08-12T11:54:57.736591Z","steps":["trace[883742161] 'read index received' (duration: 224.440051ms)","trace[883742161] 'applied index is now lower than readState.Index' (duration: 301.025617ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T11:54:57.736668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:54:57.419374Z","time spent":"317.235191ms","remote":"127.0.0.1:47504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T11:54:57.736686Z","caller":"traceutil/trace.go:171","msg":"trace[281642855] transaction","detail":"{read_only:false; response_revision:25034; number_of_response:1; }","duration":"316.978327ms","start":"2026-08-12T11:54:57.419669Z","end":"2026-08-12T11:54:57.736647Z","steps":["trace[281642855] 'process raft request' (duration: 316.839343ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:54:57.736824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:54:57.419658Z","time spent":"317.094129ms","remote":"127.0.0.1:47504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T11:54:57.73684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"525.708774ms","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-08-12T11:54:57.73692Z","caller":"traceutil/trace.go:171","msg":"trace[1306559094] range","detail":"{range_begin:/registry/secrets/openstack/rabbitmq-manila-default-user; range_end:; response_count:1; response_revision:25034; }","duration":"525.802665ms","start":"2026-08-12T11:54:57.211101Z","end":"2026-08-12T11:54:57.736903Z","steps":["trace[1306559094] 'agreement among raft nodes before linearized reading' (duration: 525.592831ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:54:57.73697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:54:57.211094Z","time spent":"525.864246ms","remote":"127.0.0.1:47376","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":1,"response size":1334,"request content":"key:\"/registry/secrets/openstack/rabbitmq-manila-default-user\" "} {"level":"info","ts":"2026-08-12T11:54:57.736593Z","caller":"traceutil/trace.go:171","msg":"trace[1776670923] transaction","detail":"{read_only:false; response_revision:25032; number_of_response:1; }","duration":"317.468335ms","start":"2026-08-12T11:54:57.419056Z","end":"2026-08-12T11:54:57.736525Z","steps":["trace[1776670923] 'process raft request' (duration: 317.18433ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:54:57.737391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:54:57.41903Z","time spent":"318.281941ms","remote":"127.0.0.1:47504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T11:54:59.574564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.429219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-12T11:54:59.574666Z","caller":"traceutil/trace.go:171","msg":"trace[1457332153] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:25046; }","duration":"197.611463ms","start":"2026-08-12T11:54:59.377029Z","end":"2026-08-12T11:54:59.57464Z","steps":["trace[1457332153] 'count revisions from in-memory index tree' (duration: 197.303358ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:55:03.393972Z","caller":"traceutil/trace.go:171","msg":"trace[1760405973] transaction","detail":"{read_only:false; response_revision:25103; number_of_response:1; }","duration":"174.758581ms","start":"2026-08-12T11:55:03.219179Z","end":"2026-08-12T11:55:03.393937Z","steps":["trace[1760405973] 'process raft request' (duration: 174.546627ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:55:03.674819Z","caller":"traceutil/trace.go:171","msg":"trace[1702065326] linearizableReadLoop","detail":"{readStateIndex:25937; appliedIndex:25935; }","duration":"451.728045ms","start":"2026-08-12T11:55:03.223066Z","end":"2026-08-12T11:55:03.674794Z","steps":["trace[1702065326] 'read index received' (duration: 170.779345ms)","trace[1702065326] 'applied index is now lower than readState.Index' (duration: 280.94752ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:55:03.67517Z","caller":"traceutil/trace.go:171","msg":"trace[1391008800] transaction","detail":"{read_only:false; response_revision:25104; number_of_response:1; }","duration":"452.646593ms","start":"2026-08-12T11:55:03.222505Z","end":"2026-08-12T11:55:03.675152Z","steps":["trace[1391008800] 'process raft request' (duration: 452.130333ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:55:03.675299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:55:03.222473Z","time spent":"452.740055ms","remote":"127.0.0.1:47504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T11:55:03.67552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.460471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-08-12T11:55:03.675562Z","caller":"traceutil/trace.go:171","msg":"trace[1431859671] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25104; }","duration":"452.529621ms","start":"2026-08-12T11:55:03.223021Z","end":"2026-08-12T11:55:03.67555Z","steps":["trace[1431859671] 'agreement among raft nodes before linearized reading' (duration: 452.402838ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:55:03.675586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:55:03.22301Z","time spent":"452.570182ms","remote":"127.0.0.1:47504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-12T11:55:03.675881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.512242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-12T11:55:03.675952Z","caller":"traceutil/trace.go:171","msg":"trace[1930310595] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:25104; }","duration":"352.622084ms","start":"2026-08-12T11:55:03.323312Z","end":"2026-08-12T11:55:03.675934Z","steps":["trace[1930310595] 'agreement among raft nodes before linearized reading' (duration: 352.488811ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:55:03.675991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:55:03.323296Z","time spent":"352.687586ms","remote":"127.0.0.1:39030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":8,"response size":30,"request content":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-08-12T11:55:03.9443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.849074ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-12T11:55:03.944405Z","caller":"traceutil/trace.go:171","msg":"trace[143857816] transaction","detail":"{read_only:false; response_revision:25108; number_of_response:1; }","duration":"189.406104ms","start":"2026-08-12T11:55:03.754982Z","end":"2026-08-12T11:55:03.944388Z","steps":["trace[143857816] 'process raft request' (duration: 86.388697ms)","trace[143857816] 'compare' (duration: 102.60471ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:55:03.984307Z","caller":"traceutil/trace.go:171","msg":"trace[1937281933] linearizableReadLoop","detail":"{readStateIndex:25943; appliedIndex:25941; }","duration":"225.074453ms","start":"2026-08-12T11:55:03.759208Z","end":"2026-08-12T11:55:03.984282Z","steps":["trace[1937281933] 'read index received' (duration: 82.156585ms)","trace[1937281933] 'applied index is now lower than readState.Index' (duration: 142.916818ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:55:03.984352Z","caller":"traceutil/trace.go:171","msg":"trace[1592695756] transaction","detail":"{read_only:false; response_revision:25109; number_of_response:1; }","duration":"226.4959ms","start":"2026-08-12T11:55:03.757839Z","end":"2026-08-12T11:55:03.984335Z","steps":["trace[1592695756] 'process raft request' (duration: 226.233935ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:55:03.984418Z","caller":"traceutil/trace.go:171","msg":"trace[1342202911] transaction","detail":"{read_only:false; response_revision:25110; number_of_response:1; }","duration":"224.656204ms","start":"2026-08-12T11:55:03.759751Z","end":"2026-08-12T11:55:03.984407Z","steps":["trace[1342202911] 'process raft request' (duration: 224.486871ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:55:03.984576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.352089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinedeployments/\" range_end:\"/registry/cluster.x-k8s.io/machinedeployments0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-12T11:55:03.984619Z","caller":"traceutil/trace.go:171","msg":"trace[559500239] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinedeployments/; range_end:/registry/cluster.x-k8s.io/machinedeployments0; response_count:0; response_revision:25110; }","duration":"225.43089ms","start":"2026-08-12T11:55:03.759173Z","end":"2026-08-12T11:55:03.984604Z","steps":["trace[559500239] 'agreement among raft nodes before linearized reading' (duration: 225.300117ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:55:04.012199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.19408ms","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-08-12T11:55:04.012257Z","caller":"traceutil/trace.go:171","msg":"trace[1010761598] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25111; }","duration":"160.284183ms","start":"2026-08-12T11:55:03.851959Z","end":"2026-08-12T11:55:04.012243Z","steps":["trace[1010761598] 'agreement among raft nodes before linearized reading' (duration: 160.049639ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:55:04.012397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.865795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-12T11:55:04.012432Z","caller":"traceutil/trace.go:171","msg":"trace[1603260561] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25111; }","duration":"244.912626ms","start":"2026-08-12T11:55:03.767508Z","end":"2026-08-12T11:55:04.01242Z","steps":["trace[1603260561] 'agreement among raft nodes before linearized reading' (duration: 244.849904ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:55:04.012411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.37101ms","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-08-12T11:55:04.012581Z","caller":"traceutil/trace.go:171","msg":"trace[884025796] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25111; }","duration":"251.619185ms","start":"2026-08-12T11:55:03.760904Z","end":"2026-08-12T11:55:04.012524Z","steps":["trace[884025796] 'agreement among raft nodes before linearized reading' (duration: 251.096495ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:55:05.873623Z","caller":"traceutil/trace.go:171","msg":"trace[370235733] linearizableReadLoop","detail":"{readStateIndex:25972; appliedIndex:25971; }","duration":"104.934114ms","start":"2026-08-12T11:55:05.768652Z","end":"2026-08-12T11:55:05.873586Z","steps":["trace[370235733] 'read index received' (duration: 71.129312ms)","trace[370235733] 'applied index is now lower than readState.Index' (duration: 33.802802ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:55:05.873664Z","caller":"traceutil/trace.go:171","msg":"trace[2073784923] transaction","detail":"{read_only:false; response_revision:25138; number_of_response:1; }","duration":"172.968068ms","start":"2026-08-12T11:55:05.700647Z","end":"2026-08-12T11:55:05.873615Z","steps":["trace[2073784923] 'process raft request' (duration: 139.096774ms)","trace[2073784923] 'compare' (duration: 33.526567ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T11:55:05.873833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.157459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-12T11:55:05.873882Z","caller":"traceutil/trace.go:171","msg":"trace[1419446167] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25138; }","duration":"105.26638ms","start":"2026-08-12T11:55:05.768602Z","end":"2026-08-12T11:55:05.873868Z","steps":["trace[1419446167] 'agreement among raft nodes before linearized reading' (duration: 105.154878ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:55:09.067428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.534049ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-08-12T11:55:09.082564Z","caller":"traceutil/trace.go:171","msg":"trace[1241002286] transaction","detail":"{read_only:false; response_revision:25163; number_of_response:1; }","duration":"149.444094ms","start":"2026-08-12T11:55:08.933087Z","end":"2026-08-12T11:55:09.082531Z","steps":["trace[1241002286] 'process raft request' (duration: 149.23136ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:55:09.304242Z","caller":"traceutil/trace.go:171","msg":"trace[116449473] linearizableReadLoop","detail":"{readStateIndex:25999; appliedIndex:25997; }","duration":"288.046948ms","start":"2026-08-12T11:55:09.016154Z","end":"2026-08-12T11:55:09.304201Z","steps":["trace[116449473] 'read index received' (duration: 66.213318ms)","trace[116449473] 'applied index is now lower than readState.Index' (duration: 221.83177ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:55:09.304247Z","caller":"traceutil/trace.go:171","msg":"trace[518095798] transaction","detail":"{read_only:false; response_revision:25164; number_of_response:1; }","duration":"367.178394ms","start":"2026-08-12T11:55:08.937038Z","end":"2026-08-12T11:55:09.304216Z","steps":["trace[518095798] 'process raft request' (duration: 285.55635ms)","trace[518095798] 'compare' (duration: 81.341689ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T11:55:09.304365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.1929ms","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-08-12T11:55:09.304404Z","caller":"traceutil/trace.go:171","msg":"trace[1959192971] 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:25164; }","duration":"288.277912ms","start":"2026-08-12T11:55:09.016113Z","end":"2026-08-12T11:55:09.304391Z","steps":["trace[1959192971] 'agreement among raft nodes before linearized reading' (duration: 288.191711ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:55:09.304657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:55:08.937025Z","time spent":"367.293926ms","remote":"127.0.0.1:47504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T11:55:27.861667Z","caller":"traceutil/trace.go:171","msg":"trace[929113230] transaction","detail":"{read_only:false; response_revision:25324; number_of_response:1; }","duration":"103.617349ms","start":"2026-08-12T11:55:27.75802Z","end":"2026-08-12T11:55:27.861637Z","steps":["trace[929113230] 'process raft request' (duration: 103.437016ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:55:27.887868Z","caller":"traceutil/trace.go:171","msg":"trace[748439969] linearizableReadLoop","detail":"{readStateIndex:26164; appliedIndex:26162; }","duration":"120.969554ms","start":"2026-08-12T11:55:27.766874Z","end":"2026-08-12T11:55:27.887843Z","steps":["trace[748439969] 'read index received' (duration: 94.609155ms)","trace[748439969] 'applied index is now lower than readState.Index' (duration: 26.359249ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:55:27.887953Z","caller":"traceutil/trace.go:171","msg":"trace[1918115759] transaction","detail":"{read_only:false; response_revision:25325; number_of_response:1; }","duration":"124.126415ms","start":"2026-08-12T11:55:27.763799Z","end":"2026-08-12T11:55:27.887925Z","steps":["trace[1918115759] 'process raft request' (duration: 123.8876ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:55:27.888058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.149879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-12T11:55:27.888122Z","caller":"traceutil/trace.go:171","msg":"trace[962176413] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25325; }","duration":"121.28547ms","start":"2026-08-12T11:55:27.76682Z","end":"2026-08-12T11:55:27.888106Z","steps":["trace[962176413] 'agreement among raft nodes before linearized reading' (duration: 121.150188ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:55:28.043883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.632231ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-12T11:55:28.04401Z","caller":"traceutil/trace.go:171","msg":"trace[1714182524] transaction","detail":"{read_only:false; response_revision:25326; number_of_response:1; }","duration":"152.321528ms","start":"2026-08-12T11:55:27.891656Z","end":"2026-08-12T11:55:28.043978Z","steps":["trace[1714182524] 'process raft request' (duration: 36.524125ms)","trace[1714182524] 'compare' (duration: 115.524778ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:55:28.28586Z","caller":"traceutil/trace.go:171","msg":"trace[1569746666] transaction","detail":"{read_only:false; response_revision:25330; number_of_response:1; }","duration":"216.016648ms","start":"2026-08-12T11:55:28.069795Z","end":"2026-08-12T11:55:28.285812Z","steps":["trace[1569746666] 'process raft request' (duration: 215.923186ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:55:28.285899Z","caller":"traceutil/trace.go:171","msg":"trace[451829803] linearizableReadLoop","detail":"{readStateIndex:26170; appliedIndex:26167; }","duration":"135.320101ms","start":"2026-08-12T11:55:28.150557Z","end":"2026-08-12T11:55:28.285877Z","steps":["trace[451829803] 'read index received' (duration: 109.817059ms)","trace[451829803] 'applied index is now lower than readState.Index' (duration: 25.501342ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:55:28.286087Z","caller":"traceutil/trace.go:171","msg":"trace[725204324] transaction","detail":"{read_only:false; response_revision:25329; number_of_response:1; }","duration":"216.436257ms","start":"2026-08-12T11:55:28.069632Z","end":"2026-08-12T11:55:28.286068Z","steps":["trace[725204324] 'process raft request' (duration: 190.829473ms)","trace[725204324] 'compare' (duration: 25.083514ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:55:28.286129Z","caller":"traceutil/trace.go:171","msg":"trace[1309546867] transaction","detail":"{read_only:false; response_revision:25331; number_of_response:1; }","duration":"201.282044ms","start":"2026-08-12T11:55:28.084835Z","end":"2026-08-12T11:55:28.286117Z","steps":["trace[1309546867] 'process raft request' (duration: 200.940157ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:55:28.286303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.73659ms","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-08-12T11:55:28.286351Z","caller":"traceutil/trace.go:171","msg":"trace[137842455] range","detail":"{range_begin:/registry/resourcequotas/openstack/; range_end:/registry/resourcequotas/openstack0; response_count:0; response_revision:25331; }","duration":"135.849762ms","start":"2026-08-12T11:55:28.15049Z","end":"2026-08-12T11:55:28.28634Z","steps":["trace[137842455] 'agreement among raft nodes before linearized reading' (duration: 135.72585ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:55:28.487806Z","caller":"traceutil/trace.go:171","msg":"trace[329775138] transaction","detail":"{read_only:false; response_revision:25333; number_of_response:1; }","duration":"196.958451ms","start":"2026-08-12T11:55:28.29079Z","end":"2026-08-12T11:55:28.487749Z","steps":["trace[329775138] 'process raft request' (duration: 97.209656ms)","trace[329775138] 'compare' (duration: 99.366768ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:55:28.48789Z","caller":"traceutil/trace.go:171","msg":"trace[1917656855] linearizableReadLoop","detail":"{readStateIndex:26173; appliedIndex:26170; }","duration":"194.714967ms","start":"2026-08-12T11:55:28.293137Z","end":"2026-08-12T11:55:28.487852Z","steps":["trace[1917656855] 'read index received' (duration: 77.806101ms)","trace[1917656855] 'applied index is now lower than readState.Index' (duration: 116.906506ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:55:28.487908Z","caller":"traceutil/trace.go:171","msg":"trace[1844374108] transaction","detail":"{read_only:false; response_revision:25334; number_of_response:1; }","duration":"197.072013ms","start":"2026-08-12T11:55:28.290803Z","end":"2026-08-12T11:55:28.487875Z","steps":["trace[1844374108] 'process raft request' (duration: 196.89119ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:55:28.488144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.987473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:1446"} {"level":"info","ts":"2026-08-12T11:55:28.488215Z","caller":"traceutil/trace.go:171","msg":"trace[564959709] range","detail":"{range_begin:/registry/services/specs/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:25334; }","duration":"195.095085ms","start":"2026-08-12T11:55:28.293103Z","end":"2026-08-12T11:55:28.488198Z","steps":["trace[564959709] 'agreement among raft nodes before linearized reading' (duration: 194.85358ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:55:29.448377Z","caller":"traceutil/trace.go:171","msg":"trace[1583211703] transaction","detail":"{read_only:false; response_revision:25335; number_of_response:1; }","duration":"225.992491ms","start":"2026-08-12T11:55:29.222358Z","end":"2026-08-12T11:55:29.44835Z","steps":["trace[1583211703] 'process raft request' (duration: 225.815528ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:55:30.684243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.487871ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-12T11:55:30.684441Z","caller":"traceutil/trace.go:171","msg":"trace[214292634] linearizableReadLoop","detail":"{readStateIndex:26187; appliedIndex:26185; }","duration":"117.522708ms","start":"2026-08-12T11:55:30.566885Z","end":"2026-08-12T11:55:30.684408Z","steps":["trace[214292634] 'read index received' (duration: 47.68502ms)","trace[214292634] 'applied index is now lower than readState.Index' (duration: 69.836248ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T11:55:30.684572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.69095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/routers/\" range_end:\"/registry/openstack.k-orc.cloud/routers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-12T11:55:30.684567Z","caller":"traceutil/trace.go:171","msg":"trace[1091032411] transaction","detail":"{read_only:false; response_revision:25346; number_of_response:1; }","duration":"186.433198ms","start":"2026-08-12T11:55:30.49809Z","end":"2026-08-12T11:55:30.684523Z","steps":["trace[1091032411] 'process raft request' (duration: 45.584771ms)","trace[1091032411] 'compare' (duration: 140.218315ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:55:30.68457Z","caller":"traceutil/trace.go:171","msg":"trace[1593430106] transaction","detail":"{read_only:false; response_revision:25347; number_of_response:1; }","duration":"186.257464ms","start":"2026-08-12T11:55:30.498269Z","end":"2026-08-12T11:55:30.684527Z","steps":["trace[1593430106] 'process raft request' (duration: 186.084151ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:55:30.684608Z","caller":"traceutil/trace.go:171","msg":"trace[1061571964] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/routers/; range_end:/registry/openstack.k-orc.cloud/routers0; response_count:0; response_revision:25347; }","duration":"117.764662ms","start":"2026-08-12T11:55:30.566827Z","end":"2026-08-12T11:55:30.684592Z","steps":["trace[1061571964] 'agreement among raft nodes before linearized reading' (duration: 117.64359ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:55:30.964795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.447553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-08-12T11:55:30.964891Z","caller":"traceutil/trace.go:171","msg":"trace[1300918422] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25347; }","duration":"163.601996ms","start":"2026-08-12T11:55:30.801266Z","end":"2026-08-12T11:55:30.964868Z","steps":["trace[1300918422] 'range keys from in-memory index tree' (duration: 163.219638ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:55:31.605235Z","caller":"traceutil/trace.go:171","msg":"trace[123959225] transaction","detail":"{read_only:false; response_revision:25348; number_of_response:1; }","duration":"150.526355ms","start":"2026-08-12T11:55:31.454683Z","end":"2026-08-12T11:55:31.605209Z","steps":["trace[123959225] 'process raft request' (duration: 150.32662ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:55:31.856565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.585839ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-12T11:55:31.85678Z","caller":"traceutil/trace.go:171","msg":"trace[322051824] transaction","detail":"{read_only:false; response_revision:25350; number_of_response:1; }","duration":"243.911626ms","start":"2026-08-12T11:55:31.612828Z","end":"2026-08-12T11:55:31.85674Z","steps":["trace[322051824] 'process raft request' (duration: 243.851195ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:55:31.856877Z","caller":"traceutil/trace.go:171","msg":"trace[1062148139] linearizableReadLoop","detail":"{readStateIndex:26189; appliedIndex:26187; }","duration":"309.459301ms","start":"2026-08-12T11:55:31.547296Z","end":"2026-08-12T11:55:31.856756Z","steps":["trace[1062148139] 'read index received' (duration: 57.743084ms)","trace[1062148139] 'applied index is now lower than readState.Index' (duration: 251.714037ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:55:31.857145Z","caller":"traceutil/trace.go:171","msg":"trace[683195130] transaction","detail":"{read_only:false; response_revision:25349; number_of_response:1; }","duration":"377.592195ms","start":"2026-08-12T11:55:31.479078Z","end":"2026-08-12T11:55:31.856671Z","steps":["trace[683195130] 'process raft request' (duration: 259.801152ms)","trace[683195130] 'compare' (duration: 117.443966ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T11:55:31.857333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:55:31.479052Z","time spent":"378.243408ms","remote":"127.0.0.1:47504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T11:55:31.857365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.294177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-12T11:55:31.857404Z","caller":"traceutil/trace.go:171","msg":"trace[374008048] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:25350; }","duration":"212.375948ms","start":"2026-08-12T11:55:31.645017Z","end":"2026-08-12T11:55:31.857393Z","steps":["trace[374008048] 'agreement among raft nodes before linearized reading' (duration: 212.300136ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:55:31.857159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.867278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-heat-server-0\" ","response":"range_response_count:1 size:1852"} {"level":"info","ts":"2026-08-12T11:55:31.857463Z","caller":"traceutil/trace.go:171","msg":"trace[1329675524] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-heat-server-0; range_end:; response_count:1; response_revision:25350; }","duration":"310.228156ms","start":"2026-08-12T11:55:31.54722Z","end":"2026-08-12T11:55:31.857449Z","steps":["trace[1329675524] 'agreement among raft nodes before linearized reading' (duration: 309.650844ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:55:31.857505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:55:31.5472Z","time spent":"310.295188ms","remote":"127.0.0.1:47436","response type":"/etcdserverpb.KV/Range","request count":0,"request size":79,"response count":1,"response size":1874,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-heat-server-0\" "} {"level":"warn","ts":"2026-08-12T11:55:31.8581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.347574ms","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-08-12T11:55:31.858191Z","caller":"traceutil/trace.go:171","msg":"trace[683997848] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:25350; }","duration":"118.968536ms","start":"2026-08-12T11:55:31.739199Z","end":"2026-08-12T11:55:31.858167Z","steps":["trace[683997848] 'agreement among raft nodes before linearized reading' (duration: 118.328914ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:55:32.048142Z","caller":"traceutil/trace.go:171","msg":"trace[60913597] transaction","detail":"{read_only:false; response_revision:25351; number_of_response:1; }","duration":"176.720689ms","start":"2026-08-12T11:55:31.871396Z","end":"2026-08-12T11:55:32.048117Z","steps":["trace[60913597] 'process raft request' (duration: 176.520936ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:55:32.098235Z","caller":"traceutil/trace.go:171","msg":"trace[415894110] transaction","detail":"{read_only:false; response_revision:25352; number_of_response:1; }","duration":"107.523535ms","start":"2026-08-12T11:55:31.990695Z","end":"2026-08-12T11:55:32.098218Z","steps":["trace[415894110] 'process raft request' (duration: 107.279489ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:55:42.132474Z","caller":"traceutil/trace.go:171","msg":"trace[899087711] transaction","detail":"{read_only:false; response_revision:25434; number_of_response:1; }","duration":"238.852408ms","start":"2026-08-12T11:55:41.893592Z","end":"2026-08-12T11:55:42.132444Z","steps":["trace[899087711] 'process raft request' (duration: 195.154896ms)","trace[899087711] 'compare' (duration: 43.413207ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T11:55:42.555558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.714944ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-12T11:55:42.555815Z","caller":"traceutil/trace.go:171","msg":"trace[100223744] linearizableReadLoop","detail":"{readStateIndex:26278; appliedIndex:26276; }","duration":"391.705208ms","start":"2026-08-12T11:55:42.164092Z","end":"2026-08-12T11:55:42.555797Z","steps":["trace[100223744] 'read index received' (duration: 151.688837ms)","trace[100223744] 'applied index is now lower than readState.Index' (duration: 240.015241ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:55:42.556027Z","caller":"traceutil/trace.go:171","msg":"trace[767192876] transaction","detail":"{read_only:false; response_revision:25435; number_of_response:1; }","duration":"472.999096ms","start":"2026-08-12T11:55:42.083015Z","end":"2026-08-12T11:55:42.556014Z","steps":["trace[767192876] 'process raft request' (duration: 232.753211ms)","trace[767192876] 'compare' (duration: 238.98056ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T11:55:42.556098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:55:42.082986Z","time spent":"473.078078ms","remote":"127.0.0.1:47504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T11:55:42.556268Z","caller":"traceutil/trace.go:171","msg":"trace[829472833] transaction","detail":"{read_only:false; response_revision:25436; number_of_response:1; }","duration":"409.274276ms","start":"2026-08-12T11:55:42.146984Z","end":"2026-08-12T11:55:42.556258Z","steps":["trace[829472833] 'process raft request' (duration: 408.707165ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:55:42.556325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:55:42.146965Z","time spent":"409.333107ms","remote":"127.0.0.1:47504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T11:55:42.556452Z","caller":"traceutil/trace.go:171","msg":"trace[885852832] transaction","detail":"{read_only:false; response_revision:25437; number_of_response:1; }","duration":"361.848081ms","start":"2026-08-12T11:55:42.194593Z","end":"2026-08-12T11:55:42.556441Z","steps":["trace[885852832] 'process raft request' (duration: 361.169458ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:55:42.55651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:55:42.194565Z","time spent":"361.914113ms","remote":"127.0.0.1:47504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":627,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T11:55:42.556764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.671316ms","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-08-12T11:55:42.5568Z","caller":"traceutil/trace.go:171","msg":"trace[2122691962] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25437; }","duration":"392.737977ms","start":"2026-08-12T11:55:42.164053Z","end":"2026-08-12T11:55:42.556791Z","steps":["trace[2122691962] 'agreement among raft nodes before linearized reading' (duration: 392.614284ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:55:42.556835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:55:42.164041Z","time spent":"392.787939ms","remote":"127.0.0.1:47504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-12T11:55:42.621127Z","caller":"traceutil/trace.go:171","msg":"trace[553000529] transaction","detail":"{read_only:false; response_revision:25440; number_of_response:1; }","duration":"281.531712ms","start":"2026-08-12T11:55:42.339574Z","end":"2026-08-12T11:55:42.621105Z","steps":["trace[553000529] 'process raft request' (duration: 281.488741ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:55:42.6215Z","caller":"traceutil/trace.go:171","msg":"trace[2052867320] transaction","detail":"{read_only:false; response_revision:25438; number_of_response:1; }","duration":"285.388336ms","start":"2026-08-12T11:55:42.336104Z","end":"2026-08-12T11:55:42.621493Z","steps":["trace[2052867320] 'process raft request' (duration: 284.788125ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:55:42.621604Z","caller":"traceutil/trace.go:171","msg":"trace[849456200] transaction","detail":"{read_only:false; response_revision:25439; number_of_response:1; }","duration":"283.744724ms","start":"2026-08-12T11:55:42.337852Z","end":"2026-08-12T11:55:42.621597Z","steps":["trace[849456200] 'process raft request' (duration: 283.183164ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:55:42.622067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.258553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-08-12T11:55:42.622104Z","caller":"traceutil/trace.go:171","msg":"trace[1918322413] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25440; }","duration":"131.354185ms","start":"2026-08-12T11:55:42.490742Z","end":"2026-08-12T11:55:42.622096Z","steps":["trace[1918322413] 'agreement among raft nodes before linearized reading' (duration: 131.224302ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:55:42.622206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.84783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-08-12T11:55:42.622231Z","caller":"traceutil/trace.go:171","msg":"trace[708551841] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25440; }","duration":"420.89025ms","start":"2026-08-12T11:55:42.201333Z","end":"2026-08-12T11:55:42.622223Z","steps":["trace[708551841] 'agreement among raft nodes before linearized reading' (duration: 420.820439ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:55:42.62225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:55:42.201323Z","time spent":"420.92081ms","remote":"127.0.0.1:47504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-12T11:55:42.836785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.166129ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-12T11:55:42.837075Z","caller":"traceutil/trace.go:171","msg":"trace[1799673771] linearizableReadLoop","detail":"{readStateIndex:26283; appliedIndex:26282; }","duration":"215.10804ms","start":"2026-08-12T11:55:42.621948Z","end":"2026-08-12T11:55:42.837056Z","steps":["trace[1799673771] 'read index received' (duration: 95.505082ms)","trace[1799673771] 'applied index is now lower than readState.Index' (duration: 119.601368ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T11:55:42.837205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.910607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephcosidrivers/\" range_end:\"/registry/ceph.rook.io/cephcosidrivers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-12T11:55:42.837239Z","caller":"traceutil/trace.go:171","msg":"trace[18300408] range","detail":"{range_begin:/registry/ceph.rook.io/cephcosidrivers/; range_end:/registry/ceph.rook.io/cephcosidrivers0; response_count:0; response_revision:25441; }","duration":"241.949097ms","start":"2026-08-12T11:55:42.59528Z","end":"2026-08-12T11:55:42.837229Z","steps":["trace[18300408] 'agreement among raft nodes before linearized reading' (duration: 241.847696ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:55:42.837274Z","caller":"traceutil/trace.go:171","msg":"trace[1945391881] transaction","detail":"{read_only:false; response_revision:25442; number_of_response:1; }","duration":"209.728326ms","start":"2026-08-12T11:55:42.627527Z","end":"2026-08-12T11:55:42.837256Z","steps":["trace[1945391881] 'process raft request' (duration: 209.365049ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:55:42.837668Z","caller":"traceutil/trace.go:171","msg":"trace[113077177] transaction","detail":"{read_only:false; response_revision:25443; number_of_response:1; }","duration":"210.100563ms","start":"2026-08-12T11:55:42.627533Z","end":"2026-08-12T11:55:42.837634Z","steps":["trace[113077177] 'process raft request' (duration: 209.685285ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:55:42.837733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.295717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium-operator\" ","response":"range_response_count:1 size:539"} {"level":"info","ts":"2026-08-12T11:55:42.837758Z","caller":"traceutil/trace.go:171","msg":"trace[695545747] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium-operator; range_end:; response_count:1; response_revision:25443; }","duration":"210.373898ms","start":"2026-08-12T11:55:42.627377Z","end":"2026-08-12T11:55:42.837751Z","steps":["trace[695545747] 'agreement among raft nodes before linearized reading' (duration: 210.246436ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:55:42.83777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.864933ms","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-08-12T11:55:42.837819Z","caller":"traceutil/trace.go:171","msg":"trace[1810689636] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25443; }","duration":"104.920703ms","start":"2026-08-12T11:55:42.732885Z","end":"2026-08-12T11:55:42.837805Z","steps":["trace[1810689636] 'agreement among raft nodes before linearized reading' (duration: 104.780741ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:55:42.837882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.881402ms","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-08-12T11:55:42.837902Z","caller":"traceutil/trace.go:171","msg":"trace[541359989] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25443; }","duration":"106.931563ms","start":"2026-08-12T11:55:42.730966Z","end":"2026-08-12T11:55:42.837897Z","steps":["trace[541359989] 'agreement among raft nodes before linearized reading' (duration: 106.881232ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:55:42.837087Z","caller":"traceutil/trace.go:171","msg":"trace[620698815] transaction","detail":"{read_only:false; response_revision:25441; number_of_response:1; }","duration":"275.685989ms","start":"2026-08-12T11:55:42.561359Z","end":"2026-08-12T11:55:42.837044Z","steps":["trace[620698815] 'process raft request' (duration: 156.173433ms)","trace[620698815] 'compare' (duration: 118.890054ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T11:55:43.297154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.368753ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-12T11:55:43.297455Z","caller":"traceutil/trace.go:171","msg":"trace[1639682003] transaction","detail":"{read_only:false; response_revision:25444; number_of_response:1; }","duration":"455.499688ms","start":"2026-08-12T11:55:42.841807Z","end":"2026-08-12T11:55:43.297307Z","steps":["trace[1639682003] 'process raft request' (duration: 179.866781ms)","trace[1639682003] 'compare' (duration: 275.025606ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T11:55:43.297809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:55:42.841773Z","time spent":"455.990607ms","remote":"127.0.0.1:47504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T11:55:43.60553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.654394ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-12T11:55:43.605759Z","caller":"traceutil/trace.go:171","msg":"trace[786907968] transaction","detail":"{read_only:false; response_revision:25445; number_of_response:1; }","duration":"763.708285ms","start":"2026-08-12T11:55:42.842027Z","end":"2026-08-12T11:55:43.605735Z","steps":["trace[786907968] 'process raft request' (duration: 582.772395ms)","trace[786907968] 'compare' (duration: 180.384429ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:55:43.605787Z","caller":"traceutil/trace.go:171","msg":"trace[603882278] transaction","detail":"{read_only:false; response_revision:25446; number_of_response:1; }","duration":"707.949449ms","start":"2026-08-12T11:55:42.89783Z","end":"2026-08-12T11:55:43.60578Z","steps":["trace[603882278] 'process raft request' (duration: 707.803996ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:55:43.605827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:55:42.897795Z","time spent":"708.01008ms","remote":"127.0.0.1:47504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T11:55:43.605843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:55:42.842008Z","time spent":"763.794556ms","remote":"127.0.0.1:47504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-12T11:55:43.605885Z","caller":"traceutil/trace.go:171","msg":"trace[1103501898] linearizableReadLoop","detail":"{readStateIndex:26289; appliedIndex:26286; }","duration":"228.025939ms","start":"2026-08-12T11:55:43.377838Z","end":"2026-08-12T11:55:43.605864Z","steps":["trace[1103501898] 'read index received' (duration: 46.981737ms)","trace[1103501898] 'applied index is now lower than readState.Index' (duration: 181.043152ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T11:55:43.605939Z","caller":"traceutil/trace.go:171","msg":"trace[110839193] transaction","detail":"{read_only:false; response_revision:25447; number_of_response:1; }","duration":"381.885998ms","start":"2026-08-12T11:55:43.224015Z","end":"2026-08-12T11:55:43.605901Z","steps":["trace[110839193] 'process raft request' (duration: 381.721395ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:55:43.606036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.193842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-12T11:55:43.60607Z","caller":"traceutil/trace.go:171","msg":"trace[278250705] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:25447; }","duration":"228.297154ms","start":"2026-08-12T11:55:43.377764Z","end":"2026-08-12T11:55:43.606061Z","steps":["trace[278250705] 'agreement among raft nodes before linearized reading' (duration: 228.216362ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:55:43.60613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-12T11:55:43.223987Z","time spent":"382.051011ms","remote":"127.0.0.1:47504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-12T11:55:43.606045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.496924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephbuckettopics/\" range_end:\"/registry/ceph.rook.io/cephbuckettopics0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-12T11:55:43.606232Z","caller":"traceutil/trace.go:171","msg":"trace[1233413687] range","detail":"{range_begin:/registry/ceph.rook.io/cephbuckettopics/; range_end:/registry/ceph.rook.io/cephbuckettopics0; response_count:0; response_revision:25447; }","duration":"106.681629ms","start":"2026-08-12T11:55:43.499537Z","end":"2026-08-12T11:55:43.606219Z","steps":["trace[1233413687] 'agreement among raft nodes before linearized reading' (duration: 106.470404ms)"],"step_count":1} {"level":"warn","ts":"2026-08-12T11:55:43.859019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.375833ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-08-12T11:55:43.859475Z","caller":"traceutil/trace.go:171","msg":"trace[973269793] transaction","detail":"{read_only:false; response_revision:25448; number_of_response:1; }","duration":"139.905139ms","start":"2026-08-12T11:55:43.719542Z","end":"2026-08-12T11:55:43.859447Z","steps":["trace[973269793] 'process raft request' (duration: 139.606754ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T11:56:11.521528Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006813.wal"} {"level":"info","ts":"2026-08-12T11:56:40.523502Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23073} {"level":"info","ts":"2026-08-12T11:56:40.666418Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23073,"took":"138.754487ms","hash":3246194237,"current-db-size-bytes":59949056,"current-db-size":"60 MB","current-db-size-in-use-bytes":36667392,"current-db-size-in-use":"37 MB"} {"level":"info","ts":"2026-08-12T11:56:40.666485Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3246194237,"revision":23073,"compact-revision":20120} {"level":"info","ts":"2026-08-12T12:01:40.528868Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26039} {"level":"info","ts":"2026-08-12T12:01:40.678562Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26039,"took":"145.091368ms","hash":1347597420,"current-db-size-bytes":59949056,"current-db-size":"60 MB","current-db-size-in-use-bytes":35602432,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-08-12T12:01:40.678641Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1347597420,"revision":26039,"compact-revision":23073} {"level":"info","ts":"2026-08-12T12:02:12.031018Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"2b21fdc9c735cc27","local-member-applied-index":30004,"local-member-snapshot-index":20003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-12T12:02:12.036104Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30004} {"level":"info","ts":"2026-08-12T12:02:12.036206Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25004} {"level":"info","ts":"2026-08-12T12:06:40.53482Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28836} {"level":"info","ts":"2026-08-12T12:06:40.677367Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28836,"took":"137.85522ms","hash":3768338012,"current-db-size-bytes":59949056,"current-db-size":"60 MB","current-db-size-in-use-bytes":31182848,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-12T12:06:40.677424Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3768338012,"revision":28836,"compact-revision":26039} {"level":"info","ts":"2026-08-12T12:11:40.541638Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":31091} {"level":"info","ts":"2026-08-12T12:11:40.671258Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":31091,"took":"125.975323ms","hash":4072443950,"current-db-size-bytes":59949056,"current-db-size":"60 MB","current-db-size-in-use-bytes":30650368,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-12T12:11:40.671314Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4072443950,"revision":31091,"compact-revision":28836} {"level":"info","ts":"2026-08-12T12:16:40.547644Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33357} {"level":"info","ts":"2026-08-12T12:16:40.698701Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33357,"took":"146.49685ms","hash":637230045,"current-db-size-bytes":60993536,"current-db-size":"61 MB","current-db-size-in-use-bytes":31653888,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-12T12:16:40.698777Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":637230045,"revision":33357,"compact-revision":31091} {"level":"info","ts":"2026-08-12T12:21:40.554778Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35649} {"level":"info","ts":"2026-08-12T12:21:40.697485Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35649,"took":"138.294351ms","hash":4206102141,"current-db-size-bytes":61468672,"current-db-size":"62 MB","current-db-size-in-use-bytes":32337920,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-12T12:21:40.697565Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4206102141,"revision":35649,"compact-revision":33357} {"level":"info","ts":"2026-08-12T12:23:38.977305Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"2b21fdc9c735cc27","local-member-applied-index":40005,"local-member-snapshot-index":30004,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-12T12:23:38.98156Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40005} {"level":"info","ts":"2026-08-12T12:23:38.981639Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35005} {"level":"info","ts":"2026-08-12T12:26:40.559818Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37879} {"level":"info","ts":"2026-08-12T12:26:40.671022Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37879,"took":"107.952322ms","hash":3495213791,"current-db-size-bytes":61468672,"current-db-size":"62 MB","current-db-size-in-use-bytes":31289344,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-08-12T12:26:40.671076Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3495213791,"revision":37879,"compact-revision":35649} {"level":"info","ts":"2026-08-12T12:26:50.607113Z","caller":"traceutil/trace.go:171","msg":"trace[1760560944] transaction","detail":"{read_only:false; response_revision:40239; number_of_response:1; }","duration":"100.893067ms","start":"2026-08-12T12:26:50.506177Z","end":"2026-08-12T12:26:50.60707Z","steps":["trace[1760560944] 'process raft request' (duration: 74.373416ms)","trace[1760560944] 'compare' (duration: 26.271507ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T12:26:57.43628Z","caller":"traceutil/trace.go:171","msg":"trace[456988840] transaction","detail":"{read_only:false; response_revision:40288; number_of_response:1; }","duration":"113.755895ms","start":"2026-08-12T12:26:57.322487Z","end":"2026-08-12T12:26:57.436243Z","steps":["trace[456988840] 'process raft request' (duration: 113.604372ms)"],"step_count":1} {"level":"info","ts":"2026-08-12T12:27:01.727064Z","caller":"traceutil/trace.go:171","msg":"trace[1407949467] linearizableReadLoop","detail":"{readStateIndex:41575; appliedIndex:41574; }","duration":"129.700783ms","start":"2026-08-12T12:27:01.59734Z","end":"2026-08-12T12:27:01.72704Z","steps":["trace[1407949467] 'read index received' (duration: 84.805907ms)","trace[1407949467] 'applied index is now lower than readState.Index' (duration: 44.893696ms)"],"step_count":2} {"level":"info","ts":"2026-08-12T12:27:01.727117Z","caller":"traceutil/trace.go:171","msg":"trace[1761702142] transaction","detail":"{read_only:false; response_revision:40319; number_of_response:1; }","duration":"143.54587ms","start":"2026-08-12T12:27:01.583531Z","end":"2026-08-12T12:27:01.727077Z","steps":["trace[1761702142] 'process raft request' (duration: 98.637844ms)","trace[1761702142] 'compare' (duration: 44.763703ms)"],"step_count":2} {"level":"warn","ts":"2026-08-12T12:27:01.728107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.752343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/glance-rabbitmq-admin\" ","response":"range_response_count:1 size:752"} {"level":"info","ts":"2026-08-12T12:27:01.728653Z","caller":"traceutil/trace.go:171","msg":"trace[1545220293] range","detail":"{range_begin:/registry/secrets/openstack/glance-rabbitmq-admin; range_end:; response_count:1; response_revision:40319; }","duration":"130.897696ms","start":"2026-08-12T12:27:01.59729Z","end":"2026-08-12T12:27:01.728188Z","steps":["trace[1545220293] 'agreement among raft nodes before linearized reading' (duration: 129.876937ms)"],"step_count":1}