{"level":"warn","ts":"2026-08-06T14:03:48.008642Z","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-06T14:03:48.008726Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.169: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.169:2380","--initial-cluster=instance=https://199.19.213.169:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.169:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.169: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-06T14:03:48.008788Z","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-06T14:03:48.008798Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.169:2380"]} {"level":"info","ts":"2026-08-06T14:03:48.008813Z","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-06T14:03:48.009416Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.169:2379"]} {"level":"info","ts":"2026-08-06T14:03:48.009495Z","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":8,"max-cpu-available":8,"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.169:2380"],"listen-peer-urls":["https://199.19.213.169:2380"],"advertise-client-urls":["https://199.19.213.169:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.169:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.169: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-06T14:03:48.014409Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.722378ms"} {"level":"info","ts":"2026-08-06T14:03:48.021159Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"a742f9f5aa7326e5","cluster-id":"a6b466adbadbbcd5"} {"level":"info","ts":"2026-08-06T14:03:48.021226Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a742f9f5aa7326e5 switched to configuration voters=()"} {"level":"info","ts":"2026-08-06T14:03:48.021248Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a742f9f5aa7326e5 became follower at term 0"} {"level":"info","ts":"2026-08-06T14:03:48.021266Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft a742f9f5aa7326e5 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-06T14:03:48.021276Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a742f9f5aa7326e5 became follower at term 1"} {"level":"info","ts":"2026-08-06T14:03:48.021314Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a742f9f5aa7326e5 switched to configuration voters=(12052470386318845669)"} {"level":"warn","ts":"2026-08-06T14:03:48.028157Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-06T14:03:48.029447Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-06T14:03:48.031091Z","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-06T14:03:48.03332Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"a742f9f5aa7326e5","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-06T14:03:48.033528Z","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-06T14:03:48.033623Z","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-06T14:03:48.033637Z","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-06T14:03:48.033643Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"a742f9f5aa7326e5","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-06T14:03:48.034831Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a742f9f5aa7326e5 switched to configuration voters=(12052470386318845669)"} {"level":"info","ts":"2026-08-06T14:03:48.035948Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"a6b466adbadbbcd5","local-member-id":"a742f9f5aa7326e5","added-peer-id":"a742f9f5aa7326e5","added-peer-peer-urls":["https://199.19.213.169:2380"]} {"level":"info","ts":"2026-08-06T14:03:48.037336Z","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-06T14:03:48.037553Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.169:2380"} {"level":"info","ts":"2026-08-06T14:03:48.0376Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.169:2380"} {"level":"info","ts":"2026-08-06T14:03:48.03795Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"a742f9f5aa7326e5","initial-advertise-peer-urls":["https://199.19.213.169:2380"],"listen-peer-urls":["https://199.19.213.169:2380"],"advertise-client-urls":["https://199.19.213.169:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.169:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-06T14:03:48.038056Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-06T14:03:48.52157Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a742f9f5aa7326e5 is starting a new election at term 1"} {"level":"info","ts":"2026-08-06T14:03:48.521633Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a742f9f5aa7326e5 became pre-candidate at term 1"} {"level":"info","ts":"2026-08-06T14:03:48.52173Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a742f9f5aa7326e5 received MsgPreVoteResp from a742f9f5aa7326e5 at term 1"} {"level":"info","ts":"2026-08-06T14:03:48.521763Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a742f9f5aa7326e5 became candidate at term 2"} {"level":"info","ts":"2026-08-06T14:03:48.521816Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a742f9f5aa7326e5 received MsgVoteResp from a742f9f5aa7326e5 at term 2"} {"level":"info","ts":"2026-08-06T14:03:48.521879Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a742f9f5aa7326e5 became leader at term 2"} {"level":"info","ts":"2026-08-06T14:03:48.521999Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: a742f9f5aa7326e5 elected leader a742f9f5aa7326e5 at term 2"} {"level":"info","ts":"2026-08-06T14:03:48.523152Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"a742f9f5aa7326e5","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.169:2379]}","request-path":"/0/members/a742f9f5aa7326e5/attributes","cluster-id":"a6b466adbadbbcd5","publish-timeout":"7s"} {"level":"info","ts":"2026-08-06T14:03:48.523531Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-06T14:03:48.523579Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-06T14:03:48.523683Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-06T14:03:48.523733Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-06T14:03:48.52373Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-06T14:03:48.52533Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-06T14:03:48.526272Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.169:2379"} {"level":"info","ts":"2026-08-06T14:03:48.526566Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"a6b466adbadbbcd5","local-member-id":"a742f9f5aa7326e5","cluster-version":"3.5"} {"level":"info","ts":"2026-08-06T14:03:48.526688Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-06T14:03:48.526766Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-06T14:03:52.076713Z","caller":"traceutil/trace.go:171","msg":"trace[1205158662] transaction","detail":"{read_only:false; response_revision:240; number_of_response:1; }","duration":"161.658981ms","start":"2026-08-06T14:03:51.915017Z","end":"2026-08-06T14:03:52.076675Z","steps":["trace[1205158662] 'process raft request' (duration: 161.407287ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:03:57.601595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.648498902s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"warn","ts":"2026-08-06T14:03:57.602124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.178550995s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-08-06T14:03:57.602203Z","caller":"traceutil/trace.go:171","msg":"trace[285361688] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:318; }","duration":"1.649143111s","start":"2026-08-06T14:03:55.953037Z","end":"2026-08-06T14:03:57.602181Z","steps":["trace[285361688] 'range keys from in-memory index tree' (duration: 1.648371919s)"],"step_count":1} {"level":"info","ts":"2026-08-06T14:03:57.602239Z","caller":"traceutil/trace.go:171","msg":"trace[1262890245] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:318; }","duration":"1.179156744s","start":"2026-08-06T14:03:56.423055Z","end":"2026-08-06T14:03:57.602212Z","steps":["trace[1262890245] 'range keys from in-memory index tree' (duration: 1.178386842s)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:03:57.602289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T14:03:56.423035Z","time spent":"1.179243906s","remote":"127.0.0.1:33896","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":"warn","ts":"2026-08-06T14:03:57.602282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"820.930827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-08-06T14:03:57.602421Z","caller":"traceutil/trace.go:171","msg":"trace[1054408905] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:318; }","duration":"822.014853ms","start":"2026-08-06T14:03:56.780381Z","end":"2026-08-06T14:03:57.602396Z","steps":["trace[1054408905] 'range keys from in-memory index tree' (duration: 820.792525ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:03:57.602498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T14:03:56.78036Z","time spent":"822.119905ms","remote":"127.0.0.1:33896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-06T14:03:57.602307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T14:03:55.953032Z","time spent":"1.649264523s","remote":"127.0.0.1:33718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":375,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-08-06T14:03:57.601514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.507260722s","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-06T14:03:57.602848Z","caller":"traceutil/trace.go:171","msg":"trace[1163347673] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:318; }","duration":"1.508697434s","start":"2026-08-06T14:03:56.094139Z","end":"2026-08-06T14:03:57.602836Z","steps":["trace[1163347673] 'range keys from in-memory index tree' (duration: 1.507095799s)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:03:57.602887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T14:03:56.094117Z","time spent":"1.508760526s","remote":"127.0.0.1:33896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-06T14:03:57.601596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.370834592s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-08-06T14:03:57.602983Z","caller":"traceutil/trace.go:171","msg":"trace[1398743306] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:318; }","duration":"1.372277815s","start":"2026-08-06T14:03:56.23069Z","end":"2026-08-06T14:03:57.602967Z","steps":["trace[1398743306] 'range keys from in-memory index tree' (duration: 1.37071951s)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:03:57.603031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T14:03:56.230666Z","time spent":"1.372353346s","remote":"127.0.0.1:33896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-08-06T14:03:57.9624Z","caller":"traceutil/trace.go:171","msg":"trace[23646233] transaction","detail":"{read_only:false; response_revision:319; number_of_response:1; }","duration":"348.304048ms","start":"2026-08-06T14:03:57.614071Z","end":"2026-08-06T14:03:57.962375Z","steps":["trace[23646233] 'process raft request' (duration: 348.061344ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:03:57.963047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T14:03:57.614051Z","time spent":"348.429961ms","remote":"127.0.0.1:33896","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-06T14:03:57.981543Z","caller":"traceutil/trace.go:171","msg":"trace[39120988] transaction","detail":"{read_only:false; response_revision:321; number_of_response:1; }","duration":"366.875945ms","start":"2026-08-06T14:03:57.614636Z","end":"2026-08-06T14:03:57.981512Z","steps":["trace[39120988] 'process raft request' (duration: 366.791014ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T14:03:57.981593Z","caller":"traceutil/trace.go:171","msg":"trace[1972218955] transaction","detail":"{read_only:false; response_revision:320; number_of_response:1; }","duration":"366.952186ms","start":"2026-08-06T14:03:57.614601Z","end":"2026-08-06T14:03:57.981553Z","steps":["trace[1972218955] 'process raft request' (duration: 366.665612ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:03:57.981711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T14:03:57.614593Z","time spent":"367.058387ms","remote":"127.0.0.1:33896","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-06T14:03:57.981771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T14:03:57.614466Z","time spent":"367.182789ms","remote":"127.0.0.1:33896","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-06T14:03:57.981845Z","caller":"traceutil/trace.go:171","msg":"trace[299095637] transaction","detail":"{read_only:false; response_revision:322; number_of_response:1; }","duration":"365.241371ms","start":"2026-08-06T14:03:57.616566Z","end":"2026-08-06T14:03:57.981807Z","steps":["trace[299095637] 'process raft request' (duration: 364.896166ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:03:57.982029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T14:03:57.616558Z","time spent":"365.370363ms","remote":"127.0.0.1:33896","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-06T14:03:57.982153Z","caller":"traceutil/trace.go:171","msg":"trace[1761760646] transaction","detail":"{read_only:false; response_revision:323; number_of_response:1; }","duration":"363.990921ms","start":"2026-08-06T14:03:57.618155Z","end":"2026-08-06T14:03:57.982146Z","steps":["trace[1761760646] 'process raft request' (duration: 363.546764ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:03:57.982251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T14:03:57.61815Z","time spent":"364.080172ms","remote":"127.0.0.1:33828","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":182,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-06T14:04:27.178891Z","caller":"traceutil/trace.go:171","msg":"trace[101133367] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"680.164453ms","start":"2026-08-06T14:04:26.498686Z","end":"2026-08-06T14:04:27.178851Z","steps":["trace[101133367] 'process raft request' (duration: 679.799407ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:04:27.179067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T14:04:26.498659Z","time spent":"680.321027ms","remote":"127.0.0.1:33676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-06T14:04:27.187738Z","caller":"traceutil/trace.go:171","msg":"trace[877653585] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"683.73058ms","start":"2026-08-06T14:04:26.503987Z","end":"2026-08-06T14:04:27.187718Z","steps":["trace[877653585] 'process raft request' (duration: 683.409004ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T14:04:27.187943Z","caller":"traceutil/trace.go:171","msg":"trace[1924282145] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"671.717702ms","start":"2026-08-06T14:04:26.516197Z","end":"2026-08-06T14:04:27.187914Z","steps":["trace[1924282145] 'process raft request' (duration: 671.465537ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T14:04:27.187964Z","caller":"traceutil/trace.go:171","msg":"trace[1307460004] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"671.683791ms","start":"2026-08-06T14:04:26.516258Z","end":"2026-08-06T14:04:27.187942Z","steps":["trace[1307460004] 'process raft request' (duration: 671.439477ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:04:27.188085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T14:04:26.516178Z","time spent":"671.840565ms","remote":"127.0.0.1:33676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-06T14:04:27.188115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T14:04:26.516255Z","time spent":"671.798294ms","remote":"127.0.0.1:33676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-06T14:04:27.188171Z","caller":"traceutil/trace.go:171","msg":"trace[683597406] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"664.913524ms","start":"2026-08-06T14:04:26.523238Z","end":"2026-08-06T14:04:27.188152Z","steps":["trace[683597406] 'process raft request' (duration: 664.690599ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T14:04:27.188212Z","caller":"traceutil/trace.go:171","msg":"trace[1116569686] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"666.445093ms","start":"2026-08-06T14:04:26.521757Z","end":"2026-08-06T14:04:27.188203Z","steps":["trace[1116569686] 'process raft request' (duration: 665.977823ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T14:04:27.188265Z","caller":"traceutil/trace.go:171","msg":"trace[2143332468] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"679.825096ms","start":"2026-08-06T14:04:26.50843Z","end":"2026-08-06T14:04:27.188255Z","steps":["trace[2143332468] 'process raft request' (duration: 679.056151ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:04:27.188274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T14:04:26.521749Z","time spent":"666.489494ms","remote":"127.0.0.1:33676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-06T14:04:27.188327Z","caller":"traceutil/trace.go:171","msg":"trace[298591316] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"675.74859ms","start":"2026-08-06T14:04:26.512548Z","end":"2026-08-06T14:04:27.188296Z","steps":["trace[298591316] 'process raft request' (duration: 675.027935ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:04:27.188275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T14:04:26.523203Z","time spent":"665.015336ms","remote":"127.0.0.1:33676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-06T14:04:27.188385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T14:04:26.512539Z","time spent":"675.830691ms","remote":"127.0.0.1:33676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-06T14:04:27.188169Z","caller":"traceutil/trace.go:171","msg":"trace[1495760800] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"666.223549ms","start":"2026-08-06T14:04:26.521914Z","end":"2026-08-06T14:04:27.188137Z","steps":["trace[1495760800] 'process raft request' (duration: 665.890702ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:04:27.188408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T14:04:26.508424Z","time spent":"679.926478ms","remote":"127.0.0.1:33676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-06T14:04:27.188442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T14:04:26.521906Z","time spent":"666.520154ms","remote":"127.0.0.1:33676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-06T14:04:27.188498Z","caller":"traceutil/trace.go:171","msg":"trace[1832245818] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"655.75781ms","start":"2026-08-06T14:04:26.532731Z","end":"2026-08-06T14:04:27.188489Z","steps":["trace[1832245818] 'process raft request' (duration: 655.250841ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:04:27.188543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T14:04:26.532721Z","time spent":"655.805851ms","remote":"127.0.0.1:33676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-06T14:04:27.188618Z","caller":"traceutil/trace.go:171","msg":"trace[1474674139] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"637.980395ms","start":"2026-08-06T14:04:26.550629Z","end":"2026-08-06T14:04:27.18861Z","steps":["trace[1474674139] 'process raft request' (duration: 637.396794ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:04:27.188675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T14:04:26.550603Z","time spent":"638.052665ms","remote":"127.0.0.1:33676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-06T14:04:27.191754Z","caller":"traceutil/trace.go:171","msg":"trace[1382298449] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"517.886062ms","start":"2026-08-06T14:04:26.673836Z","end":"2026-08-06T14:04:27.191722Z","steps":["trace[1382298449] 'process raft request' (duration: 517.260141ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:04:27.191842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T14:04:26.673805Z","time spent":"517.996924ms","remote":"127.0.0.1:33676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-06T14:04:27.192349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T14:04:26.50398Z","time spent":"683.825812ms","remote":"127.0.0.1:33676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-06T14:04:34.536008Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2802822090538465915,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-06T14:04:34.557692Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.324975649s","expected-duration":"1s"} {"level":"info","ts":"2026-08-06T14:04:34.564911Z","caller":"traceutil/trace.go:171","msg":"trace[1566740161] linearizableReadLoop","detail":"{readStateIndex:660; appliedIndex:659; }","duration":"529.794528ms","start":"2026-08-06T14:04:34.03506Z","end":"2026-08-06T14:04:34.564854Z","steps":["trace[1566740161] 'read index received' (duration: 522.80457ms)","trace[1566740161] 'applied index is now lower than readState.Index' (duration: 6.988968ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T14:04:34.564977Z","caller":"traceutil/trace.go:171","msg":"trace[2064053326] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"1.335100825s","start":"2026-08-06T14:04:33.229832Z","end":"2026-08-06T14:04:34.564933Z","steps":["trace[2064053326] 'process raft request' (duration: 1.332114041s)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:04:34.565152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T14:04:33.229805Z","time spent":"1.335255347s","remote":"127.0.0.1:33698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036285,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-06T14:04:34.565147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"530.065472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-jn97v\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-08-06T14:04:34.565229Z","caller":"traceutil/trace.go:171","msg":"trace[353702224] range","detail":"{range_begin:/registry/pods/kube-system/cilium-jn97v; range_end:; response_count:1; response_revision:636; }","duration":"530.178604ms","start":"2026-08-06T14:04:34.035027Z","end":"2026-08-06T14:04:34.565205Z","steps":["trace[353702224] 'agreement among raft nodes before linearized reading' (duration: 529.95128ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:04:34.565279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T14:04:34.035011Z","time spent":"530.255815ms","remote":"127.0.0.1:33802","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16989,"request content":"key:\"/registry/pods/kube-system/cilium-jn97v\" "} {"level":"warn","ts":"2026-08-06T14:04:34.565447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.506565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-08-06T14:04:34.565503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.541496ms","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-06T14:04:34.56558Z","caller":"traceutil/trace.go:171","msg":"trace[1346342799] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:636; }","duration":"368.673057ms","start":"2026-08-06T14:04:34.196885Z","end":"2026-08-06T14:04:34.565558Z","steps":["trace[1346342799] 'agreement among raft nodes before linearized reading' (duration: 368.464564ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:04:34.565566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.334482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T14:04:34.565719Z","caller":"traceutil/trace.go:171","msg":"trace[1869610922] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:636; }","duration":"254.526595ms","start":"2026-08-06T14:04:34.311156Z","end":"2026-08-06T14:04:34.565683Z","steps":["trace[1869610922] 'agreement among raft nodes before linearized reading' (duration: 254.24563ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:04:34.56563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T14:04:34.196838Z","time spent":"368.782719ms","remote":"127.0.0.1:33896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-08-06T14:04:34.565488Z","caller":"traceutil/trace.go:171","msg":"trace[713885210] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:636; }","duration":"333.556757ms","start":"2026-08-06T14:04:34.231919Z","end":"2026-08-06T14:04:34.565476Z","steps":["trace[713885210] 'agreement among raft nodes before linearized reading' (duration: 333.391654ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:04:34.565827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T14:04:34.231913Z","time spent":"333.907283ms","remote":"127.0.0.1:33896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-06T14:04:34.565467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.253912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-08-06T14:04:34.566009Z","caller":"traceutil/trace.go:171","msg":"trace[245673647] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:636; }","duration":"378.832152ms","start":"2026-08-06T14:04:34.187158Z","end":"2026-08-06T14:04:34.56599Z","steps":["trace[245673647] 'agreement among raft nodes before linearized reading' (duration: 378.014077ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:04:34.566086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T14:04:34.187145Z","time spent":"378.926075ms","remote":"127.0.0.1:33896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-06T14:04:34.56578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.736657ms","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-06T14:04:34.566339Z","caller":"traceutil/trace.go:171","msg":"trace[962191517] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:636; }","duration":"335.271108ms","start":"2026-08-06T14:04:34.231023Z","end":"2026-08-06T14:04:34.566294Z","steps":["trace[962191517] 'agreement among raft nodes before linearized reading' (duration: 334.28318ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:04:34.566374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T14:04:34.231018Z","time spent":"335.345919ms","remote":"127.0.0.1:33896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-06T14:04:35.058173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.770957ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-06T14:04:35.058287Z","caller":"traceutil/trace.go:171","msg":"trace[1792955967] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"486.171427ms","start":"2026-08-06T14:04:34.572097Z","end":"2026-08-06T14:04:35.058269Z","steps":["trace[1792955967] 'process raft request' (duration: 284.056854ms)","trace[1792955967] 'compare' (duration: 201.536953ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T14:04:35.058345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T14:04:34.57208Z","time spent":"486.237799ms","remote":"127.0.0.1:33896","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-06T14:04:35.237945Z","caller":"traceutil/trace.go:171","msg":"trace[530100295] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"665.689309ms","start":"2026-08-06T14:04:34.572225Z","end":"2026-08-06T14:04:35.237914Z","steps":["trace[530100295] 'process raft request' (duration: 640.506041ms)","trace[530100295] 'compare' (duration: 25.000255ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T14:04:35.238156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T14:04:34.572184Z","time spent":"665.860452ms","remote":"127.0.0.1:33896","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-06T14:04:35.238222Z","caller":"traceutil/trace.go:171","msg":"trace[1655024724] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"665.924573ms","start":"2026-08-06T14:04:34.572274Z","end":"2026-08-06T14:04:35.238199Z","steps":["trace[1655024724] 'process raft request' (duration: 665.593727ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:04:35.238374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T14:04:34.572264Z","time spent":"666.023645ms","remote":"127.0.0.1:33896","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-06T14:04:35.238613Z","caller":"traceutil/trace.go:171","msg":"trace[452875183] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"657.961958ms","start":"2026-08-06T14:04:34.580632Z","end":"2026-08-06T14:04:35.238594Z","steps":["trace[452875183] 'process raft request' (duration: 657.799735ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:04:35.238693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T14:04:34.580622Z","time spent":"658.039309ms","remote":"127.0.0.1:33802","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-06T14:04:35.238714Z","caller":"traceutil/trace.go:171","msg":"trace[1534573332] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"665.691208ms","start":"2026-08-06T14:04:34.573005Z","end":"2026-08-06T14:04:35.238696Z","steps":["trace[1534573332] 'process raft request' (duration: 665.093267ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:04:35.238751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"665.660717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T14:04:35.238794Z","caller":"traceutil/trace.go:171","msg":"trace[1979215533] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:642; }","duration":"665.729828ms","start":"2026-08-06T14:04:34.573053Z","end":"2026-08-06T14:04:35.238782Z","steps":["trace[1979215533] 'agreement among raft nodes before linearized reading' (duration: 665.600156ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:04:35.238805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T14:04:34.572997Z","time spent":"665.759619ms","remote":"127.0.0.1:33896","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-06T14:04:35.238833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T14:04:34.573045Z","time spent":"665.780989ms","remote":"127.0.0.1:33620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-06T14:04:35.238919Z","caller":"traceutil/trace.go:171","msg":"trace[866742572] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"613.394744ms","start":"2026-08-06T14:04:34.625506Z","end":"2026-08-06T14:04:35.238901Z","steps":["trace[866742572] 'process raft request' (duration: 613.022198ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T14:04:35.238603Z","caller":"traceutil/trace.go:171","msg":"trace[576011147] linearizableReadLoop","detail":"{readStateIndex:664; appliedIndex:660; }","duration":"665.511685ms","start":"2026-08-06T14:04:34.573066Z","end":"2026-08-06T14:04:35.238578Z","steps":["trace[576011147] 'read index received' (duration: 283.099466ms)","trace[576011147] 'applied index is now lower than readState.Index' (duration: 382.410819ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T14:04:35.239019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T14:04:34.625474Z","time spent":"613.491657ms","remote":"127.0.0.1:34108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-06T14:04:35.26523Z","caller":"traceutil/trace.go:171","msg":"trace[1019188002] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"366.62874ms","start":"2026-08-06T14:04:34.898577Z","end":"2026-08-06T14:04:35.265206Z","steps":["trace[1019188002] 'process raft request' (duration: 366.228343ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:04:35.265368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T14:04:34.898551Z","time spent":"366.739882ms","remote":"127.0.0.1:33896","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-06T14:04:36.024225Z","caller":"traceutil/trace.go:171","msg":"trace[523217343] linearizableReadLoop","detail":"{readStateIndex:668; appliedIndex:667; }","duration":"274.352873ms","start":"2026-08-06T14:04:35.749849Z","end":"2026-08-06T14:04:36.024202Z","steps":["trace[523217343] 'read index received' (duration: 273.354514ms)","trace[523217343] 'applied index is now lower than readState.Index' (duration: 997.429µs)"],"step_count":2} {"level":"warn","ts":"2026-08-06T14:04:36.024345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.475865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T14:04:36.024368Z","caller":"traceutil/trace.go:171","msg":"trace[985399320] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:644; }","duration":"274.518746ms","start":"2026-08-06T14:04:35.749842Z","end":"2026-08-06T14:04:36.024361Z","steps":["trace[985399320] 'agreement among raft nodes before linearized reading' (duration: 274.444885ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T14:04:36.024425Z","caller":"traceutil/trace.go:171","msg":"trace[578072621] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"338.140542ms","start":"2026-08-06T14:04:35.686124Z","end":"2026-08-06T14:04:36.024265Z","steps":["trace[578072621] 'process raft request' (duration: 337.328977ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:04:36.024591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T14:04:35.686096Z","time spent":"338.419518ms","remote":"127.0.0.1:33698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036313,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-06T14:04:36.387398Z","caller":"traceutil/trace.go:171","msg":"trace[877678482] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"306.483255ms","start":"2026-08-06T14:04:36.080881Z","end":"2026-08-06T14:04:36.387364Z","steps":["trace[877678482] 'process raft request' (duration: 306.100278ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:04:36.38773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T14:04:36.080848Z","time spent":"306.639007ms","remote":"127.0.0.1:33876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-06T14:04:39.312724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999887562s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-08-06T14:04:39.312802Z","caller":"traceutil/trace.go:171","msg":"trace[336876614] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000032964s","start":"2026-08-06T14:04:37.312753Z","end":"2026-08-06T14:04:39.312786Z","steps":["trace[336876614] 'agreement among raft nodes before linearized reading' (duration: 1.999933832s)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:04:39.312848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T14:04:37.312723Z","time spent":"2.000117245s","remote":"127.0.0.1:33634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/08/06 14:04:39 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-06T14:04:39.918569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.708224772s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T14:04:39.918695Z","caller":"traceutil/trace.go:171","msg":"trace[1417859905] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:645; }","duration":"1.708368175s","start":"2026-08-06T14:04:38.210308Z","end":"2026-08-06T14:04:39.918676Z","steps":["trace[1417859905] 'range keys from in-memory index tree' (duration: 1.708206492s)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:04:39.919182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.300237498s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-06T14:04:39.919647Z","caller":"traceutil/trace.go:171","msg":"trace[1023389767] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"3.510444012s","start":"2026-08-06T14:04:36.409162Z","end":"2026-08-06T14:04:39.919606Z","steps":["trace[1023389767] 'process raft request' (duration: 209.682764ms)","trace[1023389767] 'compare' (duration: 3.299662049s)"],"step_count":2} {"level":"warn","ts":"2026-08-06T14:04:39.919815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T14:04:36.409148Z","time spent":"3.510583775s","remote":"127.0.0.1:33802","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2735,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-06T14:04:39.920175Z","caller":"traceutil/trace.go:171","msg":"trace[29593766] linearizableReadLoop","detail":"{readStateIndex:670; appliedIndex:669; }","duration":"2.857619615s","start":"2026-08-06T14:04:37.062506Z","end":"2026-08-06T14:04:39.920125Z","steps":["trace[29593766] 'read index received' (duration: 58.261µs)","trace[29593766] 'applied index is now lower than readState.Index' (duration: 2.857557494s)"],"step_count":2} {"level":"warn","ts":"2026-08-06T14:04:39.941397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.878887595s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-08-06T14:04:39.941456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.698907032s","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-06T14:04:39.941504Z","caller":"traceutil/trace.go:171","msg":"trace[1525645809] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:646; }","duration":"2.699023844s","start":"2026-08-06T14:04:37.242469Z","end":"2026-08-06T14:04:39.941493Z","steps":["trace[1525645809] 'agreement among raft nodes before linearized reading' (duration: 2.698881382s)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:04:39.941528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T14:04:37.242434Z","time spent":"2.699088715s","remote":"127.0.0.1:33896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-06T14:04:39.941569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.696928046s","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-06T14:04:39.941596Z","caller":"traceutil/trace.go:171","msg":"trace[149802231] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:647; }","duration":"2.697000058s","start":"2026-08-06T14:04:37.24459Z","end":"2026-08-06T14:04:39.94159Z","steps":["trace[149802231] 'agreement among raft nodes before linearized reading' (duration: 2.696903546s)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:04:39.941616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T14:04:37.244562Z","time spent":"2.697048969s","remote":"127.0.0.1:33896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-08-06T14:04:39.94147Z","caller":"traceutil/trace.go:171","msg":"trace[998691696] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:646; }","duration":"2.879026288s","start":"2026-08-06T14:04:37.06243Z","end":"2026-08-06T14:04:39.941456Z","steps":["trace[998691696] 'agreement among raft nodes before linearized reading' (duration: 2.857926161s)","trace[998691696] 'range keys from in-memory index tree' (duration: 20.930894ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T14:04:39.941671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T14:04:37.062398Z","time spent":"2.879268102s","remote":"127.0.0.1:33896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-06T14:04:39.94168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.696886186s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-08-06T14:04:39.94179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"628.909347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T14:04:39.941812Z","caller":"traceutil/trace.go:171","msg":"trace[1283614111] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:647; }","duration":"628.952779ms","start":"2026-08-06T14:04:39.312854Z","end":"2026-08-06T14:04:39.941807Z","steps":["trace[1283614111] 'agreement among raft nodes before linearized reading' (duration: 628.867517ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T14:04:39.941796Z","caller":"traceutil/trace.go:171","msg":"trace[1750773092] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:647; }","duration":"2.697068919s","start":"2026-08-06T14:04:37.244702Z","end":"2026-08-06T14:04:39.941771Z","steps":["trace[1750773092] 'agreement among raft nodes before linearized reading' (duration: 2.696738723s)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:04:39.941867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T14:04:39.312842Z","time spent":"629.02138ms","remote":"127.0.0.1:33620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-06T14:04:39.94188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T14:04:37.244672Z","time spent":"2.697196632s","remote":"127.0.0.1:33896","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-06T14:04:41.383818Z","caller":"traceutil/trace.go:171","msg":"trace[1481813663] transaction","detail":"{read_only:false; response_revision:666; number_of_response:1; }","duration":"179.607694ms","start":"2026-08-06T14:04:41.204163Z","end":"2026-08-06T14:04:41.383771Z","steps":["trace[1481813663] 'process raft request' (duration: 179.428491ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T14:04:55.459043Z","caller":"traceutil/trace.go:171","msg":"trace[1465338353] linearizableReadLoop","detail":"{readStateIndex:836; appliedIndex:835; }","duration":"169.144258ms","start":"2026-08-06T14:04:55.289877Z","end":"2026-08-06T14:04:55.459021Z","steps":["trace[1465338353] 'read index received' (duration: 168.996256ms)","trace[1465338353] 'applied index is now lower than readState.Index' (duration: 147.022µs)"],"step_count":2} {"level":"info","ts":"2026-08-06T14:04:55.459239Z","caller":"traceutil/trace.go:171","msg":"trace[1760001691] transaction","detail":"{read_only:false; response_revision:806; number_of_response:1; }","duration":"266.266353ms","start":"2026-08-06T14:04:55.192933Z","end":"2026-08-06T14:04:55.459199Z","steps":["trace[1760001691] 'process raft request' (duration: 265.884427ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:04:55.459282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.376852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-06T14:04:55.459347Z","caller":"traceutil/trace.go:171","msg":"trace[2042601960] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:806; }","duration":"169.487354ms","start":"2026-08-06T14:04:55.289846Z","end":"2026-08-06T14:04:55.459333Z","steps":["trace[2042601960] 'agreement among raft nodes before linearized reading' (duration: 169.366381ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:04:55.479493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.178456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T14:04:55.479667Z","caller":"traceutil/trace.go:171","msg":"trace[1556107218] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:807; }","duration":"170.386819ms","start":"2026-08-06T14:04:55.309271Z","end":"2026-08-06T14:04:55.479657Z","steps":["trace[1556107218] 'agreement among raft nodes before linearized reading' (duration: 170.157735ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T14:04:55.479591Z","caller":"traceutil/trace.go:171","msg":"trace[368462532] transaction","detail":"{read_only:false; response_revision:807; number_of_response:1; }","duration":"175.236041ms","start":"2026-08-06T14:04:55.304333Z","end":"2026-08-06T14:04:55.479569Z","steps":["trace[368462532] 'process raft request' (duration: 174.941586ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:05:26.761004Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2802822090538466983,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-06T14:05:26.874741Z","caller":"traceutil/trace.go:171","msg":"trace[478635897] transaction","detail":"{read_only:false; response_revision:1209; number_of_response:1; }","duration":"784.160216ms","start":"2026-08-06T14:05:26.090559Z","end":"2026-08-06T14:05:26.874719Z","steps":["trace[478635897] 'process raft request' (duration: 784.042324ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:05:26.874877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T14:05:26.090538Z","time spent":"784.277257ms","remote":"127.0.0.1:33786","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6406,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-06T14:05:26.878026Z","caller":"traceutil/trace.go:171","msg":"trace[1951610806] transaction","detail":"{read_only:false; response_revision:1210; number_of_response:1; }","duration":"475.959274ms","start":"2026-08-06T14:05:26.402026Z","end":"2026-08-06T14:05:26.877985Z","steps":["trace[1951610806] 'process raft request' (duration: 474.852827ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:05:26.878187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T14:05:26.402002Z","time spent":"476.097476ms","remote":"127.0.0.1:33896","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-06T14:05:26.878233Z","caller":"traceutil/trace.go:171","msg":"trace[224420231] transaction","detail":"{read_only:false; response_revision:1211; number_of_response:1; }","duration":"325.177994ms","start":"2026-08-06T14:05:26.553011Z","end":"2026-08-06T14:05:26.878189Z","steps":["trace[224420231] 'process raft request' (duration: 324.128788ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:05:26.878393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T14:05:26.552989Z","time spent":"325.323646ms","remote":"127.0.0.1:33896","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-06T14:05:26.881085Z","caller":"traceutil/trace.go:171","msg":"trace[1990298008] linearizableReadLoop","detail":"{readStateIndex:1249; appliedIndex:1246; }","duration":"620.71281ms","start":"2026-08-06T14:05:26.260355Z","end":"2026-08-06T14:05:26.881068Z","steps":["trace[1990298008] 'read index received' (duration: 614.805188ms)","trace[1990298008] 'applied index is now lower than readState.Index' (duration: 5.906371ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T14:05:26.881332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"620.948413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-08-06T14:05:26.88139Z","caller":"traceutil/trace.go:171","msg":"trace[756308282] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1211; }","duration":"621.064874ms","start":"2026-08-06T14:05:26.260315Z","end":"2026-08-06T14:05:26.88138Z","steps":["trace[756308282] 'agreement among raft nodes before linearized reading' (duration: 620.871481ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:05:26.88146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T14:05:26.260285Z","time spent":"621.164396ms","remote":"127.0.0.1:33896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-06T14:05:26.881505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"600.129532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-06T14:05:26.881567Z","caller":"traceutil/trace.go:171","msg":"trace[418130877] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1211; }","duration":"600.214963ms","start":"2026-08-06T14:05:26.281338Z","end":"2026-08-06T14:05:26.881553Z","steps":["trace[418130877] 'agreement among raft nodes before linearized reading' (duration: 599.99378ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:05:26.881603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T14:05:26.281328Z","time spent":"600.267193ms","remote":"127.0.0.1:33896","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":"warn","ts":"2026-08-06T14:05:26.881728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"568.926159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-06T14:05:26.881739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"595.829315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-08-06T14:05:26.881771Z","caller":"traceutil/trace.go:171","msg":"trace[1935717537] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1211; }","duration":"569.003031ms","start":"2026-08-06T14:05:26.312758Z","end":"2026-08-06T14:05:26.881761Z","steps":["trace[1935717537] 'agreement among raft nodes before linearized reading' (duration: 568.94284ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:05:26.881799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T14:05:26.312737Z","time spent":"569.056132ms","remote":"127.0.0.1:33634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-06T14:05:26.881852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"597.45889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-08-06T14:05:26.881892Z","caller":"traceutil/trace.go:171","msg":"trace[1653807971] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1211; }","duration":"597.546812ms","start":"2026-08-06T14:05:26.284336Z","end":"2026-08-06T14:05:26.881883Z","steps":["trace[1653807971] 'agreement among raft nodes before linearized reading' (duration: 597.42692ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:05:26.88192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T14:05:26.284298Z","time spent":"597.616612ms","remote":"127.0.0.1:33896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-08-06T14:05:26.881916Z","caller":"traceutil/trace.go:171","msg":"trace[1599577416] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1211; }","duration":"595.917456ms","start":"2026-08-06T14:05:26.285862Z","end":"2026-08-06T14:05:26.88178Z","steps":["trace[1599577416] 'agreement among raft nodes before linearized reading' (duration: 595.688233ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:05:26.882084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T14:05:26.28585Z","time spent":"596.18746ms","remote":"127.0.0.1:33896","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-06T14:05:51.27943Z","caller":"traceutil/trace.go:171","msg":"trace[1563793315] linearizableReadLoop","detail":"{readStateIndex:1521; appliedIndex:1520; }","duration":"274.297291ms","start":"2026-08-06T14:05:51.005104Z","end":"2026-08-06T14:05:51.279401Z","steps":["trace[1563793315] 'read index received' (duration: 234.680697ms)","trace[1563793315] 'applied index is now lower than readState.Index' (duration: 39.615544ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T14:05:51.279572Z","caller":"traceutil/trace.go:171","msg":"trace[1790363611] transaction","detail":"{read_only:false; response_revision:1476; number_of_response:1; }","duration":"290.929716ms","start":"2026-08-06T14:05:50.988611Z","end":"2026-08-06T14:05:51.27954Z","steps":["trace[1790363611] 'process raft request' (duration: 251.1784ms)","trace[1790363611] 'compare' (duration: 39.491703ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T14:05:51.279754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.060203ms","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-06T14:05:51.279799Z","caller":"traceutil/trace.go:171","msg":"trace[926655446] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1476; }","duration":"271.135285ms","start":"2026-08-06T14:05:51.00865Z","end":"2026-08-06T14:05:51.279786Z","steps":["trace[926655446] 'agreement among raft nodes before linearized reading' (duration: 271.024043ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:05:51.279885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.79462ms","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-06T14:05:51.279944Z","caller":"traceutil/trace.go:171","msg":"trace[635272154] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1476; }","duration":"270.886321ms","start":"2026-08-06T14:05:51.009043Z","end":"2026-08-06T14:05:51.279929Z","steps":["trace[635272154] 'agreement among raft nodes before linearized reading' (duration: 270.743629ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:05:51.279724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.595596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-06T14:05:51.280049Z","caller":"traceutil/trace.go:171","msg":"trace[323718517] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1476; }","duration":"274.941951ms","start":"2026-08-06T14:05:51.005098Z","end":"2026-08-06T14:05:51.28004Z","steps":["trace[323718517] 'agreement among raft nodes before linearized reading' (duration: 274.462084ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:06:15.810137Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2802822090538468349,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-06T14:06:15.861127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"680.970433ms","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-06T14:06:15.861221Z","caller":"traceutil/trace.go:171","msg":"trace[117323533] transaction","detail":"{read_only:false; response_revision:1895; number_of_response:1; }","duration":"680.774ms","start":"2026-08-06T14:06:15.18042Z","end":"2026-08-06T14:06:15.861194Z","steps":["trace[117323533] 'process raft request' (duration: 634.289116ms)","trace[117323533] 'compare' (duration: 45.669362ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T14:06:15.861243Z","caller":"traceutil/trace.go:171","msg":"trace[1329765573] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1894; }","duration":"681.132435ms","start":"2026-08-06T14:06:15.180084Z","end":"2026-08-06T14:06:15.861216Z","steps":["trace[1329765573] 'range keys from in-memory index tree' (duration: 680.806891ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:06:15.861367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T14:06:15.18041Z","time spent":"680.877061ms","remote":"127.0.0.1:33896","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-06T14:06:15.861547Z","caller":"traceutil/trace.go:171","msg":"trace[491802034] linearizableReadLoop","detail":"{readStateIndex:1968; appliedIndex:1965; }","duration":"551.992723ms","start":"2026-08-06T14:06:15.30954Z","end":"2026-08-06T14:06:15.861533Z","steps":["trace[491802034] 'read index received' (duration: 505.176835ms)","trace[491802034] 'applied index is now lower than readState.Index' (duration: 46.814998ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T14:06:15.861483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T14:06:15.180066Z","time spent":"681.370209ms","remote":"127.0.0.1:43226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5436,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-08-06T14:06:15.861586Z","caller":"traceutil/trace.go:171","msg":"trace[864200102] transaction","detail":"{read_only:false; response_revision:1896; number_of_response:1; }","duration":"569.949799ms","start":"2026-08-06T14:06:15.291594Z","end":"2026-08-06T14:06:15.861543Z","steps":["trace[864200102] 'process raft request' (duration: 569.563673ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:06:15.861682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"552.136515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T14:06:15.861724Z","caller":"traceutil/trace.go:171","msg":"trace[1885459835] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1897; }","duration":"552.216256ms","start":"2026-08-06T14:06:15.309496Z","end":"2026-08-06T14:06:15.861713Z","steps":["trace[1885459835] 'agreement among raft nodes before linearized reading' (duration: 552.136465ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:06:15.861754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T14:06:15.309477Z","time spent":"552.271317ms","remote":"127.0.0.1:33634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-06T14:06:15.861774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.124795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-08-06T14:06:15.861795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T14:06:15.291564Z","time spent":"570.115792ms","remote":"127.0.0.1:33896","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-06T14:06:15.861891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.880156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-efe44304-e0a0-4f9c-b5f1-b4797f1fd326\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-08-06T14:06:15.861925Z","caller":"traceutil/trace.go:171","msg":"trace[1766456289] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-efe44304-e0a0-4f9c-b5f1-b4797f1fd326; range_end:; response_count:1; response_revision:1897; }","duration":"301.956667ms","start":"2026-08-06T14:06:15.559958Z","end":"2026-08-06T14:06:15.861915Z","steps":["trace[1766456289] 'agreement among raft nodes before linearized reading' (duration: 301.880226ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T14:06:15.861941Z","caller":"traceutil/trace.go:171","msg":"trace[231287471] transaction","detail":"{read_only:false; response_revision:1897; number_of_response:1; }","duration":"237.66165ms","start":"2026-08-06T14:06:15.624263Z","end":"2026-08-06T14:06:15.861924Z","steps":["trace[231287471] 'process raft request' (duration: 237.164703ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T14:06:15.861815Z","caller":"traceutil/trace.go:171","msg":"trace[1341651278] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1897; }","duration":"458.201805ms","start":"2026-08-06T14:06:15.403594Z","end":"2026-08-06T14:06:15.861796Z","steps":["trace[1341651278] 'agreement among raft nodes before linearized reading' (duration: 458.111534ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:06:15.862028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.659195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-efe44304-e0a0-4f9c-b5f1-b4797f1fd326\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-08-06T14:06:15.862049Z","caller":"traceutil/trace.go:171","msg":"trace[1288338034] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-efe44304-e0a0-4f9c-b5f1-b4797f1fd326; range_end:; response_count:1; response_revision:1897; }","duration":"304.679226ms","start":"2026-08-06T14:06:15.557363Z","end":"2026-08-06T14:06:15.862043Z","steps":["trace[1288338034] 'agreement among raft nodes before linearized reading' (duration: 304.616055ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:06:15.862057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T14:06:15.403572Z","time spent":"458.470399ms","remote":"127.0.0.1:33896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-06T14:06:15.861958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T14:06:15.559933Z","time spent":"302.018227ms","remote":"127.0.0.1:33802","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3651,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-efe44304-e0a0-4f9c-b5f1-b4797f1fd326\" "} {"level":"warn","ts":"2026-08-06T14:06:15.862124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.699576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-08-06T14:06:15.862151Z","caller":"traceutil/trace.go:171","msg":"trace[1645687494] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1897; }","duration":"419.734716ms","start":"2026-08-06T14:06:15.44241Z","end":"2026-08-06T14:06:15.862145Z","steps":["trace[1645687494] 'agreement among raft nodes before linearized reading' (duration: 419.671705ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:06:15.862171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T14:06:15.442405Z","time spent":"419.762277ms","remote":"127.0.0.1:33896","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":"warn","ts":"2026-08-06T14:06:15.862309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.013344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-08-06T14:06:15.862332Z","caller":"traceutil/trace.go:171","msg":"trace[1731550161] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1897; }","duration":"456.055615ms","start":"2026-08-06T14:06:15.406272Z","end":"2026-08-06T14:06:15.862328Z","steps":["trace[1731550161] 'agreement among raft nodes before linearized reading' (duration: 455.995184ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:06:15.86235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T14:06:15.406261Z","time spent":"456.084725ms","remote":"127.0.0.1:33896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-06T14:06:15.86207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T14:06:15.557299Z","time spent":"304.765317ms","remote":"127.0.0.1:33802","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3651,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-efe44304-e0a0-4f9c-b5f1-b4797f1fd326\" "} {"level":"warn","ts":"2026-08-06T14:06:15.862414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.78819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-06T14:06:15.862509Z","caller":"traceutil/trace.go:171","msg":"trace[1936040164] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1897; }","duration":"448.909762ms","start":"2026-08-06T14:06:15.413577Z","end":"2026-08-06T14:06:15.862487Z","steps":["trace[1936040164] 'agreement among raft nodes before linearized reading' (duration: 448.594528ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:06:15.862561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T14:06:15.413568Z","time spent":"448.985074ms","remote":"127.0.0.1:33896","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-06T14:06:16.267498Z","caller":"traceutil/trace.go:171","msg":"trace[1472814218] transaction","detail":"{read_only:false; response_revision:1899; number_of_response:1; }","duration":"396.561613ms","start":"2026-08-06T14:06:15.870877Z","end":"2026-08-06T14:06:16.267439Z","steps":["trace[1472814218] 'process raft request' (duration: 396.040135ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T14:06:16.267689Z","caller":"traceutil/trace.go:171","msg":"trace[6851846] transaction","detail":"{read_only:false; response_revision:1901; number_of_response:1; }","duration":"396.312829ms","start":"2026-08-06T14:06:15.87135Z","end":"2026-08-06T14:06:16.267663Z","steps":["trace[6851846] 'process raft request' (duration: 396.036505ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T14:06:16.267621Z","caller":"traceutil/trace.go:171","msg":"trace[1493728488] transaction","detail":"{read_only:false; response_revision:1902; number_of_response:1; }","duration":"394.969269ms","start":"2026-08-06T14:06:15.872616Z","end":"2026-08-06T14:06:16.267585Z","steps":["trace[1493728488] 'process raft request' (duration: 394.879978ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T14:06:16.267877Z","caller":"traceutil/trace.go:171","msg":"trace[313384480] transaction","detail":"{read_only:false; response_revision:1900; number_of_response:1; }","duration":"396.897728ms","start":"2026-08-06T14:06:15.870945Z","end":"2026-08-06T14:06:16.267843Z","steps":["trace[313384480] 'process raft request' (duration: 396.37192ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:06:16.267926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T14:06:15.872607Z","time spent":"395.178872ms","remote":"127.0.0.1:33802","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3732,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-06T14:06:16.267982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T14:06:15.870856Z","time spent":"396.753695ms","remote":"127.0.0.1:33896","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-06T14:06:16.268002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T14:06:15.870878Z","time spent":"397.06059ms","remote":"127.0.0.1:33896","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-06T14:06:16.268398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T14:06:15.871338Z","time spent":"396.41464ms","remote":"127.0.0.1:33896","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-06T14:06:16.278613Z","caller":"traceutil/trace.go:171","msg":"trace[1718368837] transaction","detail":"{read_only:false; response_revision:1903; number_of_response:1; }","duration":"319.442592ms","start":"2026-08-06T14:06:15.959146Z","end":"2026-08-06T14:06:16.278589Z","steps":["trace[1718368837] 'process raft request' (duration: 319.29484ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:06:16.278712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T14:06:15.959099Z","time spent":"319.563054ms","remote":"127.0.0.1:33896","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-06T14:06:16.278852Z","caller":"traceutil/trace.go:171","msg":"trace[316030218] transaction","detail":"{read_only:false; response_revision:1904; number_of_response:1; }","duration":"290.268576ms","start":"2026-08-06T14:06:15.988575Z","end":"2026-08-06T14:06:16.278843Z","steps":["trace[316030218] 'process raft request' (duration: 289.947542ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T14:06:16.278931Z","caller":"traceutil/trace.go:171","msg":"trace[975208925] linearizableReadLoop","detail":"{readStateIndex:1975; appliedIndex:1969; }","duration":"174.952463ms","start":"2026-08-06T14:06:16.103864Z","end":"2026-08-06T14:06:16.278816Z","steps":["trace[975208925] 'read index received' (duration: 163.004422ms)","trace[975208925] 'applied index is now lower than readState.Index' (duration: 11.938581ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T14:06:16.279135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.259357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-06T14:06:16.279264Z","caller":"traceutil/trace.go:171","msg":"trace[871461473] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:1904; }","duration":"175.392839ms","start":"2026-08-06T14:06:16.10381Z","end":"2026-08-06T14:06:16.279203Z","steps":["trace[871461473] 'agreement among raft nodes before linearized reading' (duration: 175.220977ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:06:16.8314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.753135ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-06T14:06:16.831794Z","caller":"traceutil/trace.go:171","msg":"trace[1420530881] transaction","detail":"{read_only:false; response_revision:1905; number_of_response:1; }","duration":"548.404463ms","start":"2026-08-06T14:06:16.283325Z","end":"2026-08-06T14:06:16.83173Z","steps":["trace[1420530881] 'process raft request' (duration: 122.200371ms)","trace[1420530881] 'compare' (duration: 425.443491ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T14:06:16.832032Z","caller":"traceutil/trace.go:171","msg":"trace[1135171977] linearizableReadLoop","detail":"{readStateIndex:1976; appliedIndex:1975; }","duration":"521.042523ms","start":"2026-08-06T14:06:16.310946Z","end":"2026-08-06T14:06:16.831988Z","steps":["trace[1135171977] 'read index received' (duration: 94.433956ms)","trace[1135171977] 'applied index is now lower than readState.Index' (duration: 426.594697ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T14:06:16.832082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T14:06:16.283295Z","time spent":"548.686167ms","remote":"127.0.0.1:33896","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-06T14:06:16.909994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"599.017584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T14:06:16.910105Z","caller":"traceutil/trace.go:171","msg":"trace[1103334052] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1905; }","duration":"599.194777ms","start":"2026-08-06T14:06:16.310886Z","end":"2026-08-06T14:06:16.91008Z","steps":["trace[1103334052] 'agreement among raft nodes before linearized reading' (duration: 521.287757ms)","trace[1103334052] 'range keys from in-memory index tree' (duration: 77.744768ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T14:06:16.910156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T14:06:16.310859Z","time spent":"599.282668ms","remote":"127.0.0.1:33634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-06T14:06:23.117828Z","caller":"traceutil/trace.go:171","msg":"trace[2120149685] linearizableReadLoop","detail":"{readStateIndex:2041; appliedIndex:2040; }","duration":"165.151457ms","start":"2026-08-06T14:06:22.952645Z","end":"2026-08-06T14:06:23.117796Z","steps":["trace[2120149685] 'read index received' (duration: 164.825953ms)","trace[2120149685] 'applied index is now lower than readState.Index' (duration: 324.234µs)"],"step_count":2} {"level":"warn","ts":"2026-08-06T14:06:23.118133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.451611ms","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-06T14:06:23.118174Z","caller":"traceutil/trace.go:171","msg":"trace[410609599] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1968; }","duration":"165.560912ms","start":"2026-08-06T14:06:22.952599Z","end":"2026-08-06T14:06:23.11816Z","steps":["trace[410609599] 'agreement among raft nodes before linearized reading' (duration: 165.320339ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T14:06:23.11831Z","caller":"traceutil/trace.go:171","msg":"trace[1658207276] transaction","detail":"{read_only:false; response_revision:1968; number_of_response:1; }","duration":"253.374695ms","start":"2026-08-06T14:06:22.864832Z","end":"2026-08-06T14:06:23.118207Z","steps":["trace[1658207276] 'process raft request' (duration: 252.773176ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T14:06:29.493108Z","caller":"traceutil/trace.go:171","msg":"trace[2095903176] linearizableReadLoop","detail":"{readStateIndex:2079; appliedIndex:2078; }","duration":"325.421236ms","start":"2026-08-06T14:06:29.167657Z","end":"2026-08-06T14:06:29.493078Z","steps":["trace[2095903176] 'read index received' (duration: 325.155722ms)","trace[2095903176] 'applied index is now lower than readState.Index' (duration: 264.144µs)"],"step_count":2} {"level":"info","ts":"2026-08-06T14:06:29.493265Z","caller":"traceutil/trace.go:171","msg":"trace[1507133223] transaction","detail":"{read_only:false; response_revision:2005; number_of_response:1; }","duration":"341.029636ms","start":"2026-08-06T14:06:29.152188Z","end":"2026-08-06T14:06:29.493218Z","steps":["trace[1507133223] 'process raft request' (duration: 340.714671ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:06:29.493364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.690259ms","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-06T14:06:29.493395Z","caller":"traceutil/trace.go:171","msg":"trace[1728262649] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2005; }","duration":"325.74448ms","start":"2026-08-06T14:06:29.16764Z","end":"2026-08-06T14:06:29.493384Z","steps":["trace[1728262649] 'agreement among raft nodes before linearized reading' (duration: 325.594868ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:06:29.493423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T14:06:29.167573Z","time spent":"325.843721ms","remote":"127.0.0.1:43226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5436,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-08-06T14:06:29.493517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T14:06:29.152172Z","time spent":"341.209668ms","remote":"127.0.0.1:33896","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-06T14:06:29.493524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.810194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T14:06:29.49362Z","caller":"traceutil/trace.go:171","msg":"trace[1613533231] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2005; }","duration":"183.950547ms","start":"2026-08-06T14:06:29.309645Z","end":"2026-08-06T14:06:29.493596Z","steps":["trace[1613533231] 'agreement among raft nodes before linearized reading' (duration: 183.809285ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T14:06:52.162456Z","caller":"traceutil/trace.go:171","msg":"trace[644601530] linearizableReadLoop","detail":"{readStateIndex:2223; appliedIndex:2221; }","duration":"449.612683ms","start":"2026-08-06T14:06:51.712816Z","end":"2026-08-06T14:06:52.162429Z","steps":["trace[644601530] 'read index received' (duration: 77.679573ms)","trace[644601530] 'applied index is now lower than readState.Index' (duration: 371.930429ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T14:06:52.162492Z","caller":"traceutil/trace.go:171","msg":"trace[574698310] transaction","detail":"{read_only:false; response_revision:2144; number_of_response:1; }","duration":"455.752078ms","start":"2026-08-06T14:06:51.706699Z","end":"2026-08-06T14:06:52.162451Z","steps":["trace[574698310] 'process raft request' (duration: 408.17409ms)","trace[574698310] 'compare' (duration: 47.301114ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T14:06:52.162663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T14:06:51.706678Z","time spent":"455.90674ms","remote":"127.0.0.1:33896","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-06T14:06:52.16269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.858546ms","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-06T14:06:52.162723Z","caller":"traceutil/trace.go:171","msg":"trace[1306101979] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2144; }","duration":"449.952326ms","start":"2026-08-06T14:06:51.712759Z","end":"2026-08-06T14:06:52.162712Z","steps":["trace[1306101979] 'agreement among raft nodes before linearized reading' (duration: 449.790894ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:06:52.162749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T14:06:51.712735Z","time spent":"450.007818ms","remote":"127.0.0.1:43226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5436,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-08-06T14:06:52.33804Z","caller":"traceutil/trace.go:171","msg":"trace[605903391] transaction","detail":"{read_only:false; response_revision:2145; number_of_response:1; }","duration":"271.402619ms","start":"2026-08-06T14:06:52.066513Z","end":"2026-08-06T14:06:52.337916Z","steps":["trace[605903391] 'process raft request' (duration: 211.823735ms)","trace[605903391] 'compare' (duration: 59.408191ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T14:06:52.338225Z","caller":"traceutil/trace.go:171","msg":"trace[1237982318] transaction","detail":"{read_only:false; response_revision:2146; number_of_response:1; }","duration":"271.650792ms","start":"2026-08-06T14:06:52.066555Z","end":"2026-08-06T14:06:52.338206Z","steps":["trace[1237982318] 'process raft request' (duration: 271.316278ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T14:06:52.338234Z","caller":"traceutil/trace.go:171","msg":"trace[312658653] linearizableReadLoop","detail":"{readStateIndex:2226; appliedIndex:2223; }","duration":"175.677776ms","start":"2026-08-06T14:06:52.162527Z","end":"2026-08-06T14:06:52.338205Z","steps":["trace[312658653] 'read index received' (duration: 115.740878ms)","trace[312658653] 'applied index is now lower than readState.Index' (duration: 59.935998ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T14:06:52.338293Z","caller":"traceutil/trace.go:171","msg":"trace[635902274] transaction","detail":"{read_only:false; response_revision:2147; number_of_response:1; }","duration":"267.650837ms","start":"2026-08-06T14:06:52.070606Z","end":"2026-08-06T14:06:52.338256Z","steps":["trace[635902274] 'process raft request' (duration: 267.501955ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:06:52.338512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"545.78813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"warn","ts":"2026-08-06T14:06:52.338541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.526242ms","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-06T14:06:52.338648Z","caller":"traceutil/trace.go:171","msg":"trace[1653932356] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2147; }","duration":"219.677894ms","start":"2026-08-06T14:06:52.118948Z","end":"2026-08-06T14:06:52.338625Z","steps":["trace[1653932356] 'agreement among raft nodes before linearized reading' (duration: 219.328219ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T14:06:52.33859Z","caller":"traceutil/trace.go:171","msg":"trace[532559667] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:2147; }","duration":"545.887622ms","start":"2026-08-06T14:06:51.792663Z","end":"2026-08-06T14:06:52.338551Z","steps":["trace[532559667] 'agreement among raft nodes before linearized reading' (duration: 545.642539ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:06:52.338802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T14:06:51.792648Z","time spent":"546.124905ms","remote":"127.0.0.1:33774","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":446,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"info","ts":"2026-08-06T14:06:52.733797Z","caller":"traceutil/trace.go:171","msg":"trace[1031030492] linearizableReadLoop","detail":"{readStateIndex:2228; appliedIndex:2227; }","duration":"233.700358ms","start":"2026-08-06T14:06:52.500039Z","end":"2026-08-06T14:06:52.73374Z","steps":["trace[1031030492] 'read index received' (duration: 153.835405ms)","trace[1031030492] 'applied index is now lower than readState.Index' (duration: 79.863403ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T14:06:52.734059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.993992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-08-06T14:06:52.734187Z","caller":"traceutil/trace.go:171","msg":"trace[1100217161] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2149; }","duration":"234.167784ms","start":"2026-08-06T14:06:52.49999Z","end":"2026-08-06T14:06:52.734157Z","steps":["trace[1100217161] 'agreement among raft nodes before linearized reading' (duration: 233.906991ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T14:06:52.733966Z","caller":"traceutil/trace.go:171","msg":"trace[268634811] transaction","detail":"{read_only:false; response_revision:2149; number_of_response:1; }","duration":"273.815182ms","start":"2026-08-06T14:06:52.460098Z","end":"2026-08-06T14:06:52.733913Z","steps":["trace[268634811] 'process raft request' (duration: 193.694485ms)","trace[268634811] 'compare' (duration: 79.656511ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T14:06:52.734477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.365407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-08-06T14:06:52.734712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.400066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-06T14:06:52.734784Z","caller":"traceutil/trace.go:171","msg":"trace[759687710] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2149; }","duration":"226.505268ms","start":"2026-08-06T14:06:52.508262Z","end":"2026-08-06T14:06:52.734767Z","steps":["trace[759687710] 'agreement among raft nodes before linearized reading' (duration: 226.275465ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T14:06:52.734536Z","caller":"traceutil/trace.go:171","msg":"trace[880139157] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2149; }","duration":"226.496118ms","start":"2026-08-06T14:06:52.508025Z","end":"2026-08-06T14:06:52.734521Z","steps":["trace[880139157] 'agreement among raft nodes before linearized reading' (duration: 225.991952ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:06:53.296995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.58675ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-06T14:06:53.297096Z","caller":"traceutil/trace.go:171","msg":"trace[1380099730] linearizableReadLoop","detail":"{readStateIndex:2232; appliedIndex:2231; }","duration":"314.320939ms","start":"2026-08-06T14:06:52.982757Z","end":"2026-08-06T14:06:53.297078Z","steps":["trace[1380099730] 'read index received' (duration: 73.731µs)","trace[1380099730] 'applied index is now lower than readState.Index' (duration: 314.245758ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T14:06:53.297169Z","caller":"traceutil/trace.go:171","msg":"trace[618859418] transaction","detail":"{read_only:false; response_revision:2153; number_of_response:1; }","duration":"471.961776ms","start":"2026-08-06T14:06:52.825196Z","end":"2026-08-06T14:06:53.297158Z","steps":["trace[618859418] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/events/openstack/percona-xtradb-haproxy-0.18c93c7553c4970e; req_size:828; } (duration: 463.178494ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:06:53.297223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T14:06:52.825181Z","time spent":"472.010616ms","remote":"127.0.0.1:33692","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":831,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-06T14:06:53.29729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.212557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T14:06:53.297412Z","caller":"traceutil/trace.go:171","msg":"trace[1700615653] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:2153; }","duration":"167.388981ms","start":"2026-08-06T14:06:53.129992Z","end":"2026-08-06T14:06:53.297381Z","steps":["trace[1700615653] 'agreement among raft nodes before linearized reading' (duration: 167.215929ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:06:53.297833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.363718ms","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-06T14:06:53.297893Z","caller":"traceutil/trace.go:171","msg":"trace[1210458783] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2153; }","duration":"127.425149ms","start":"2026-08-06T14:06:53.170453Z","end":"2026-08-06T14:06:53.297879Z","steps":["trace[1210458783] 'agreement among raft nodes before linearized reading' (duration: 127.230336ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:06:53.298255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.480105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12386"} {"level":"info","ts":"2026-08-06T14:06:53.298354Z","caller":"traceutil/trace.go:171","msg":"trace[1800632446] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2153; }","duration":"315.643798ms","start":"2026-08-06T14:06:52.98269Z","end":"2026-08-06T14:06:53.298334Z","steps":["trace[1800632446] 'agreement among raft nodes before linearized reading' (duration: 314.873726ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:06:53.298574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T14:06:52.982626Z","time spent":"315.933841ms","remote":"127.0.0.1:33802","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12410,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-08-06T14:06:53.631665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.58263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-06T14:06:53.631761Z","caller":"traceutil/trace.go:171","msg":"trace[336657972] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:2153; }","duration":"166.737362ms","start":"2026-08-06T14:06:53.465003Z","end":"2026-08-06T14:06:53.631741Z","steps":["trace[336657972] 'count revisions from in-memory index tree' (duration: 166.467708ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:06:53.632122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.004579ms","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-06T14:06:53.632221Z","caller":"traceutil/trace.go:171","msg":"trace[1697923018] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:2153; }","duration":"176.146532ms","start":"2026-08-06T14:06:53.456049Z","end":"2026-08-06T14:06:53.632195Z","steps":["trace[1697923018] 'range keys from in-memory index tree' (duration: 175.820337ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:06:54.66087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.209278ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-06T14:06:54.661031Z","caller":"traceutil/trace.go:171","msg":"trace[919538109] linearizableReadLoop","detail":"{readStateIndex:2234; appliedIndex:2233; }","duration":"493.901183ms","start":"2026-08-06T14:06:54.167105Z","end":"2026-08-06T14:06:54.661006Z","steps":["trace[919538109] 'read index received' (duration: 120.405491ms)","trace[919538109] 'applied index is now lower than readState.Index' (duration: 373.494182ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T14:06:54.661179Z","caller":"traceutil/trace.go:171","msg":"trace[1343097271] transaction","detail":"{read_only:false; response_revision:2155; number_of_response:1; }","duration":"820.920335ms","start":"2026-08-06T14:06:53.840201Z","end":"2026-08-06T14:06:54.661121Z","steps":["trace[1343097271] 'process raft request' (duration: 447.343223ms)","trace[1343097271] 'compare' (duration: 373.047585ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T14:06:54.661293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T14:06:53.84017Z","time spent":"821.067877ms","remote":"127.0.0.1:33896","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-06T14:06:54.661525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"494.409509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-08-06T14:06:54.66163Z","caller":"traceutil/trace.go:171","msg":"trace[1760978865] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2155; }","duration":"494.579602ms","start":"2026-08-06T14:06:54.167028Z","end":"2026-08-06T14:06:54.661608Z","steps":["trace[1760978865] 'agreement among raft nodes before linearized reading' (duration: 494.098575ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:06:54.661676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T14:06:54.166992Z","time spent":"494.672423ms","remote":"127.0.0.1:33896","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":"info","ts":"2026-08-06T14:06:54.663908Z","caller":"traceutil/trace.go:171","msg":"trace[1914481101] transaction","detail":"{read_only:false; response_revision:2156; number_of_response:1; }","duration":"320.539701ms","start":"2026-08-06T14:06:54.343334Z","end":"2026-08-06T14:06:54.663874Z","steps":["trace[1914481101] 'process raft request' (duration: 320.40485ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:06:54.664151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T14:06:54.343311Z","time spent":"320.744974ms","remote":"127.0.0.1:33896","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-06T14:06:54.759786Z","caller":"traceutil/trace.go:171","msg":"trace[465976360] transaction","detail":"{read_only:false; response_revision:2157; number_of_response:1; }","duration":"416.356543ms","start":"2026-08-06T14:06:54.343397Z","end":"2026-08-06T14:06:54.759753Z","steps":["trace[465976360] 'process raft request' (duration: 416.18024ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T14:06:54.759963Z","caller":"traceutil/trace.go:171","msg":"trace[2129626974] transaction","detail":"{read_only:false; response_revision:2158; number_of_response:1; }","duration":"416.286482ms","start":"2026-08-06T14:06:54.343652Z","end":"2026-08-06T14:06:54.759939Z","steps":["trace[2129626974] 'process raft request' (duration: 416.056319ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:06:54.760042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.564677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-06T14:06:54.760211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T14:06:54.343643Z","time spent":"416.466045ms","remote":"127.0.0.1:33896","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-06T14:06:54.759977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T14:06:54.343374Z","time spent":"416.501304ms","remote":"127.0.0.1:33896","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-06T14:06:54.76027Z","caller":"traceutil/trace.go:171","msg":"trace[381841715] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2158; }","duration":"448.823181ms","start":"2026-08-06T14:06:54.311429Z","end":"2026-08-06T14:06:54.760253Z","steps":["trace[381841715] 'agreement among raft nodes before linearized reading' (duration: 448.532888ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:06:54.76061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T14:06:54.31141Z","time spent":"449.191897ms","remote":"127.0.0.1:33634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-06T14:06:54.76038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.169474ms","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-06T14:06:54.760755Z","caller":"traceutil/trace.go:171","msg":"trace[1070785088] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2158; }","duration":"401.57325ms","start":"2026-08-06T14:06:54.359164Z","end":"2026-08-06T14:06:54.760737Z","steps":["trace[1070785088] 'agreement among raft nodes before linearized reading' (duration: 400.772998ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:06:54.760846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T14:06:54.359153Z","time spent":"401.676561ms","remote":"127.0.0.1:33896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-06T14:06:54.760487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.613701ms","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-06T14:06:54.760954Z","caller":"traceutil/trace.go:171","msg":"trace[805416660] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2158; }","duration":"455.146229ms","start":"2026-08-06T14:06:54.305791Z","end":"2026-08-06T14:06:54.760938Z","steps":["trace[805416660] 'agreement among raft nodes before linearized reading' (duration: 454.271326ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:06:54.761082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T14:06:54.305758Z","time spent":"455.308351ms","remote":"127.0.0.1:43226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5436,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-08-06T14:06:55.7095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"653.686661ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-06T14:06:55.709663Z","caller":"traceutil/trace.go:171","msg":"trace[2016180278] transaction","detail":"{read_only:false; response_revision:2161; number_of_response:1; }","duration":"944.652315ms","start":"2026-08-06T14:06:54.764998Z","end":"2026-08-06T14:06:55.70965Z","steps":["trace[2016180278] 'process raft request' (duration: 944.626605ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:06:55.709705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T14:06:54.764984Z","time spent":"944.700516ms","remote":"127.0.0.1:33896","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-06T14:06:55.709902Z","caller":"traceutil/trace.go:171","msg":"trace[810454817] transaction","detail":"{read_only:false; response_revision:2159; number_of_response:1; }","duration":"1.042497205s","start":"2026-08-06T14:06:54.667397Z","end":"2026-08-06T14:06:55.709894Z","steps":["trace[810454817] 'process raft request' (duration: 388.355717ms)","trace[810454817] 'compare' (duration: 653.197905ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T14:06:55.709935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T14:06:54.66737Z","time spent":"1.042547635s","remote":"127.0.0.1:33896","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-06T14:06:55.710014Z","caller":"traceutil/trace.go:171","msg":"trace[2129163528] transaction","detail":"{read_only:false; response_revision:2160; number_of_response:1; }","duration":"969.797712ms","start":"2026-08-06T14:06:54.740207Z","end":"2026-08-06T14:06:55.710005Z","steps":["trace[2129163528] 'process raft request' (duration: 969.378666ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:06:55.710044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T14:06:54.740178Z","time spent":"969.850102ms","remote":"127.0.0.1:33896","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-06T14:06:55.710128Z","caller":"traceutil/trace.go:171","msg":"trace[1730335252] linearizableReadLoop","detail":"{readStateIndex:2239; appliedIndex:2237; }","duration":"950.217513ms","start":"2026-08-06T14:06:54.759905Z","end":"2026-08-06T14:06:55.710122Z","steps":["trace[1730335252] 'read index received' (duration: 295.861731ms)","trace[1730335252] 'applied index is now lower than readState.Index' (duration: 654.354942ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T14:06:55.710371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.037456956s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-06T14:06:55.710452Z","caller":"traceutil/trace.go:171","msg":"trace[456070193] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:2161; }","duration":"1.037571728s","start":"2026-08-06T14:06:54.672852Z","end":"2026-08-06T14:06:55.710423Z","steps":["trace[456070193] 'agreement among raft nodes before linearized reading' (duration: 1.037437776s)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:06:55.710504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T14:06:54.672837Z","time spent":"1.037656949s","remote":"127.0.0.1:34088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":5,"response size":31,"request content":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true "} {"level":"warn","ts":"2026-08-06T14:06:55.710508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"948.793862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T14:06:55.71058Z","caller":"traceutil/trace.go:171","msg":"trace[448748007] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2161; }","duration":"948.891724ms","start":"2026-08-06T14:06:54.761671Z","end":"2026-08-06T14:06:55.710563Z","steps":["trace[448748007] 'agreement among raft nodes before linearized reading' (duration: 948.760282ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:06:55.710625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T14:06:54.761664Z","time spent":"948.952935ms","remote":"127.0.0.1:33620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-06T14:06:55.710768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.741716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-06T14:06:55.71076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"898.763693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-06T14:06:55.710802Z","caller":"traceutil/trace.go:171","msg":"trace[1777754807] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:2161; }","duration":"107.831637ms","start":"2026-08-06T14:06:55.602961Z","end":"2026-08-06T14:06:55.710792Z","steps":["trace[1777754807] 'agreement among raft nodes before linearized reading' (duration: 107.762856ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T14:06:55.71083Z","caller":"traceutil/trace.go:171","msg":"trace[1599795147] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2161; }","duration":"898.869584ms","start":"2026-08-06T14:06:54.811941Z","end":"2026-08-06T14:06:55.710811Z","steps":["trace[1599795147] 'agreement among raft nodes before linearized reading' (duration: 898.645221ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:06:55.710877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T14:06:54.811934Z","time spent":"898.934935ms","remote":"127.0.0.1:33896","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":"warn","ts":"2026-08-06T14:06:55.71089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"899.386071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-08-06T14:06:55.710912Z","caller":"traceutil/trace.go:171","msg":"trace[223483756] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2161; }","duration":"899.431211ms","start":"2026-08-06T14:06:54.811474Z","end":"2026-08-06T14:06:55.710905Z","steps":["trace[223483756] 'agreement among raft nodes before linearized reading' (duration: 899.38075ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:06:55.710839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"945.097222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-08-06T14:06:55.71093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T14:06:54.811462Z","time spent":"899.464251ms","remote":"127.0.0.1:33896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-08-06T14:06:55.710947Z","caller":"traceutil/trace.go:171","msg":"trace[845615984] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2161; }","duration":"945.213174ms","start":"2026-08-06T14:06:54.765723Z","end":"2026-08-06T14:06:55.710936Z","steps":["trace[845615984] 'agreement among raft nodes before linearized reading' (duration: 945.032291ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:06:55.710985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T14:06:54.765718Z","time spent":"945.256784ms","remote":"127.0.0.1:33896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-06T14:06:56.229549Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2802822090538468942,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-06T14:06:56.263886Z","caller":"traceutil/trace.go:171","msg":"trace[89531685] transaction","detail":"{read_only:false; response_revision:2162; number_of_response:1; }","duration":"538.809726ms","start":"2026-08-06T14:06:55.725054Z","end":"2026-08-06T14:06:56.263864Z","steps":["trace[89531685] 'process raft request' (duration: 537.681901ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:06:56.264435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T14:06:55.725033Z","time spent":"538.921937ms","remote":"127.0.0.1:33896","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-06T14:06:56.268123Z","caller":"traceutil/trace.go:171","msg":"trace[1305780042] transaction","detail":"{read_only:false; response_revision:2163; number_of_response:1; }","duration":"543.028204ms","start":"2026-08-06T14:06:55.725077Z","end":"2026-08-06T14:06:56.268105Z","steps":["trace[1305780042] 'process raft request' (duration: 542.889592ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:06:56.268241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T14:06:55.725059Z","time spent":"543.117796ms","remote":"127.0.0.1:33896","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-06T14:06:56.268377Z","caller":"traceutil/trace.go:171","msg":"trace[1512299909] transaction","detail":"{read_only:false; response_revision:2164; number_of_response:1; }","duration":"542.842142ms","start":"2026-08-06T14:06:55.72551Z","end":"2026-08-06T14:06:56.268352Z","steps":["trace[1512299909] 'process raft request' (duration: 542.562468ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:06:56.26849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T14:06:55.725493Z","time spent":"542.938473ms","remote":"127.0.0.1:33896","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-06T14:06:56.26855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"539.395084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T14:06:56.268686Z","caller":"traceutil/trace.go:171","msg":"trace[1487876720] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2164; }","duration":"539.562016ms","start":"2026-08-06T14:06:55.729101Z","end":"2026-08-06T14:06:56.268663Z","steps":["trace[1487876720] 'agreement among raft nodes before linearized reading' (duration: 539.347333ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:06:56.268743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T14:06:55.729094Z","time spent":"539.638907ms","remote":"127.0.0.1:33634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-06T14:06:56.268713Z","caller":"traceutil/trace.go:171","msg":"trace[333352596] linearizableReadLoop","detail":"{readStateIndex:2243; appliedIndex:2240; }","duration":"539.554356ms","start":"2026-08-06T14:06:55.72913Z","end":"2026-08-06T14:06:56.268684Z","steps":["trace[333352596] 'read index received' (duration: 533.615975ms)","trace[333352596] 'applied index is now lower than readState.Index' (duration: 5.549136ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T14:06:56.269876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"501.712764ms","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":"warn","ts":"2026-08-06T14:06:56.269977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.945829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T14:06:56.270015Z","caller":"traceutil/trace.go:171","msg":"trace[1650362275] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:2164; }","duration":"415.028369ms","start":"2026-08-06T14:06:55.854978Z","end":"2026-08-06T14:06:56.270007Z","steps":["trace[1650362275] 'agreement among raft nodes before linearized reading' (duration: 414.962898ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:06:56.270041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T14:06:55.854948Z","time spent":"415.087851ms","remote":"127.0.0.1:34010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":29,"request content":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true "} {"level":"info","ts":"2026-08-06T14:06:56.269928Z","caller":"traceutil/trace.go:171","msg":"trace[1739775549] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2164; }","duration":"501.814036ms","start":"2026-08-06T14:06:55.768102Z","end":"2026-08-06T14:06:56.269916Z","steps":["trace[1739775549] 'agreement among raft nodes before linearized reading' (duration: 501.641904ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:06:56.270583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T14:06:55.76808Z","time spent":"502.488926ms","remote":"127.0.0.1:43226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5436,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-08-06T14:09:15.20127Z","caller":"traceutil/trace.go:171","msg":"trace[1905588327] transaction","detail":"{read_only:false; response_revision:2985; number_of_response:1; }","duration":"100.323352ms","start":"2026-08-06T14:09:15.100917Z","end":"2026-08-06T14:09:15.201241Z","steps":["trace[1905588327] 'process raft request' (duration: 100.086494ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T14:09:15.201789Z","caller":"traceutil/trace.go:171","msg":"trace[1703608193] transaction","detail":"{read_only:false; response_revision:2986; number_of_response:1; }","duration":"100.696042ms","start":"2026-08-06T14:09:15.101078Z","end":"2026-08-06T14:09:15.201774Z","steps":["trace[1703608193] 'process raft request' (duration: 100.411724ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:09:15.95342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.078813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-06T14:09:15.953494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.234709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-06T14:09:15.953566Z","caller":"traceutil/trace.go:171","msg":"trace[1777794824] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2993; }","duration":"211.299779ms","start":"2026-08-06T14:09:15.742236Z","end":"2026-08-06T14:09:15.953536Z","steps":["trace[1777794824] 'range keys from in-memory index tree' (duration: 210.961429ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T14:09:15.953594Z","caller":"traceutil/trace.go:171","msg":"trace[855492623] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:2993; }","duration":"205.394943ms","start":"2026-08-06T14:09:15.748173Z","end":"2026-08-06T14:09:15.953568Z","steps":["trace[855492623] 'count revisions from in-memory index tree' (duration: 205.132075ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:09:15.953806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.298725ms","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-06T14:09:15.953867Z","caller":"traceutil/trace.go:171","msg":"trace[1238818200] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2993; }","duration":"181.386148ms","start":"2026-08-06T14:09:15.772468Z","end":"2026-08-06T14:09:15.953854Z","steps":["trace[1238818200] 'range keys from in-memory index tree' (duration: 180.956125ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T14:09:17.131796Z","caller":"traceutil/trace.go:171","msg":"trace[2123252991] transaction","detail":"{read_only:false; response_revision:3000; number_of_response:1; }","duration":"124.058962ms","start":"2026-08-06T14:09:17.007717Z","end":"2026-08-06T14:09:17.131776Z","steps":["trace[2123252991] 'process raft request' (duration: 123.884226ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T14:09:51.868816Z","caller":"traceutil/trace.go:171","msg":"trace[413949431] linearizableReadLoop","detail":"{readStateIndex:3323; appliedIndex:3322; }","duration":"195.032309ms","start":"2026-08-06T14:09:51.673765Z","end":"2026-08-06T14:09:51.868797Z","steps":["trace[413949431] 'read index received' (duration: 194.942226ms)","trace[413949431] 'applied index is now lower than readState.Index' (duration: 89.063µs)"],"step_count":2} {"level":"warn","ts":"2026-08-06T14:09:51.868911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.129801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T14:09:51.868932Z","caller":"traceutil/trace.go:171","msg":"trace[798199668] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:3204; }","duration":"195.191083ms","start":"2026-08-06T14:09:51.673733Z","end":"2026-08-06T14:09:51.868925Z","steps":["trace[798199668] 'agreement among raft nodes before linearized reading' (duration: 195.135641ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T14:09:52.014717Z","caller":"traceutil/trace.go:171","msg":"trace[125594810] linearizableReadLoop","detail":"{readStateIndex:3324; appliedIndex:3323; }","duration":"142.590716ms","start":"2026-08-06T14:09:51.8721Z","end":"2026-08-06T14:09:52.014691Z","steps":["trace[125594810] 'read index received' (duration: 136.29699ms)","trace[125594810] 'applied index is now lower than readState.Index' (duration: 6.292856ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T14:09:52.014738Z","caller":"traceutil/trace.go:171","msg":"trace[388214113] transaction","detail":"{read_only:false; response_revision:3205; number_of_response:1; }","duration":"144.532958ms","start":"2026-08-06T14:09:51.870174Z","end":"2026-08-06T14:09:52.014707Z","steps":["trace[388214113] 'process raft request' (duration: 138.19296ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:09:52.015069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.863263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T14:09:52.015489Z","caller":"traceutil/trace.go:171","msg":"trace[1791207008] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:3205; }","duration":"143.046218ms","start":"2026-08-06T14:09:51.87205Z","end":"2026-08-06T14:09:52.015096Z","steps":["trace[1791207008] 'agreement among raft nodes before linearized reading' (duration: 142.851673ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:09:53.156379Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2802822090538471532,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-06T14:09:53.249809Z","caller":"traceutil/trace.go:171","msg":"trace[146849614] transaction","detail":"{read_only:false; response_revision:3206; number_of_response:1; }","duration":"823.768314ms","start":"2026-08-06T14:09:52.426005Z","end":"2026-08-06T14:09:53.249773Z","steps":["trace[146849614] 'process raft request' (duration: 823.594278ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:09:53.249993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T14:09:52.425982Z","time spent":"823.917088ms","remote":"127.0.0.1:33896","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-06T14:09:53.716542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.732458ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-06T14:09:53.716744Z","caller":"traceutil/trace.go:171","msg":"trace[904257586] transaction","detail":"{read_only:false; response_revision:3210; number_of_response:1; }","duration":"283.219535ms","start":"2026-08-06T14:09:53.433511Z","end":"2026-08-06T14:09:53.716731Z","steps":["trace[904257586] 'process raft request' (duration: 283.178704ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T14:09:53.716923Z","caller":"traceutil/trace.go:171","msg":"trace[1902289581] transaction","detail":"{read_only:false; response_revision:3207; number_of_response:1; }","duration":"952.67152ms","start":"2026-08-06T14:09:52.764245Z","end":"2026-08-06T14:09:53.716916Z","steps":["trace[1902289581] 'process raft request' (duration: 825.404208ms)","trace[1902289581] 'compare' (duration: 126.471861ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T14:09:53.716958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T14:09:52.764222Z","time spent":"952.718481ms","remote":"127.0.0.1:33896","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-06T14:09:53.71704Z","caller":"traceutil/trace.go:171","msg":"trace[1975735613] linearizableReadLoop","detail":"{readStateIndex:3326; appliedIndex:3324; }","duration":"1.061247261s","start":"2026-08-06T14:09:52.655786Z","end":"2026-08-06T14:09:53.717034Z","steps":["trace[1975735613] 'read index received' (duration: 593.834215ms)","trace[1975735613] 'applied index is now lower than readState.Index' (duration: 467.412306ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T14:09:53.717196Z","caller":"traceutil/trace.go:171","msg":"trace[1638476156] transaction","detail":"{read_only:false; response_revision:3208; number_of_response:1; }","duration":"332.598907ms","start":"2026-08-06T14:09:53.384592Z","end":"2026-08-06T14:09:53.717191Z","steps":["trace[1638476156] 'process raft request' (duration: 332.011482ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:09:53.717246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T14:09:53.384571Z","time spent":"332.657548ms","remote":"127.0.0.1:33896","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-06T14:09:53.717362Z","caller":"traceutil/trace.go:171","msg":"trace[331104243] transaction","detail":"{read_only:false; response_revision:3209; number_of_response:1; }","duration":"283.904562ms","start":"2026-08-06T14:09:53.433451Z","end":"2026-08-06T14:09:53.717355Z","steps":["trace[331104243] 'process raft request' (duration: 283.212404ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:09:53.717536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.061748735s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-08-06T14:09:53.717555Z","caller":"traceutil/trace.go:171","msg":"trace[1163836471] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3210; }","duration":"1.061797216s","start":"2026-08-06T14:09:52.655753Z","end":"2026-08-06T14:09:53.71755Z","steps":["trace[1163836471] 'agreement among raft nodes before linearized reading' (duration: 1.061710534s)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:09:53.71757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T14:09:52.655736Z","time spent":"1.061830448s","remote":"127.0.0.1:33896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-06T14:09:53.717706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.055549042s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-08-06T14:09:53.717724Z","caller":"traceutil/trace.go:171","msg":"trace[467055808] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3210; }","duration":"1.055606913s","start":"2026-08-06T14:09:52.662113Z","end":"2026-08-06T14:09:53.71772Z","steps":["trace[467055808] 'agreement among raft nodes before linearized reading' (duration: 1.055558172s)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:09:53.717756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T14:09:52.662091Z","time spent":"1.055643565s","remote":"127.0.0.1:33896","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-06T14:09:53.717948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.03093ms","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-06T14:09:53.717969Z","caller":"traceutil/trace.go:171","msg":"trace[1234858354] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:3210; }","duration":"262.076002ms","start":"2026-08-06T14:09:53.455887Z","end":"2026-08-06T14:09:53.717963Z","steps":["trace[1234858354] 'agreement among raft nodes before linearized reading' (duration: 262.02091ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:09:53.718764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.847331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T14:09:53.718791Z","caller":"traceutil/trace.go:171","msg":"trace[1447658152] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3210; }","duration":"406.911353ms","start":"2026-08-06T14:09:53.311873Z","end":"2026-08-06T14:09:53.718785Z","steps":["trace[1447658152] 'agreement among raft nodes before linearized reading' (duration: 406.874752ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:09:53.718804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T14:09:53.311854Z","time spent":"406.947333ms","remote":"127.0.0.1:33634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-06T14:09:53.718944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.256969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-08-06T14:09:53.718964Z","caller":"traceutil/trace.go:171","msg":"trace[880409449] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3210; }","duration":"311.30178ms","start":"2026-08-06T14:09:53.407657Z","end":"2026-08-06T14:09:53.718959Z","steps":["trace[880409449] 'agreement among raft nodes before linearized reading' (duration: 311.237278ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:09:53.718979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T14:09:53.407645Z","time spent":"311.328671ms","remote":"127.0.0.1:33896","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-06T14:09:53.718965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.980121ms","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-06T14:09:53.719107Z","caller":"traceutil/trace.go:171","msg":"trace[1977332722] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3210; }","duration":"311.132876ms","start":"2026-08-06T14:09:53.407953Z","end":"2026-08-06T14:09:53.719086Z","steps":["trace[1977332722] 'agreement among raft nodes before linearized reading' (duration: 310.866589ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:09:53.719096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.070244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-08-06T14:09:53.71913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T14:09:53.407946Z","time spent":"311.178167ms","remote":"127.0.0.1:33896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-08-06T14:09:53.719148Z","caller":"traceutil/trace.go:171","msg":"trace[22097428] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3210; }","duration":"311.123435ms","start":"2026-08-06T14:09:53.408009Z","end":"2026-08-06T14:09:53.719133Z","steps":["trace[22097428] 'agreement among raft nodes before linearized reading' (duration: 310.974051ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:09:53.719197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T14:09:53.407988Z","time spent":"311.200047ms","remote":"127.0.0.1:33896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-06T14:09:53.719316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"564.034216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-06T14:09:53.719126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"512.074386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/auth-system/keycloak-0.18c93c9c0fbec0a0\" ","response":"range_response_count:1 size:781"} {"level":"info","ts":"2026-08-06T14:09:53.71934Z","caller":"traceutil/trace.go:171","msg":"trace[1482585716] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:3210; }","duration":"564.096937ms","start":"2026-08-06T14:09:53.155236Z","end":"2026-08-06T14:09:53.719333Z","steps":["trace[1482585716] 'agreement among raft nodes before linearized reading' (duration: 564.033806ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:09:53.719355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T14:09:53.155213Z","time spent":"564.138319ms","remote":"127.0.0.1:52074","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":19,"response size":31,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true "} {"level":"info","ts":"2026-08-06T14:09:53.719367Z","caller":"traceutil/trace.go:171","msg":"trace[1819312750] range","detail":"{range_begin:/registry/events/auth-system/keycloak-0.18c93c9c0fbec0a0; range_end:; response_count:1; response_revision:3210; }","duration":"512.404735ms","start":"2026-08-06T14:09:53.206943Z","end":"2026-08-06T14:09:53.719348Z","steps":["trace[1819312750] 'agreement among raft nodes before linearized reading' (duration: 511.943983ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:09:53.719517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T14:09:53.206926Z","time spent":"512.504338ms","remote":"127.0.0.1:33692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":1,"response size":805,"request content":"key:\"/registry/events/auth-system/keycloak-0.18c93c9c0fbec0a0\" "} {"level":"warn","ts":"2026-08-06T14:13:18.949819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.993863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-08-06T14:13:18.949983Z","caller":"traceutil/trace.go:171","msg":"trace[598942513] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4477; }","duration":"158.192996ms","start":"2026-08-06T14:13:18.791771Z","end":"2026-08-06T14:13:18.949964Z","steps":["trace[598942513] 'range keys from in-memory index tree' (duration: 157.855771ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:13:18.950099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.084359ms","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-06T14:13:18.950187Z","caller":"traceutil/trace.go:171","msg":"trace[327376951] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4477; }","duration":"106.228332ms","start":"2026-08-06T14:13:18.843937Z","end":"2026-08-06T14:13:18.950165Z","steps":["trace[327376951] 'range keys from in-memory index tree' (duration: 105.901096ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:13:18.950149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.384172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-08-06T14:13:18.950385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.706368ms","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-06T14:13:18.950481Z","caller":"traceutil/trace.go:171","msg":"trace[1212901704] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4477; }","duration":"108.453031ms","start":"2026-08-06T14:13:18.842008Z","end":"2026-08-06T14:13:18.950461Z","steps":["trace[1212901704] 'range keys from in-memory index tree' (duration: 107.492694ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T14:13:18.950473Z","caller":"traceutil/trace.go:171","msg":"trace[1814915776] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4477; }","duration":"107.715638ms","start":"2026-08-06T14:13:18.842728Z","end":"2026-08-06T14:13:18.950443Z","steps":["trace[1814915776] 'range keys from in-memory index tree' (duration: 107.084026ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:13:18.950727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.445347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-08-06T14:13:18.950802Z","caller":"traceutil/trace.go:171","msg":"trace[2043805932] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4477; }","duration":"105.539359ms","start":"2026-08-06T14:13:18.84525Z","end":"2026-08-06T14:13:18.95079Z","steps":["trace[2043805932] 'range keys from in-memory index tree' (duration: 104.346278ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:13:21.481189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.165637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T14:13:21.481278Z","caller":"traceutil/trace.go:171","msg":"trace[1590685264] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4491; }","duration":"168.307589ms","start":"2026-08-06T14:13:21.31295Z","end":"2026-08-06T14:13:21.481258Z","steps":["trace[1590685264] 'range keys from in-memory index tree' (duration: 168.062625ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:13:21.481352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"518.703473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-08-06T14:13:21.481481Z","caller":"traceutil/trace.go:171","msg":"trace[1271430626] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4491; }","duration":"518.857816ms","start":"2026-08-06T14:13:20.962599Z","end":"2026-08-06T14:13:21.481456Z","steps":["trace[1271430626] 'range keys from in-memory index tree' (duration: 518.57365ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:13:21.481529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T14:13:20.962582Z","time spent":"518.936468ms","remote":"127.0.0.1:33896","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-06T14:13:21.481586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.532761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T14:13:21.481616Z","caller":"traceutil/trace.go:171","msg":"trace[834007565] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:4491; }","duration":"151.597272ms","start":"2026-08-06T14:13:21.33001Z","end":"2026-08-06T14:13:21.481607Z","steps":["trace[834007565] 'count revisions from in-memory index tree' (duration: 151.47045ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:13:21.481773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"518.55617ms","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-06T14:13:21.48181Z","caller":"traceutil/trace.go:171","msg":"trace[1702521423] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4491; }","duration":"518.599891ms","start":"2026-08-06T14:13:20.963201Z","end":"2026-08-06T14:13:21.4818Z","steps":["trace[1702521423] 'range keys from in-memory index tree' (duration: 518.496618ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:13:21.481836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T14:13:20.963196Z","time spent":"518.633071ms","remote":"127.0.0.1:33896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-06T14:13:21.481846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"516.663057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-08-06T14:13:21.481848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"518.419427ms","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-06T14:13:21.481864Z","caller":"traceutil/trace.go:171","msg":"trace[502413995] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4491; }","duration":"516.681627ms","start":"2026-08-06T14:13:20.965177Z","end":"2026-08-06T14:13:21.481859Z","steps":["trace[502413995] 'range keys from in-memory index tree' (duration: 516.560405ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:13:21.481883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T14:13:20.965114Z","time spent":"516.765498ms","remote":"127.0.0.1:33896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-08-06T14:13:21.481893Z","caller":"traceutil/trace.go:171","msg":"trace[246734690] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4491; }","duration":"518.471188ms","start":"2026-08-06T14:13:20.963407Z","end":"2026-08-06T14:13:21.481879Z","steps":["trace[246734690] 'range keys from in-memory index tree' (duration: 518.296695ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:13:21.481924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T14:13:20.963404Z","time spent":"518.511799ms","remote":"127.0.0.1:33896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-06T14:13:21.481957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"515.699908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-08-06T14:13:21.481971Z","caller":"traceutil/trace.go:171","msg":"trace[1088497940] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4491; }","duration":"515.72664ms","start":"2026-08-06T14:13:20.96624Z","end":"2026-08-06T14:13:21.481966Z","steps":["trace[1088497940] 'range keys from in-memory index tree' (duration: 515.646608ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:13:21.481985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T14:13:20.966233Z","time spent":"515.74734ms","remote":"127.0.0.1:33896","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-06T14:13:22.116481Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2802822090538474996,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-06T14:13:22.557551Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.072332537s","expected-duration":"1s"} {"level":"info","ts":"2026-08-06T14:13:22.557868Z","caller":"traceutil/trace.go:171","msg":"trace[592092644] transaction","detail":"{read_only:false; response_revision:4492; number_of_response:1; }","duration":"1.072744064s","start":"2026-08-06T14:13:21.485101Z","end":"2026-08-06T14:13:22.557845Z","steps":["trace[592092644] 'process raft request' (duration: 1.072601752s)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:13:22.558009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T14:13:21.485077Z","time spent":"1.072844395s","remote":"127.0.0.1:33896","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-06T14:13:22.560675Z","caller":"traceutil/trace.go:171","msg":"trace[1518007807] transaction","detail":"{read_only:false; response_revision:4494; number_of_response:1; }","duration":"1.074593407s","start":"2026-08-06T14:13:21.486067Z","end":"2026-08-06T14:13:22.56066Z","steps":["trace[1518007807] 'process raft request' (duration: 1.074523146s)"],"step_count":1} {"level":"info","ts":"2026-08-06T14:13:22.560718Z","caller":"traceutil/trace.go:171","msg":"trace[951287805] transaction","detail":"{read_only:false; response_revision:4493; number_of_response:1; }","duration":"1.074920933s","start":"2026-08-06T14:13:21.485745Z","end":"2026-08-06T14:13:22.560666Z","steps":["trace[951287805] 'process raft request' (duration: 1.07475913s)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:13:22.560877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T14:13:21.486058Z","time spent":"1.074659588s","remote":"127.0.0.1:33896","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-06T14:13:22.561037Z","caller":"traceutil/trace.go:171","msg":"trace[1729475200] transaction","detail":"{read_only:false; response_revision:4495; number_of_response:1; }","duration":"1.074529025s","start":"2026-08-06T14:13:21.486477Z","end":"2026-08-06T14:13:22.561006Z","steps":["trace[1729475200] 'process raft request' (duration: 1.074150589s)"],"step_count":1} {"level":"info","ts":"2026-08-06T14:13:22.561082Z","caller":"traceutil/trace.go:171","msg":"trace[720440450] transaction","detail":"{read_only:false; response_revision:4496; number_of_response:1; }","duration":"1.073962785s","start":"2026-08-06T14:13:21.487102Z","end":"2026-08-06T14:13:22.561065Z","steps":["trace[720440450] 'process raft request' (duration: 1.073801182s)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:13:22.561132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T14:13:21.486469Z","time spent":"1.074616998s","remote":"127.0.0.1:33896","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-06T14:13:22.561144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T14:13:21.487094Z","time spent":"1.074015666s","remote":"127.0.0.1:33896","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-06T14:13:22.561223Z","caller":"traceutil/trace.go:171","msg":"trace[2023693977] linearizableReadLoop","detail":"{readStateIndex:4677; appliedIndex:4670; }","duration":"945.378252ms","start":"2026-08-06T14:13:21.615824Z","end":"2026-08-06T14:13:22.561202Z","steps":["trace[2023693977] 'read index received' (duration: 941.9229ms)","trace[2023693977] 'applied index is now lower than readState.Index' (duration: 3.454582ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T14:13:22.561352Z","caller":"traceutil/trace.go:171","msg":"trace[1458872776] transaction","detail":"{read_only:false; response_revision:4497; number_of_response:1; }","duration":"580.657884ms","start":"2026-08-06T14:13:21.98068Z","end":"2026-08-06T14:13:22.561338Z","steps":["trace[1458872776] 'process raft request' (duration: 580.322178ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T14:13:22.561385Z","caller":"traceutil/trace.go:171","msg":"trace[56425410] transaction","detail":"{read_only:false; response_revision:4498; number_of_response:1; }","duration":"558.880106ms","start":"2026-08-06T14:13:22.002493Z","end":"2026-08-06T14:13:22.561373Z","steps":["trace[56425410] 'process raft request' (duration: 558.579491ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:13:22.561439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T14:13:21.980649Z","time spent":"580.751516ms","remote":"127.0.0.1:33896","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-06T14:13:22.561534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"945.695757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/\" range_end:\"/registry/rabbitmq.com/rabbitmqclusters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T14:13:22.561582Z","caller":"traceutil/trace.go:171","msg":"trace[971507971] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/; range_end:/registry/rabbitmq.com/rabbitmqclusters0; response_count:0; response_revision:4501; }","duration":"945.785748ms","start":"2026-08-06T14:13:21.61578Z","end":"2026-08-06T14:13:22.561566Z","steps":["trace[971507971] 'agreement among raft nodes before linearized reading' (duration: 945.672026ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:13:22.561621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"928.371249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-admission-create-4kdn5\" ","response":"range_response_count:1 size:4695"} {"level":"info","ts":"2026-08-06T14:13:22.561666Z","caller":"traceutil/trace.go:171","msg":"trace[808953276] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-admission-create-4kdn5; range_end:; response_count:1; response_revision:4501; }","duration":"928.43605ms","start":"2026-08-06T14:13:21.633216Z","end":"2026-08-06T14:13:22.561652Z","steps":["trace[808953276] 'agreement among raft nodes before linearized reading' (duration: 928.314348ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:13:22.561701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T14:13:21.633203Z","time spent":"928.491741ms","remote":"127.0.0.1:33802","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":4719,"request content":"key:\"/registry/pods/monitoring/kube-prometheus-stack-admission-create-4kdn5\" "} {"level":"warn","ts":"2026-08-06T14:13:22.561809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T14:13:22.002476Z","time spent":"558.935588ms","remote":"127.0.0.1:33896","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-06T14:13:22.561903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"886.352313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.169\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-08-06T14:13:22.561624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T14:13:21.615764Z","time spent":"945.84653ms","remote":"127.0.0.1:41028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/\" range_end:\"/registry/rabbitmq.com/rabbitmqclusters0\" count_only:true "} {"level":"info","ts":"2026-08-06T14:13:22.561948Z","caller":"traceutil/trace.go:171","msg":"trace[1653547981] range","detail":"{range_begin:/registry/masterleases/199.19.213.169; range_end:; response_count:1; response_revision:4501; }","duration":"886.438884ms","start":"2026-08-06T14:13:21.675497Z","end":"2026-08-06T14:13:22.561936Z","steps":["trace[1653547981] 'agreement among raft nodes before linearized reading' (duration: 886.345153ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:13:22.561994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T14:13:21.675469Z","time spent":"886.512055ms","remote":"127.0.0.1:33660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/199.19.213.169\" "} {"level":"warn","ts":"2026-08-06T14:13:22.560926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T14:13:21.485727Z","time spent":"1.075062415s","remote":"127.0.0.1:33896","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-06T14:13:26.437232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.475807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T14:13:26.437326Z","caller":"traceutil/trace.go:171","msg":"trace[2023082289] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4858; }","duration":"126.610158ms","start":"2026-08-06T14:13:26.310691Z","end":"2026-08-06T14:13:26.437301Z","steps":["trace[2023082289] 'range keys from in-memory index tree' (duration: 126.405935ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T14:13:26.437804Z","caller":"traceutil/trace.go:171","msg":"trace[922877017] transaction","detail":"{read_only:false; response_revision:4859; number_of_response:1; }","duration":"119.631196ms","start":"2026-08-06T14:13:26.318148Z","end":"2026-08-06T14:13:26.437779Z","steps":["trace[922877017] 'process raft request' (duration: 116.866597ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T14:13:27.822249Z","caller":"traceutil/trace.go:171","msg":"trace[191494731] linearizableReadLoop","detail":"{readStateIndex:5135; appliedIndex:5134; }","duration":"110.195626ms","start":"2026-08-06T14:13:27.712027Z","end":"2026-08-06T14:13:27.822223Z","steps":["trace[191494731] 'read index received' (duration: 109.954282ms)","trace[191494731] 'applied index is now lower than readState.Index' (duration: 240.214µs)"],"step_count":2} {"level":"warn","ts":"2026-08-06T14:13:27.822485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.43398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-90dd717a-429b-419c-acd3-5d40231161a0\" ","response":"range_response_count:1 size:3774"} {"level":"info","ts":"2026-08-06T14:13:27.822527Z","caller":"traceutil/trace.go:171","msg":"trace[187620190] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-90dd717a-429b-419c-acd3-5d40231161a0; range_end:; response_count:1; response_revision:4947; }","duration":"110.538572ms","start":"2026-08-06T14:13:27.711976Z","end":"2026-08-06T14:13:27.822514Z","steps":["trace[187620190] 'agreement among raft nodes before linearized reading' (duration: 110.351169ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T14:13:27.822611Z","caller":"traceutil/trace.go:171","msg":"trace[683688969] transaction","detail":"{read_only:false; response_revision:4947; number_of_response:1; }","duration":"110.826718ms","start":"2026-08-06T14:13:27.711757Z","end":"2026-08-06T14:13:27.822584Z","steps":["trace[683688969] 'process raft request' (duration: 110.309999ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T14:13:30.183991Z","caller":"traceutil/trace.go:171","msg":"trace[1140440233] transaction","detail":"{read_only:false; response_revision:4986; number_of_response:1; }","duration":"114.336925ms","start":"2026-08-06T14:13:30.069625Z","end":"2026-08-06T14:13:30.183962Z","steps":["trace[1140440233] 'process raft request' (duration: 87.476212ms)","trace[1140440233] 'compare' (duration: 26.200442ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T14:13:31.493487Z","caller":"traceutil/trace.go:171","msg":"trace[1932741888] transaction","detail":"{read_only:false; response_revision:5016; number_of_response:1; }","duration":"126.796813ms","start":"2026-08-06T14:13:31.366622Z","end":"2026-08-06T14:13:31.493419Z","steps":["trace[1932741888] 'process raft request' (duration: 126.63033ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T14:13:34.371807Z","caller":"traceutil/trace.go:171","msg":"trace[128191997] linearizableReadLoop","detail":"{readStateIndex:5273; appliedIndex:5272; }","duration":"163.816106ms","start":"2026-08-06T14:13:34.207966Z","end":"2026-08-06T14:13:34.371782Z","steps":["trace[128191997] 'read index received' (duration: 163.612573ms)","trace[128191997] 'applied index is now lower than readState.Index' (duration: 202.493µs)"],"step_count":2} {"level":"warn","ts":"2026-08-06T14:13:34.371985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.982729ms","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-06T14:13:34.372014Z","caller":"traceutil/trace.go:171","msg":"trace[1353865737] transaction","detail":"{read_only:false; response_revision:5081; number_of_response:1; }","duration":"175.724856ms","start":"2026-08-06T14:13:34.196257Z","end":"2026-08-06T14:13:34.371982Z","steps":["trace[1353865737] 'process raft request' (duration: 175.38756ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T14:13:34.372033Z","caller":"traceutil/trace.go:171","msg":"trace[1798395511] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:5081; }","duration":"164.073012ms","start":"2026-08-06T14:13:34.207945Z","end":"2026-08-06T14:13:34.372018Z","steps":["trace[1798395511] 'agreement among raft nodes before linearized reading' (duration: 163.930649ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T14:13:34.696856Z","caller":"traceutil/trace.go:171","msg":"trace[1882609230] transaction","detail":"{read_only:false; response_revision:5082; number_of_response:1; }","duration":"318.946312ms","start":"2026-08-06T14:13:34.377886Z","end":"2026-08-06T14:13:34.696832Z","steps":["trace[1882609230] 'process raft request' (duration: 318.773639ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:13:34.696997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T14:13:34.377863Z","time spent":"319.058423ms","remote":"127.0.0.1:33902","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1853,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-06T14:13:34.9021Z","caller":"traceutil/trace.go:171","msg":"trace[287138535] transaction","detail":"{read_only:false; response_revision:5083; number_of_response:1; }","duration":"523.711736ms","start":"2026-08-06T14:13:34.378365Z","end":"2026-08-06T14:13:34.902077Z","steps":["trace[287138535] 'process raft request' (duration: 523.504882ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:13:34.902273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T14:13:34.378341Z","time spent":"523.85546ms","remote":"127.0.0.1:33774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1249,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-06T14:13:34.902528Z","caller":"traceutil/trace.go:171","msg":"trace[934919829] transaction","detail":"{read_only:false; response_revision:5084; number_of_response:1; }","duration":"523.514734ms","start":"2026-08-06T14:13:34.378999Z","end":"2026-08-06T14:13:34.902514Z","steps":["trace[934919829] 'process raft request' (duration: 523.013825ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:13:34.902602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T14:13:34.378993Z","time spent":"523.573455ms","remote":"127.0.0.1:34076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4883,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-06T14:13:34.902781Z","caller":"traceutil/trace.go:171","msg":"trace[1013355075] transaction","detail":"{read_only:false; response_revision:5085; number_of_response:1; }","duration":"291.367896ms","start":"2026-08-06T14:13:34.611385Z","end":"2026-08-06T14:13:34.902753Z","steps":["trace[1013355075] 'process raft request' (duration: 290.96644ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T14:13:34.902784Z","caller":"traceutil/trace.go:171","msg":"trace[594034235] transaction","detail":"{read_only:false; response_revision:5087; number_of_response:1; }","duration":"285.993433ms","start":"2026-08-06T14:13:34.616766Z","end":"2026-08-06T14:13:34.902759Z","steps":["trace[594034235] 'process raft request' (duration: 285.808229ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T14:13:34.902841Z","caller":"traceutil/trace.go:171","msg":"trace[2117680545] transaction","detail":"{read_only:false; response_revision:5086; number_of_response:1; }","duration":"289.726829ms","start":"2026-08-06T14:13:34.613106Z","end":"2026-08-06T14:13:34.902832Z","steps":["trace[2117680545] 'process raft request' (duration: 289.425824ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T14:13:34.903727Z","caller":"traceutil/trace.go:171","msg":"trace[335192597] linearizableReadLoop","detail":"{readStateIndex:5276; appliedIndex:5273; }","duration":"502.019066ms","start":"2026-08-06T14:13:34.401677Z","end":"2026-08-06T14:13:34.903696Z","steps":["trace[335192597] 'read index received' (duration: 295.078821ms)","trace[335192597] 'applied index is now lower than readState.Index' (duration: 206.939115ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T14:13:34.903864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"502.172319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-06T14:13:34.904861Z","caller":"traceutil/trace.go:171","msg":"trace[1785554127] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:5088; }","duration":"503.220916ms","start":"2026-08-06T14:13:34.401623Z","end":"2026-08-06T14:13:34.904844Z","steps":["trace[1785554127] 'agreement among raft nodes before linearized reading' (duration: 502.156618ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:13:34.904916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T14:13:34.401598Z","time spent":"503.302848ms","remote":"127.0.0.1:52048","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":21,"response size":31,"request content":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true "} {"level":"info","ts":"2026-08-06T14:13:34.903909Z","caller":"traceutil/trace.go:171","msg":"trace[1460364492] transaction","detail":"{read_only:false; response_revision:5088; number_of_response:1; }","duration":"286.958169ms","start":"2026-08-06T14:13:34.616936Z","end":"2026-08-06T14:13:34.903894Z","steps":["trace[1460364492] 'process raft request' (duration: 285.782569ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T14:13:35.02533Z","caller":"traceutil/trace.go:171","msg":"trace[1171802654] linearizableReadLoop","detail":"{readStateIndex:5281; appliedIndex:5280; }","duration":"120.488686ms","start":"2026-08-06T14:13:34.904813Z","end":"2026-08-06T14:13:35.025302Z","steps":["trace[1171802654] 'read index received' (duration: 95.63784ms)","trace[1171802654] 'applied index is now lower than readState.Index' (duration: 24.849886ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T14:13:35.025374Z","caller":"traceutil/trace.go:171","msg":"trace[1103831805] transaction","detail":"{read_only:false; response_revision:5089; number_of_response:1; }","duration":"232.476022ms","start":"2026-08-06T14:13:34.792846Z","end":"2026-08-06T14:13:35.025322Z","steps":["trace[1103831805] 'process raft request' (duration: 207.593126ms)","trace[1103831805] 'compare' (duration: 24.723663ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T14:13:35.025675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.263098ms","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-06T14:13:35.025718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.458029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-08-06T14:13:35.025741Z","caller":"traceutil/trace.go:171","msg":"trace[1087114056] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5089; }","duration":"356.373719ms","start":"2026-08-06T14:13:34.669352Z","end":"2026-08-06T14:13:35.025726Z","steps":["trace[1087114056] 'agreement among raft nodes before linearized reading' (duration: 356.031252ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T14:13:35.025753Z","caller":"traceutil/trace.go:171","msg":"trace[819487513] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5089; }","duration":"377.5385ms","start":"2026-08-06T14:13:34.648205Z","end":"2026-08-06T14:13:35.025744Z","steps":["trace[819487513] 'agreement among raft nodes before linearized reading' (duration: 377.385078ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:13:35.02578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T14:13:34.648186Z","time spent":"377.588251ms","remote":"127.0.0.1:33896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-06T14:13:35.025772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.54161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-08-06T14:13:35.02578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T14:13:34.669325Z","time spent":"356.446591ms","remote":"127.0.0.1:33896","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-06T14:13:35.025805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.809876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-rg82b\" ","response":"range_response_count:1 size:9674"} {"level":"info","ts":"2026-08-06T14:13:35.025816Z","caller":"traceutil/trace.go:171","msg":"trace[650060875] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5089; }","duration":"377.643422ms","start":"2026-08-06T14:13:34.64816Z","end":"2026-08-06T14:13:35.025804Z","steps":["trace[650060875] 'agreement among raft nodes before linearized reading' (duration: 377.259875ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T14:13:35.025857Z","caller":"traceutil/trace.go:171","msg":"trace[1824760687] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-rg82b; range_end:; response_count:1; response_revision:5089; }","duration":"233.910428ms","start":"2026-08-06T14:13:34.79193Z","end":"2026-08-06T14:13:35.02584Z","steps":["trace[1824760687] 'agreement among raft nodes before linearized reading' (duration: 233.436039ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:13:35.025866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T14:13:34.648153Z","time spent":"377.702423ms","remote":"127.0.0.1:33896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-06T14:13:35.026229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.937208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-08-06T14:13:35.026272Z","caller":"traceutil/trace.go:171","msg":"trace[124041312] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5089; }","duration":"377.992928ms","start":"2026-08-06T14:13:34.648268Z","end":"2026-08-06T14:13:35.026261Z","steps":["trace[124041312] 'agreement among raft nodes before linearized reading' (duration: 377.072032ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:13:35.026302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T14:13:34.648263Z","time spent":"378.032659ms","remote":"127.0.0.1:33896","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-06T14:13:35.026653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.430976ms","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-06T14:13:35.026699Z","caller":"traceutil/trace.go:171","msg":"trace[966483167] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5089; }","duration":"378.497517ms","start":"2026-08-06T14:13:34.648191Z","end":"2026-08-06T14:13:35.026689Z","steps":["trace[966483167] 'agreement among raft nodes before linearized reading' (duration: 377.237084ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:13:35.026728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T14:13:34.648179Z","time spent":"378.540507ms","remote":"127.0.0.1:33896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-08-06T14:13:35.162724Z","caller":"traceutil/trace.go:171","msg":"trace[2020087600] transaction","detail":"{read_only:false; response_revision:5090; number_of_response:1; }","duration":"253.102733ms","start":"2026-08-06T14:13:34.909605Z","end":"2026-08-06T14:13:35.162708Z","steps":["trace[2020087600] 'process raft request' (duration: 217.130981ms)","trace[2020087600] 'compare' (duration: 35.821908ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T14:13:35.162938Z","caller":"traceutil/trace.go:171","msg":"trace[362722332] linearizableReadLoop","detail":"{readStateIndex:5283; appliedIndex:5281; }","duration":"137.540515ms","start":"2026-08-06T14:13:35.02539Z","end":"2026-08-06T14:13:35.162931Z","steps":["trace[362722332] 'read index received' (duration: 101.267798ms)","trace[362722332] 'applied index is now lower than readState.Index' (duration: 36.271777ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T14:13:35.163061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.132294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-06T14:13:35.163088Z","caller":"traceutil/trace.go:171","msg":"trace[2046581984] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:5091; }","duration":"163.200094ms","start":"2026-08-06T14:13:34.999882Z","end":"2026-08-06T14:13:35.163082Z","steps":["trace[2046581984] 'agreement among raft nodes before linearized reading' (duration: 163.080022ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T14:13:35.163196Z","caller":"traceutil/trace.go:171","msg":"trace[102736594] transaction","detail":"{read_only:false; response_revision:5091; number_of_response:1; }","duration":"187.374849ms","start":"2026-08-06T14:13:34.975815Z","end":"2026-08-06T14:13:35.16319Z","steps":["trace[102736594] 'process raft request' (duration: 186.847819ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T14:13:35.273196Z","caller":"traceutil/trace.go:171","msg":"trace[663001914] transaction","detail":"{read_only:false; response_revision:5093; number_of_response:1; }","duration":"243.291809ms","start":"2026-08-06T14:13:35.029888Z","end":"2026-08-06T14:13:35.27318Z","steps":["trace[663001914] 'process raft request' (duration: 243.011964ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T14:13:35.272964Z","caller":"traceutil/trace.go:171","msg":"trace[296749485] transaction","detail":"{read_only:false; response_revision:5092; number_of_response:1; }","duration":"243.163887ms","start":"2026-08-06T14:13:35.029774Z","end":"2026-08-06T14:13:35.272938Z","steps":["trace[296749485] 'process raft request' (duration: 242.917212ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T14:13:35.273795Z","caller":"traceutil/trace.go:171","msg":"trace[2118730426] transaction","detail":"{read_only:false; response_revision:5094; number_of_response:1; }","duration":"243.572944ms","start":"2026-08-06T14:13:35.030207Z","end":"2026-08-06T14:13:35.27378Z","steps":["trace[2118730426] 'process raft request' (duration: 242.916323ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T14:13:35.274802Z","caller":"traceutil/trace.go:171","msg":"trace[446223746] transaction","detail":"{read_only:false; response_revision:5095; number_of_response:1; }","duration":"244.439959ms","start":"2026-08-06T14:13:35.030318Z","end":"2026-08-06T14:13:35.274758Z","steps":["trace[446223746] 'process raft request' (duration: 243.31781ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T14:13:35.276151Z","caller":"traceutil/trace.go:171","msg":"trace[847852175] transaction","detail":"{read_only:false; response_revision:5097; number_of_response:1; }","duration":"238.18661ms","start":"2026-08-06T14:13:35.037954Z","end":"2026-08-06T14:13:35.27614Z","steps":["trace[847852175] 'process raft request' (duration: 238.051238ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T14:13:35.276414Z","caller":"traceutil/trace.go:171","msg":"trace[1851573047] transaction","detail":"{read_only:false; response_revision:5096; number_of_response:1; }","duration":"245.997736ms","start":"2026-08-06T14:13:35.030407Z","end":"2026-08-06T14:13:35.276405Z","steps":["trace[1851573047] 'process raft request' (duration: 245.541619ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:13:40.618357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.451225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-79b51c7f-67ef-495e-ae6d-839cd0e287a9\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-08-06T14:13:40.618456Z","caller":"traceutil/trace.go:171","msg":"trace[375128998] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-79b51c7f-67ef-495e-ae6d-839cd0e287a9; range_end:; response_count:1; response_revision:5188; }","duration":"251.597608ms","start":"2026-08-06T14:13:40.366831Z","end":"2026-08-06T14:13:40.618428Z","steps":["trace[375128998] 'range keys from in-memory index tree' (duration: 251.246491ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:13:40.618515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.828302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-06T14:13:40.618592Z","caller":"traceutil/trace.go:171","msg":"trace[1823740323] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:5188; }","duration":"195.967675ms","start":"2026-08-06T14:13:40.422608Z","end":"2026-08-06T14:13:40.618575Z","steps":["trace[1823740323] 'count revisions from in-memory index tree' (duration: 195.731201ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T14:13:41.100656Z","caller":"traceutil/trace.go:171","msg":"trace[310955724] transaction","detail":"{read_only:false; response_revision:5189; number_of_response:1; }","duration":"262.560547ms","start":"2026-08-06T14:13:40.83807Z","end":"2026-08-06T14:13:41.100631Z","steps":["trace[310955724] 'process raft request' (duration: 262.368804ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T14:13:41.209996Z","caller":"traceutil/trace.go:171","msg":"trace[1369814470] linearizableReadLoop","detail":"{readStateIndex:5388; appliedIndex:5382; }","duration":"217.905386ms","start":"2026-08-06T14:13:40.992061Z","end":"2026-08-06T14:13:41.209967Z","steps":["trace[1369814470] 'read index received' (duration: 108.314842ms)","trace[1369814470] 'applied index is now lower than readState.Index' (duration: 109.589444ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T14:13:41.21027Z","caller":"traceutil/trace.go:171","msg":"trace[1435248627] transaction","detail":"{read_only:false; response_revision:5193; number_of_response:1; }","duration":"284.844934ms","start":"2026-08-06T14:13:40.925386Z","end":"2026-08-06T14:13:41.210231Z","steps":["trace[1435248627] 'process raft request' (duration: 284.452998ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T14:13:41.210357Z","caller":"traceutil/trace.go:171","msg":"trace[160518749] transaction","detail":"{read_only:false; response_revision:5194; number_of_response:1; }","duration":"280.696902ms","start":"2026-08-06T14:13:40.929643Z","end":"2026-08-06T14:13:41.21034Z","steps":["trace[160518749] 'process raft request' (duration: 280.270515ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T14:13:41.210642Z","caller":"traceutil/trace.go:171","msg":"trace[332583610] transaction","detail":"{read_only:false; response_revision:5190; number_of_response:1; }","duration":"368.974365ms","start":"2026-08-06T14:13:40.841644Z","end":"2026-08-06T14:13:41.210618Z","steps":["trace[332583610] 'process raft request' (duration: 360.10376ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:13:41.210764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T14:13:40.841621Z","time spent":"369.068726ms","remote":"127.0.0.1:33802","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3748,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-06T14:13:41.211047Z","caller":"traceutil/trace.go:171","msg":"trace[277666830] transaction","detail":"{read_only:false; response_revision:5191; number_of_response:1; }","duration":"286.509655ms","start":"2026-08-06T14:13:40.924521Z","end":"2026-08-06T14:13:41.21103Z","steps":["trace[277666830] 'process raft request' (duration: 285.234912ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T14:13:41.211115Z","caller":"traceutil/trace.go:171","msg":"trace[560033657] transaction","detail":"{read_only:false; response_revision:5192; number_of_response:1; }","duration":"286.362702ms","start":"2026-08-06T14:13:40.924734Z","end":"2026-08-06T14:13:41.211097Z","steps":["trace[560033657] 'process raft request' (duration: 285.071819ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:13:41.210642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.566967ms","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-06T14:13:41.21127Z","caller":"traceutil/trace.go:171","msg":"trace[1834157738] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5194; }","duration":"219.24181ms","start":"2026-08-06T14:13:40.992003Z","end":"2026-08-06T14:13:41.211245Z","steps":["trace[1834157738] 'agreement among raft nodes before linearized reading' (duration: 218.475856ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T14:13:42.356301Z","caller":"traceutil/trace.go:171","msg":"trace[1374122624] transaction","detail":"{read_only:false; response_revision:5204; number_of_response:1; }","duration":"124.516282ms","start":"2026-08-06T14:13:42.231764Z","end":"2026-08-06T14:13:42.35628Z","steps":["trace[1374122624] 'process raft request' (duration: 124.41005ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T14:13:44.404817Z","caller":"traceutil/trace.go:171","msg":"trace[548933050] linearizableReadLoop","detail":"{readStateIndex:5426; appliedIndex:5425; }","duration":"337.610583ms","start":"2026-08-06T14:13:44.067175Z","end":"2026-08-06T14:13:44.404785Z","steps":["trace[548933050] 'read index received' (duration: 337.280297ms)","trace[548933050] 'applied index is now lower than readState.Index' (duration: 328.756µs)"],"step_count":2} {"level":"info","ts":"2026-08-06T14:13:44.404996Z","caller":"traceutil/trace.go:171","msg":"trace[3668609] transaction","detail":"{read_only:false; response_revision:5231; number_of_response:1; }","duration":"472.920841ms","start":"2026-08-06T14:13:43.931988Z","end":"2026-08-06T14:13:44.404908Z","steps":["trace[3668609] 'process raft request' (duration: 472.586175ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:13:44.405247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.938478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3316"} {"level":"warn","ts":"2026-08-06T14:13:44.405391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T14:13:43.931967Z","time spent":"473.288257ms","remote":"127.0.0.1:33692","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":997,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-06T14:13:44.405374Z","caller":"traceutil/trace.go:171","msg":"trace[827888483] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:5231; }","duration":"338.196023ms","start":"2026-08-06T14:13:44.067103Z","end":"2026-08-06T14:13:44.405299Z","steps":["trace[827888483] 'agreement among raft nodes before linearized reading' (duration: 337.864587ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:13:44.405638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T14:13:44.067075Z","time spent":"338.449237ms","remote":"127.0.0.1:41028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":1,"response size":3340,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" "} {"level":"warn","ts":"2026-08-06T14:13:44.406274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.502464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-rg82b.18c93cd53d25d902\" ","response":"range_response_count:1 size:906"} {"level":"info","ts":"2026-08-06T14:13:44.406817Z","caller":"traceutil/trace.go:171","msg":"trace[449007210] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-rg82b.18c93cd53d25d902; range_end:; response_count:1; response_revision:5231; }","duration":"224.042983ms","start":"2026-08-06T14:13:44.18275Z","end":"2026-08-06T14:13:44.406793Z","steps":["trace[449007210] 'agreement among raft nodes before linearized reading' (duration: 223.28479ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T14:13:48.591381Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2838} {"level":"info","ts":"2026-08-06T14:13:48.681712Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2838,"took":"87.013541ms","hash":625837120,"current-db-size-bytes":52375552,"current-db-size":"52 MB","current-db-size-in-use-bytes":27205632,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-08-06T14:13:48.681769Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":625837120,"revision":2838,"compact-revision":-1} {"level":"warn","ts":"2026-08-06T14:13:55.928949Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2802822090538476720,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-06T14:13:55.960436Z","caller":"traceutil/trace.go:171","msg":"trace[846000753] linearizableReadLoop","detail":"{readStateIndex:5586; appliedIndex:5585; }","duration":"531.942433ms","start":"2026-08-06T14:13:55.428464Z","end":"2026-08-06T14:13:55.960406Z","steps":["trace[846000753] 'read index received' (duration: 531.708999ms)","trace[846000753] 'applied index is now lower than readState.Index' (duration: 232.174µs)"],"step_count":2} {"level":"info","ts":"2026-08-06T14:13:55.96052Z","caller":"traceutil/trace.go:171","msg":"trace[2023318740] transaction","detail":"{read_only:false; response_revision:5387; number_of_response:1; }","duration":"589.923595ms","start":"2026-08-06T14:13:55.370559Z","end":"2026-08-06T14:13:55.960483Z","steps":["trace[2023318740] 'process raft request' (duration: 589.61544ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:13:55.960682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T14:13:55.370543Z","time spent":"590.050857ms","remote":"127.0.0.1:33896","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-06T14:13:55.960719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"532.226398ms","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-06T14:13:55.96083Z","caller":"traceutil/trace.go:171","msg":"trace[1506610433] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5387; }","duration":"532.39186ms","start":"2026-08-06T14:13:55.428418Z","end":"2026-08-06T14:13:55.96081Z","steps":["trace[1506610433] 'agreement among raft nodes before linearized reading' (duration: 532.094905ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:13:55.960886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T14:13:55.428394Z","time spent":"532.481322ms","remote":"127.0.0.1:33896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-06T14:13:55.960889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"522.130013ms","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-06T14:13:55.96077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"489.770034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-08-06T14:13:55.960936Z","caller":"traceutil/trace.go:171","msg":"trace[2029669063] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5387; }","duration":"522.196275ms","start":"2026-08-06T14:13:55.438728Z","end":"2026-08-06T14:13:55.960924Z","steps":["trace[2029669063] 'agreement among raft nodes before linearized reading' (duration: 522.076903ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T14:13:55.960963Z","caller":"traceutil/trace.go:171","msg":"trace[1001392475] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5387; }","duration":"489.953487ms","start":"2026-08-06T14:13:55.470986Z","end":"2026-08-06T14:13:55.960939Z","steps":["trace[1001392475] 'agreement among raft nodes before linearized reading' (duration: 489.669922ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:13:55.960981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T14:13:55.438717Z","time spent":"522.256486ms","remote":"127.0.0.1:33896","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-06T14:13:55.960956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"529.670494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-08-06T14:13:55.961039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T14:13:55.470937Z","time spent":"490.081019ms","remote":"127.0.0.1:33896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-08-06T14:13:55.961102Z","caller":"traceutil/trace.go:171","msg":"trace[163580736] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5387; }","duration":"529.905607ms","start":"2026-08-06T14:13:55.431157Z","end":"2026-08-06T14:13:55.961063Z","steps":["trace[163580736] 'agreement among raft nodes before linearized reading' (duration: 529.50131ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:13:55.961165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T14:13:55.431142Z","time spent":"530.009779ms","remote":"127.0.0.1:33896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-06T14:13:56.478096Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2802822090538476723,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-06T14:13:56.732615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.094694ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-06T14:13:56.733247Z","caller":"traceutil/trace.go:171","msg":"trace[1778017360] transaction","detail":"{read_only:false; response_revision:5388; number_of_response:1; }","duration":"767.78513ms","start":"2026-08-06T14:13:55.965439Z","end":"2026-08-06T14:13:56.733224Z","steps":["trace[1778017360] 'process raft request' (duration: 639.977233ms)","trace[1778017360] 'compare' (duration: 126.696718ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T14:13:56.733477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T14:13:55.965405Z","time spent":"768.037824ms","remote":"127.0.0.1:33896","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-06T14:13:56.770759Z","caller":"traceutil/trace.go:171","msg":"trace[1522959490] transaction","detail":"{read_only:false; response_revision:5390; number_of_response:1; }","duration":"803.878793ms","start":"2026-08-06T14:13:55.966842Z","end":"2026-08-06T14:13:56.77072Z","steps":["trace[1522959490] 'process raft request' (duration: 803.589968ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T14:13:56.770824Z","caller":"traceutil/trace.go:171","msg":"trace[318638278] linearizableReadLoop","detail":"{readStateIndex:5591; appliedIndex:5586; }","duration":"793.380831ms","start":"2026-08-06T14:13:55.977415Z","end":"2026-08-06T14:13:56.770796Z","steps":["trace[318638278] 'read index received' (duration: 628.086286ms)","trace[318638278] 'applied index is now lower than readState.Index' (duration: 165.293155ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T14:13:56.770925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T14:13:55.966817Z","time spent":"804.028596ms","remote":"127.0.0.1:33896","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-06T14:13:56.771058Z","caller":"traceutil/trace.go:171","msg":"trace[25953802] transaction","detail":"{read_only:false; response_revision:5392; number_of_response:1; }","duration":"676.113005ms","start":"2026-08-06T14:13:56.094934Z","end":"2026-08-06T14:13:56.771047Z","steps":["trace[25953802] 'process raft request' (duration: 675.78703ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:13:56.77111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T14:13:56.094897Z","time spent":"676.184217ms","remote":"127.0.0.1:33786","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11858,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-06T14:13:56.771282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"793.819558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:13290"} {"level":"info","ts":"2026-08-06T14:13:56.771382Z","caller":"traceutil/trace.go:171","msg":"trace[1460860476] range","detail":"{range_begin:/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:5392; }","duration":"793.95406ms","start":"2026-08-06T14:13:55.977405Z","end":"2026-08-06T14:13:56.771359Z","steps":["trace[1460860476] 'agreement among raft nodes before linearized reading' (duration: 793.642185ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:13:56.771425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T14:13:55.977361Z","time spent":"794.054612ms","remote":"127.0.0.1:33802","response type":"/etcdserverpb.KV/Range","request count":0,"request size":77,"response count":1,"response size":13314,"request content":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" "} {"level":"info","ts":"2026-08-06T14:13:56.772383Z","caller":"traceutil/trace.go:171","msg":"trace[1775433943] transaction","detail":"{read_only:false; response_revision:5391; number_of_response:1; }","duration":"803.294942ms","start":"2026-08-06T14:13:55.967532Z","end":"2026-08-06T14:13:56.770827Z","steps":["trace[1775433943] 'process raft request' (duration: 803.107919ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T14:13:56.773789Z","caller":"traceutil/trace.go:171","msg":"trace[765028256] transaction","detail":"{read_only:false; response_revision:5389; number_of_response:1; }","duration":"803.640608ms","start":"2026-08-06T14:13:55.966832Z","end":"2026-08-06T14:13:56.770473Z","steps":["trace[765028256] 'process raft request' (duration: 803.487636ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:13:56.773883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T14:13:55.966812Z","time spent":"807.024507ms","remote":"127.0.0.1:33896","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-06T14:13:56.774085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T14:13:55.967499Z","time spent":"806.206243ms","remote":"127.0.0.1:33896","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-06T14:13:56.962712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.431155ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-08-06T14:13:56.962843Z","caller":"traceutil/trace.go:171","msg":"trace[1225536115] linearizableReadLoop","detail":"{readStateIndex:5592; appliedIndex:5591; }","duration":"191.927863ms","start":"2026-08-06T14:13:56.770894Z","end":"2026-08-06T14:13:56.962821Z","steps":["trace[1225536115] 'read index received' (duration: 22.400036ms)","trace[1225536115] 'applied index is now lower than readState.Index' (duration: 169.526017ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T14:13:56.963029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"974.497479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T14:13:56.963073Z","caller":"traceutil/trace.go:171","msg":"trace[80714043] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:5392; }","duration":"974.594861ms","start":"2026-08-06T14:13:55.988464Z","end":"2026-08-06T14:13:56.963059Z","steps":["trace[80714043] 'agreement among raft nodes before linearized reading' (duration: 974.486229ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:13:56.963074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"652.792213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-06T14:13:56.963122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T14:13:55.988452Z","time spent":"974.659731ms","remote":"127.0.0.1:33868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":29,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"info","ts":"2026-08-06T14:13:56.963165Z","caller":"traceutil/trace.go:171","msg":"trace[1877189484] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5392; }","duration":"652.934596ms","start":"2026-08-06T14:13:56.310208Z","end":"2026-08-06T14:13:56.963142Z","steps":["trace[1877189484] 'agreement among raft nodes before linearized reading' (duration: 652.791033ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:13:56.963128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"636.911759ms","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:5"} {"level":"warn","ts":"2026-08-06T14:13:56.963213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.305689ms","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-06T14:13:56.96329Z","caller":"traceutil/trace.go:171","msg":"trace[741047152] 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:5392; }","duration":"637.121543ms","start":"2026-08-06T14:13:56.326145Z","end":"2026-08-06T14:13:56.963266Z","steps":["trace[741047152] 'agreement among raft nodes before linearized reading' (duration: 636.868809ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:13:56.963412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"891.103008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-08-06T14:13:56.963484Z","caller":"traceutil/trace.go:171","msg":"trace[1869766568] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5392; }","duration":"891.22594ms","start":"2026-08-06T14:13:56.072241Z","end":"2026-08-06T14:13:56.963467Z","steps":["trace[1869766568] 'agreement among raft nodes before linearized reading' (duration: 890.999877ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T14:13:56.963302Z","caller":"traceutil/trace.go:171","msg":"trace[1995328101] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5392; }","duration":"365.449481ms","start":"2026-08-06T14:13:56.597833Z","end":"2026-08-06T14:13:56.963283Z","steps":["trace[1995328101] 'agreement among raft nodes before linearized reading' (duration: 365.245958ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:13:56.963535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T14:13:56.072213Z","time spent":"891.310681ms","remote":"127.0.0.1:33772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1889,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"warn","ts":"2026-08-06T14:13:56.96356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T14:13:56.597807Z","time spent":"365.737717ms","remote":"127.0.0.1:33802","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12240,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-08-06T14:13:56.963217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T14:13:56.310173Z","time spent":"653.034857ms","remote":"127.0.0.1:33634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-06T14:13:56.963959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T14:13:56.326137Z","time spent":"637.708953ms","remote":"127.0.0.1:40080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true "} {"level":"info","ts":"2026-08-06T14:13:56.976969Z","caller":"traceutil/trace.go:171","msg":"trace[2080548444] transaction","detail":"{read_only:false; response_revision:5393; number_of_response:1; }","duration":"190.627553ms","start":"2026-08-06T14:13:56.786326Z","end":"2026-08-06T14:13:56.976954Z","steps":["trace[2080548444] 'process raft request' (duration: 189.938821ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T14:13:57.177785Z","caller":"traceutil/trace.go:171","msg":"trace[1286865770] linearizableReadLoop","detail":"{readStateIndex:5594; appliedIndex:5593; }","duration":"133.672857ms","start":"2026-08-06T14:13:57.044087Z","end":"2026-08-06T14:13:57.177759Z","steps":["trace[1286865770] 'read index received' (duration: 76.931648ms)","trace[1286865770] 'applied index is now lower than readState.Index' (duration: 56.740079ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T14:13:57.177936Z","caller":"traceutil/trace.go:171","msg":"trace[670958617] transaction","detail":"{read_only:false; response_revision:5394; number_of_response:1; }","duration":"181.397489ms","start":"2026-08-06T14:13:56.996526Z","end":"2026-08-06T14:13:57.177923Z","steps":["trace[670958617] 'process raft request' (duration: 124.551958ms)","trace[670958617] 'compare' (duration: 56.565526ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T14:13:57.178129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.033953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T14:13:57.178213Z","caller":"traceutil/trace.go:171","msg":"trace[1138088535] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:5394; }","duration":"134.160486ms","start":"2026-08-06T14:13:57.044038Z","end":"2026-08-06T14:13:57.178199Z","steps":["trace[1138088535] 'agreement among raft nodes before linearized reading' (duration: 134.032173ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T14:13:57.303372Z","caller":"traceutil/trace.go:171","msg":"trace[104946628] transaction","detail":"{read_only:false; response_revision:5396; number_of_response:1; }","duration":"111.547376ms","start":"2026-08-06T14:13:57.191799Z","end":"2026-08-06T14:13:57.303347Z","steps":["trace[104946628] 'process raft request' (duration: 94.625014ms)","trace[104946628] 'compare' (duration: 16.681308ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T14:13:57.303408Z","caller":"traceutil/trace.go:171","msg":"trace[1357518731] transaction","detail":"{read_only:false; response_revision:5397; number_of_response:1; }","duration":"111.217379ms","start":"2026-08-06T14:13:57.192169Z","end":"2026-08-06T14:13:57.303386Z","steps":["trace[1357518731] 'process raft request' (duration: 111.050166ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T14:13:57.303577Z","caller":"traceutil/trace.go:171","msg":"trace[1794548900] linearizableReadLoop","detail":"{readStateIndex:5596; appliedIndex:5594; }","duration":"111.685717ms","start":"2026-08-06T14:13:57.191874Z","end":"2026-08-06T14:13:57.303559Z","steps":["trace[1794548900] 'read index received' (duration: 63.72515ms)","trace[1794548900] 'applied index is now lower than readState.Index' (duration: 47.959607ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T14:13:57.303515Z","caller":"traceutil/trace.go:171","msg":"trace[353598663] transaction","detail":"{read_only:false; response_revision:5398; number_of_response:1; }","duration":"110.874813ms","start":"2026-08-06T14:13:57.192616Z","end":"2026-08-06T14:13:57.303491Z","steps":["trace[353598663] 'process raft request' (duration: 110.654849ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:13:57.303743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.84573ms","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-06T14:13:57.303785Z","caller":"traceutil/trace.go:171","msg":"trace[329335394] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5399; }","duration":"111.927912ms","start":"2026-08-06T14:13:57.191847Z","end":"2026-08-06T14:13:57.303775Z","steps":["trace[329335394] 'agreement among raft nodes before linearized reading' (duration: 111.775069ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T14:13:57.304018Z","caller":"traceutil/trace.go:171","msg":"trace[1500132531] transaction","detail":"{read_only:false; response_revision:5399; number_of_response:1; }","duration":"107.317802ms","start":"2026-08-06T14:13:57.196687Z","end":"2026-08-06T14:13:57.304005Z","steps":["trace[1500132531] 'process raft request' (duration: 106.64294ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T14:13:57.558841Z","caller":"traceutil/trace.go:171","msg":"trace[344969872] linearizableReadLoop","detail":"{readStateIndex:5604; appliedIndex:5603; }","duration":"168.256603ms","start":"2026-08-06T14:13:57.39056Z","end":"2026-08-06T14:13:57.558817Z","steps":["trace[344969872] 'read index received' (duration: 167.982848ms)","trace[344969872] 'applied index is now lower than readState.Index' (duration: 272.615µs)"],"step_count":2} {"level":"info","ts":"2026-08-06T14:13:57.558878Z","caller":"traceutil/trace.go:171","msg":"trace[137645380] transaction","detail":"{read_only:false; response_revision:5404; number_of_response:1; }","duration":"205.858191ms","start":"2026-08-06T14:13:57.352995Z","end":"2026-08-06T14:13:57.558853Z","steps":["trace[137645380] 'process raft request' (duration: 205.637787ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:13:57.559023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.430386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-06T14:13:57.55906Z","caller":"traceutil/trace.go:171","msg":"trace[387525699] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:5404; }","duration":"168.557268ms","start":"2026-08-06T14:13:57.390491Z","end":"2026-08-06T14:13:57.559048Z","steps":["trace[387525699] 'agreement among raft nodes before linearized reading' (duration: 168.423256ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T14:14:32.023538Z","caller":"traceutil/trace.go:171","msg":"trace[1488604223] transaction","detail":"{read_only:false; response_revision:5699; number_of_response:1; }","duration":"155.891684ms","start":"2026-08-06T14:14:31.867623Z","end":"2026-08-06T14:14:32.023515Z","steps":["trace[1488604223] 'process raft request' (duration: 119.407347ms)","trace[1488604223] 'compare' (duration: 36.184512ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T14:14:35.606762Z","caller":"traceutil/trace.go:171","msg":"trace[939331478] transaction","detail":"{read_only:false; response_revision:5718; number_of_response:1; }","duration":"162.209755ms","start":"2026-08-06T14:14:35.44452Z","end":"2026-08-06T14:14:35.606729Z","steps":["trace[939331478] 'process raft request' (duration: 161.8879ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T14:14:35.607387Z","caller":"traceutil/trace.go:171","msg":"trace[1061290660] transaction","detail":"{read_only:false; response_revision:5719; number_of_response:1; }","duration":"162.747484ms","start":"2026-08-06T14:14:35.444622Z","end":"2026-08-06T14:14:35.60737Z","steps":["trace[1061290660] 'process raft request' (duration: 162.033832ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T14:14:35.61008Z","caller":"traceutil/trace.go:171","msg":"trace[1771697062] transaction","detail":"{read_only:false; response_revision:5720; number_of_response:1; }","duration":"153.108681ms","start":"2026-08-06T14:14:35.456951Z","end":"2026-08-06T14:14:35.610059Z","steps":["trace[1771697062] 'process raft request' (duration: 150.296504ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T14:17:38.079506Z","caller":"traceutil/trace.go:171","msg":"trace[292796070] transaction","detail":"{read_only:false; response_revision:6978; number_of_response:1; }","duration":"171.659969ms","start":"2026-08-06T14:17:37.907816Z","end":"2026-08-06T14:17:38.079476Z","steps":["trace[292796070] 'process raft request' (duration: 171.482744ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T14:17:38.098997Z","caller":"traceutil/trace.go:171","msg":"trace[1244441450] linearizableReadLoop","detail":"{readStateIndex:7230; appliedIndex:7228; }","duration":"185.673367ms","start":"2026-08-06T14:17:37.913315Z","end":"2026-08-06T14:17:38.098989Z","steps":["trace[1244441450] 'read index received' (duration: 165.928899ms)","trace[1244441450] 'applied index is now lower than readState.Index' (duration: 19.743688ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T14:17:38.099077Z","caller":"traceutil/trace.go:171","msg":"trace[403930657] transaction","detail":"{read_only:false; response_revision:6979; number_of_response:1; }","duration":"189.531198ms","start":"2026-08-06T14:17:37.909516Z","end":"2026-08-06T14:17:38.099047Z","steps":["trace[403930657] 'process raft request' (duration: 189.422565ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:17:38.099254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.975996ms","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-06T14:17:38.099302Z","caller":"traceutil/trace.go:171","msg":"trace[638884461] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:6979; }","duration":"185.040268ms","start":"2026-08-06T14:17:37.914254Z","end":"2026-08-06T14:17:38.099295Z","steps":["trace[638884461] 'agreement among raft nodes before linearized reading' (duration: 184.855072ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:17:38.099687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.349439ms","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-06T14:17:38.099748Z","caller":"traceutil/trace.go:171","msg":"trace[240326541] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:6979; }","duration":"186.438232ms","start":"2026-08-06T14:17:37.913296Z","end":"2026-08-06T14:17:38.099734Z","steps":["trace[240326541] 'agreement among raft nodes before linearized reading' (duration: 186.246015ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T14:17:42.245285Z","caller":"traceutil/trace.go:171","msg":"trace[938144120] linearizableReadLoop","detail":"{readStateIndex:7250; appliedIndex:7249; }","duration":"135.07876ms","start":"2026-08-06T14:17:42.110182Z","end":"2026-08-06T14:17:42.24526Z","steps":["trace[938144120] 'read index received' (duration: 134.986407ms)","trace[938144120] 'applied index is now lower than readState.Index' (duration: 91.243µs)"],"step_count":2} {"level":"info","ts":"2026-08-06T14:17:42.245387Z","caller":"traceutil/trace.go:171","msg":"trace[1914875570] transaction","detail":"{read_only:false; response_revision:6998; number_of_response:1; }","duration":"151.824141ms","start":"2026-08-06T14:17:42.093498Z","end":"2026-08-06T14:17:42.245323Z","steps":["trace[1914875570] 'process raft request' (duration: 151.616625ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:17:42.245513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.303847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-08-06T14:17:42.245557Z","caller":"traceutil/trace.go:171","msg":"trace[1259684678] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:6998; }","duration":"135.37794ms","start":"2026-08-06T14:17:42.110167Z","end":"2026-08-06T14:17:42.245545Z","steps":["trace[1259684678] 'agreement among raft nodes before linearized reading' (duration: 135.216615ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:17:42.31622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.24033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-08-06T14:17:42.316246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.574229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-06T14:17:42.316296Z","caller":"traceutil/trace.go:171","msg":"trace[868456234] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:6999; }","duration":"193.337592ms","start":"2026-08-06T14:17:42.122941Z","end":"2026-08-06T14:17:42.316278Z","steps":["trace[868456234] 'agreement among raft nodes before linearized reading' (duration: 192.998642ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T14:17:42.316354Z","caller":"traceutil/trace.go:171","msg":"trace[1410087409] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:6999; }","duration":"192.676382ms","start":"2026-08-06T14:17:42.123636Z","end":"2026-08-06T14:17:42.316312Z","steps":["trace[1410087409] 'agreement among raft nodes before linearized reading' (duration: 192.321181ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:17:42.316489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.26448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-06T14:17:42.316558Z","caller":"traceutil/trace.go:171","msg":"trace[1318941484] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:6999; }","duration":"111.375143ms","start":"2026-08-06T14:17:42.205169Z","end":"2026-08-06T14:17:42.316544Z","steps":["trace[1318941484] 'agreement among raft nodes before linearized reading' (duration: 110.774985ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T14:17:42.520012Z","caller":"traceutil/trace.go:171","msg":"trace[1987312168] transaction","detail":"{read_only:false; response_revision:7001; number_of_response:1; }","duration":"199.801063ms","start":"2026-08-06T14:17:42.320184Z","end":"2026-08-06T14:17:42.519985Z","steps":["trace[1987312168] 'process raft request' (duration: 170.07745ms)","trace[1987312168] 'compare' (duration: 29.589919ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T14:17:42.524573Z","caller":"traceutil/trace.go:171","msg":"trace[1812295977] transaction","detail":"{read_only:false; response_revision:7002; number_of_response:1; }","duration":"203.475318ms","start":"2026-08-06T14:17:42.321082Z","end":"2026-08-06T14:17:42.524557Z","steps":["trace[1812295977] 'process raft request' (duration: 203.376245ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T14:17:42.524714Z","caller":"traceutil/trace.go:171","msg":"trace[736865672] transaction","detail":"{read_only:false; response_revision:7003; number_of_response:1; }","duration":"144.236755ms","start":"2026-08-06T14:17:42.380471Z","end":"2026-08-06T14:17:42.524707Z","steps":["trace[736865672] 'process raft request' (duration: 144.045679ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T14:17:42.524973Z","caller":"traceutil/trace.go:171","msg":"trace[1076561825] transaction","detail":"{read_only:false; response_revision:7004; number_of_response:1; }","duration":"128.756664ms","start":"2026-08-06T14:17:42.396206Z","end":"2026-08-06T14:17:42.524962Z","steps":["trace[1076561825] 'process raft request' (duration: 128.469665ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T14:17:42.52508Z","caller":"traceutil/trace.go:171","msg":"trace[661525915] transaction","detail":"{read_only:false; response_revision:7005; number_of_response:1; }","duration":"125.108719ms","start":"2026-08-06T14:17:42.399948Z","end":"2026-08-06T14:17:42.525056Z","steps":["trace[661525915] 'process raft request' (duration: 124.976545ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T14:17:45.278937Z","caller":"traceutil/trace.go:171","msg":"trace[403938995] transaction","detail":"{read_only:false; response_revision:7020; number_of_response:1; }","duration":"109.019893ms","start":"2026-08-06T14:17:45.169888Z","end":"2026-08-06T14:17:45.278908Z","steps":["trace[403938995] 'process raft request' (duration: 108.849038ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T14:18:00.515443Z","caller":"traceutil/trace.go:171","msg":"trace[159861576] transaction","detail":"{read_only:false; response_revision:7152; number_of_response:1; }","duration":"123.190038ms","start":"2026-08-06T14:18:00.392234Z","end":"2026-08-06T14:18:00.515424Z","steps":["trace[159861576] 'process raft request' (duration: 79.358036ms)","trace[159861576] 'compare' (duration: 43.377348ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T14:18:08.996338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.311809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-06T14:18:08.996667Z","caller":"traceutil/trace.go:171","msg":"trace[2083281887] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:7199; }","duration":"152.69241ms","start":"2026-08-06T14:18:08.843956Z","end":"2026-08-06T14:18:08.996648Z","steps":["trace[2083281887] 'count revisions from in-memory index tree' (duration: 152.242316ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T14:18:48.599572Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5316} {"level":"info","ts":"2026-08-06T14:18:48.690361Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5316,"took":"89.215182ms","hash":2106170383,"current-db-size-bytes":52375552,"current-db-size":"52 MB","current-db-size-in-use-bytes":19681280,"current-db-size-in-use":"20 MB"} {"level":"info","ts":"2026-08-06T14:18:48.690527Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2106170383,"revision":5316,"compact-revision":2838} {"level":"info","ts":"2026-08-06T14:19:44.993524Z","caller":"traceutil/trace.go:171","msg":"trace[1278609782] transaction","detail":"{read_only:false; response_revision:7704; number_of_response:1; }","duration":"124.792533ms","start":"2026-08-06T14:19:44.868701Z","end":"2026-08-06T14:19:44.993493Z","steps":["trace[1278609782] 'process raft request' (duration: 124.582448ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T14:19:45.386776Z","caller":"traceutil/trace.go:171","msg":"trace[1677221164] linearizableReadLoop","detail":"{readStateIndex:7992; appliedIndex:7991; }","duration":"138.713212ms","start":"2026-08-06T14:19:45.248039Z","end":"2026-08-06T14:19:45.386752Z","steps":["trace[1677221164] 'read index received' (duration: 115.835977ms)","trace[1677221164] 'applied index is now lower than readState.Index' (duration: 22.873675ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T14:19:45.386899Z","caller":"traceutil/trace.go:171","msg":"trace[2053858931] transaction","detail":"{read_only:false; response_revision:7710; number_of_response:1; }","duration":"139.506292ms","start":"2026-08-06T14:19:45.24738Z","end":"2026-08-06T14:19:45.386886Z","steps":["trace[2053858931] 'process raft request' (duration: 116.561107ms)","trace[2053858931] 'compare' (duration: 22.705839ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T14:19:45.386972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.941674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-06T14:19:45.387023Z","caller":"traceutil/trace.go:171","msg":"trace[1350643330] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:7710; }","duration":"125.056767ms","start":"2026-08-06T14:19:45.261954Z","end":"2026-08-06T14:19:45.387011Z","steps":["trace[1350643330] 'agreement among raft nodes before linearized reading' (duration: 124.965625ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:19:45.387226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.184714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-06T14:19:45.38725Z","caller":"traceutil/trace.go:171","msg":"trace[1939146970] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:7710; }","duration":"139.209184ms","start":"2026-08-06T14:19:45.248034Z","end":"2026-08-06T14:19:45.387244Z","steps":["trace[1939146970] 'agreement among raft nodes before linearized reading' (duration: 139.109932ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T14:21:01.938073Z","caller":"traceutil/trace.go:171","msg":"trace[675440922] linearizableReadLoop","detail":"{readStateIndex:8394; appliedIndex:8393; }","duration":"135.709623ms","start":"2026-08-06T14:21:01.802332Z","end":"2026-08-06T14:21:01.938041Z","steps":["trace[675440922] 'read index received' (duration: 36.494319ms)","trace[675440922] 'applied index is now lower than readState.Index' (duration: 99.213894ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T14:21:01.938122Z","caller":"traceutil/trace.go:171","msg":"trace[118240495] transaction","detail":"{read_only:false; response_revision:8096; number_of_response:1; }","duration":"206.968051ms","start":"2026-08-06T14:21:01.731126Z","end":"2026-08-06T14:21:01.938094Z","steps":["trace[118240495] 'process raft request' (duration: 107.813049ms)","trace[118240495] 'compare' (duration: 98.981798ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T14:21:01.938342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.999431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/kube-prometheus-stack-nodes\" ","response":"range_response_count:1 size:15440"} {"level":"info","ts":"2026-08-06T14:21:01.938378Z","caller":"traceutil/trace.go:171","msg":"trace[1745831267] range","detail":"{range_begin:/registry/configmaps/monitoring/kube-prometheus-stack-nodes; range_end:; response_count:1; response_revision:8096; }","duration":"136.099663ms","start":"2026-08-06T14:21:01.802266Z","end":"2026-08-06T14:21:01.938366Z","steps":["trace[1745831267] 'agreement among raft nodes before linearized reading' (duration: 135.935909ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:21:01.938431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.822365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/kube-prometheus-stack-node-rsrc-use\" ","response":"range_response_count:1 size:14617"} {"level":"info","ts":"2026-08-06T14:21:01.938472Z","caller":"traceutil/trace.go:171","msg":"trace[606345104] range","detail":"{range_begin:/registry/configmaps/monitoring/kube-prometheus-stack-node-rsrc-use; range_end:; response_count:1; response_revision:8096; }","duration":"127.871056ms","start":"2026-08-06T14:21:01.810583Z","end":"2026-08-06T14:21:01.938454Z","steps":["trace[606345104] 'agreement among raft nodes before linearized reading' (duration: 127.724533ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T14:21:01.938478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.776997ms","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-06T14:21:01.938549Z","caller":"traceutil/trace.go:171","msg":"trace[1026394127] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8096; }","duration":"107.89113ms","start":"2026-08-06T14:21:01.830641Z","end":"2026-08-06T14:21:01.938532Z","steps":["trace[1026394127] 'agreement among raft nodes before linearized reading' (duration: 107.709476ms)"],"step_count":1}