{"level":"warn","ts":"2026-07-30T23:40:35.529988Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-30T23:40:35.530101Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.61: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.204.45.61:2380","--initial-cluster=instance=https://199.204.45.61:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.61:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.61: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-07-30T23:40:35.530202Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-30T23:40:35.530215Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.61:2380"]} {"level":"info","ts":"2026-07-30T23:40:35.530241Z","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-07-30T23:40:35.531971Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.61:2379"]} {"level":"info","ts":"2026-07-30T23:40:35.532074Z","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.204.45.61:2380"],"listen-peer-urls":["https://199.204.45.61:2380"],"advertise-client-urls":["https://199.204.45.61:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.61:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.61: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-07-30T23:40:35.536588Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.275973ms"} {"level":"info","ts":"2026-07-30T23:40:35.54708Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"ca256638ea38eb43","cluster-id":"dfd7ac400ae20102"} {"level":"info","ts":"2026-07-30T23:40:35.547305Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca256638ea38eb43 switched to configuration voters=()"} {"level":"info","ts":"2026-07-30T23:40:35.547346Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca256638ea38eb43 became follower at term 0"} {"level":"info","ts":"2026-07-30T23:40:35.547362Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft ca256638ea38eb43 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-30T23:40:35.54738Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca256638ea38eb43 became follower at term 1"} {"level":"info","ts":"2026-07-30T23:40:35.547436Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca256638ea38eb43 switched to configuration voters=(14566160964433537859)"} {"level":"warn","ts":"2026-07-30T23:40:35.55438Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-30T23:40:35.556224Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-30T23:40:35.557535Z","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-07-30T23:40:35.564287Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"ca256638ea38eb43","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-30T23:40:35.564732Z","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-07-30T23:40:35.564829Z","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-07-30T23:40:35.564842Z","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-07-30T23:40:35.571994Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"ca256638ea38eb43","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-30T23:40:35.57234Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca256638ea38eb43 switched to configuration voters=(14566160964433537859)"} {"level":"info","ts":"2026-07-30T23:40:35.572533Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"dfd7ac400ae20102","local-member-id":"ca256638ea38eb43","added-peer-id":"ca256638ea38eb43","added-peer-peer-urls":["https://199.204.45.61:2380"]} {"level":"info","ts":"2026-07-30T23:40:35.576464Z","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-07-30T23:40:35.576707Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.61:2380"} {"level":"info","ts":"2026-07-30T23:40:35.576732Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.61:2380"} {"level":"info","ts":"2026-07-30T23:40:35.57698Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"ca256638ea38eb43","initial-advertise-peer-urls":["https://199.204.45.61:2380"],"listen-peer-urls":["https://199.204.45.61:2380"],"advertise-client-urls":["https://199.204.45.61:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.61:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-30T23:40:35.577019Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-30T23:40:36.447996Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca256638ea38eb43 is starting a new election at term 1"} {"level":"info","ts":"2026-07-30T23:40:36.44806Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca256638ea38eb43 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-30T23:40:36.448093Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca256638ea38eb43 received MsgPreVoteResp from ca256638ea38eb43 at term 1"} {"level":"info","ts":"2026-07-30T23:40:36.44811Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca256638ea38eb43 became candidate at term 2"} {"level":"info","ts":"2026-07-30T23:40:36.448118Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca256638ea38eb43 received MsgVoteResp from ca256638ea38eb43 at term 2"} {"level":"info","ts":"2026-07-30T23:40:36.44813Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca256638ea38eb43 became leader at term 2"} {"level":"info","ts":"2026-07-30T23:40:36.448145Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: ca256638ea38eb43 elected leader ca256638ea38eb43 at term 2"} {"level":"info","ts":"2026-07-30T23:40:36.449369Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-30T23:40:36.45045Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-30T23:40:36.450455Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"ca256638ea38eb43","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.61:2379]}","request-path":"/0/members/ca256638ea38eb43/attributes","cluster-id":"dfd7ac400ae20102","publish-timeout":"7s"} {"level":"info","ts":"2026-07-30T23:40:36.450905Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-30T23:40:36.451227Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-30T23:40:36.45124Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-30T23:40:36.454971Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-30T23:40:36.455461Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.61:2379"} {"level":"info","ts":"2026-07-30T23:40:36.466476Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"dfd7ac400ae20102","local-member-id":"ca256638ea38eb43","cluster-version":"3.5"} {"level":"info","ts":"2026-07-30T23:40:36.466602Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-30T23:40:36.466636Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-30T23:40:39.263327Z","caller":"traceutil/trace.go:171","msg":"trace[1435874927] transaction","detail":"{read_only:false; response_revision:143; number_of_response:1; }","duration":"158.503112ms","start":"2026-07-30T23:40:39.104797Z","end":"2026-07-30T23:40:39.2633Z","steps":["trace[1435874927] 'process raft request' (duration: 153.78944ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:40:39.765065Z","caller":"traceutil/trace.go:171","msg":"trace[868063260] transaction","detail":"{read_only:false; response_revision:163; number_of_response:1; }","duration":"140.020941ms","start":"2026-07-30T23:40:39.625019Z","end":"2026-07-30T23:40:39.76504Z","steps":["trace[868063260] 'process raft request' (duration: 139.964019ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:40:39.765092Z","caller":"traceutil/trace.go:171","msg":"trace[433632227] transaction","detail":"{read_only:false; response_revision:162; number_of_response:1; }","duration":"142.507953ms","start":"2026-07-30T23:40:39.622553Z","end":"2026-07-30T23:40:39.765061Z","steps":["trace[433632227] 'process raft request' (duration: 66.703952ms)","trace[433632227] 'compare' (duration: 75.570021ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T23:40:40.743893Z","caller":"traceutil/trace.go:171","msg":"trace[101535658] transaction","detail":"{read_only:false; response_revision:220; number_of_response:1; }","duration":"117.021783ms","start":"2026-07-30T23:40:40.626847Z","end":"2026-07-30T23:40:40.743869Z","steps":["trace[101535658] 'process raft request' (duration: 58.327563ms)","trace[101535658] 'compare' (duration: 58.497851ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T23:40:56.571613Z","caller":"traceutil/trace.go:171","msg":"trace[1658719765] linearizableReadLoop","detail":"{readStateIndex:436; appliedIndex:435; }","duration":"404.819495ms","start":"2026-07-30T23:40:56.166771Z","end":"2026-07-30T23:40:56.57159Z","steps":["trace[1658719765] 'read index received' (duration: 404.640689ms)","trace[1658719765] 'applied index is now lower than readState.Index' (duration: 177.106µs)"],"step_count":2} {"level":"info","ts":"2026-07-30T23:40:56.571883Z","caller":"traceutil/trace.go:171","msg":"trace[1342231924] transaction","detail":"{read_only:false; response_revision:421; number_of_response:1; }","duration":"503.467562ms","start":"2026-07-30T23:40:56.068398Z","end":"2026-07-30T23:40:56.571865Z","steps":["trace[1342231924] 'process raft request' (duration: 503.075677ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:40:56.572658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:40:56.068382Z","time spent":"503.594076ms","remote":"127.0.0.1:60960","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-07-30T23:40:56.573104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.33149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-07-30T23:40:56.573193Z","caller":"traceutil/trace.go:171","msg":"trace[1481974283] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:421; }","duration":"406.460705ms","start":"2026-07-30T23:40:56.166713Z","end":"2026-07-30T23:40:56.573173Z","steps":["trace[1481974283] 'agreement among raft nodes before linearized reading' (duration: 406.30378ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:40:56.573238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:40:56.16669Z","time spent":"406.535087ms","remote":"127.0.0.1:60960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-30T23:40:56.784228Z","caller":"traceutil/trace.go:171","msg":"trace[526399042] transaction","detail":"{read_only:false; response_revision:422; number_of_response:1; }","duration":"206.231543ms","start":"2026-07-30T23:40:56.577977Z","end":"2026-07-30T23:40:56.784208Z","steps":["trace[526399042] 'process raft request' (duration: 206.112487ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:40:59.266255Z","caller":"traceutil/trace.go:171","msg":"trace[649878295] transaction","detail":"{read_only:false; response_revision:428; number_of_response:1; }","duration":"176.660268ms","start":"2026-07-30T23:40:59.089551Z","end":"2026-07-30T23:40:59.266211Z","steps":["trace[649878295] 'process raft request' (duration: 176.321745ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:41:14.915461Z","caller":"traceutil/trace.go:171","msg":"trace[1305763257] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"161.719955ms","start":"2026-07-30T23:41:14.753706Z","end":"2026-07-30T23:41:14.915426Z","steps":["trace[1305763257] 'process raft request' (duration: 161.439676ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:41:14.91567Z","caller":"traceutil/trace.go:171","msg":"trace[925806399] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"157.29121ms","start":"2026-07-30T23:41:14.758349Z","end":"2026-07-30T23:41:14.915641Z","steps":["trace[925806399] 'process raft request' (duration: 156.96869ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:41:14.915721Z","caller":"traceutil/trace.go:171","msg":"trace[453951376] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"161.855109ms","start":"2026-07-30T23:41:14.75384Z","end":"2026-07-30T23:41:14.915695Z","steps":["trace[453951376] 'process raft request' (duration: 161.437736ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:41:14.915768Z","caller":"traceutil/trace.go:171","msg":"trace[33788873] transaction","detail":"{read_only:false; number_of_response:0; response_revision:548; }","duration":"155.189876ms","start":"2026-07-30T23:41:14.760531Z","end":"2026-07-30T23:41:14.915721Z","steps":["trace[33788873] 'process raft request' (duration: 154.96682ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:41:14.915842Z","caller":"traceutil/trace.go:171","msg":"trace[2035184800] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"141.830602ms","start":"2026-07-30T23:41:14.774Z","end":"2026-07-30T23:41:14.91583Z","steps":["trace[2035184800] 'process raft request' (duration: 141.573664ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:41:14.915856Z","caller":"traceutil/trace.go:171","msg":"trace[1245022088] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"133.372475ms","start":"2026-07-30T23:41:14.782464Z","end":"2026-07-30T23:41:14.915836Z","steps":["trace[1245022088] 'process raft request' (duration: 133.20033ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:41:14.915896Z","caller":"traceutil/trace.go:171","msg":"trace[1066977033] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"149.452612ms","start":"2026-07-30T23:41:14.766413Z","end":"2026-07-30T23:41:14.915865Z","steps":["trace[1066977033] 'process raft request' (duration: 149.107761ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:41:14.916006Z","caller":"traceutil/trace.go:171","msg":"trace[429979447] linearizableReadLoop","detail":"{readStateIndex:570; appliedIndex:568; }","duration":"158.088395ms","start":"2026-07-30T23:41:14.757889Z","end":"2026-07-30T23:41:14.915978Z","steps":["trace[429979447] 'read index received' (duration: 69.473757ms)","trace[429979447] 'applied index is now lower than readState.Index' (duration: 88.613308ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T23:41:14.916012Z","caller":"traceutil/trace.go:171","msg":"trace[681051977] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"157.377773ms","start":"2026-07-30T23:41:14.75839Z","end":"2026-07-30T23:41:14.915768Z","steps":["trace[681051977] 'process raft request' (duration: 157.064743ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:41:14.916167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.25577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/v1beta1.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:1010"} {"level":"info","ts":"2026-07-30T23:41:14.916221Z","caller":"traceutil/trace.go:171","msg":"trace[391938687] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/v1beta1.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:551; }","duration":"158.332502ms","start":"2026-07-30T23:41:14.757871Z","end":"2026-07-30T23:41:14.916203Z","steps":["trace[391938687] 'agreement among raft nodes before linearized reading' (duration: 158.170127ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:41:15.038541Z","caller":"traceutil/trace.go:171","msg":"trace[1281084484] linearizableReadLoop","detail":"{readStateIndex:577; appliedIndex:576; }","duration":"122.464504ms","start":"2026-07-30T23:41:14.916025Z","end":"2026-07-30T23:41:15.03849Z","steps":["trace[1281084484] 'read index received' (duration: 91.683191ms)","trace[1281084484] 'applied index is now lower than readState.Index' (duration: 30.780273ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T23:41:15.038636Z","caller":"traceutil/trace.go:171","msg":"trace[1783279031] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"170.580022ms","start":"2026-07-30T23:41:14.868015Z","end":"2026-07-30T23:41:15.038595Z","steps":["trace[1783279031] 'process raft request' (duration: 139.904952ms)","trace[1783279031] 'compare' (duration: 30.276879ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T23:41:15.038806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.286197ms","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-07-30T23:41:15.038887Z","caller":"traceutil/trace.go:171","msg":"trace[892407669] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:552; }","duration":"210.403271ms","start":"2026-07-30T23:41:14.828431Z","end":"2026-07-30T23:41:15.038834Z","steps":["trace[892407669] 'agreement among raft nodes before linearized reading' (duration: 210.228945ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:41:15.0437Z","caller":"traceutil/trace.go:171","msg":"trace[86871976] transaction","detail":"{read_only:false; number_of_response:0; response_revision:552; }","duration":"103.472315ms","start":"2026-07-30T23:41:14.940212Z","end":"2026-07-30T23:41:15.043684Z","steps":["trace[86871976] 'process raft request' (duration: 100.959921ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:41:15.044283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.321719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/v1.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:995"} {"level":"info","ts":"2026-07-30T23:41:15.044331Z","caller":"traceutil/trace.go:171","msg":"trace[233218135] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/v1.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:555; }","duration":"126.411751ms","start":"2026-07-30T23:41:14.917905Z","end":"2026-07-30T23:41:15.044317Z","steps":["trace[233218135] 'agreement among raft nodes before linearized reading' (duration: 126.146493ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:41:15.044728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.446525ms","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-07-30T23:41:15.044794Z","caller":"traceutil/trace.go:171","msg":"trace[865736720] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:555; }","duration":"102.542338ms","start":"2026-07-30T23:41:14.942242Z","end":"2026-07-30T23:41:15.04476Z","steps":["trace[865736720] 'agreement among raft nodes before linearized reading' (duration: 102.424905ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:41:15.424145Z","caller":"traceutil/trace.go:171","msg":"trace[1577420854] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"372.914472ms","start":"2026-07-30T23:41:15.051209Z","end":"2026-07-30T23:41:15.424124Z","steps":["trace[1577420854] 'process raft request' (duration: 277.620022ms)","trace[1577420854] 'compare' (duration: 95.040423ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T23:41:15.424178Z","caller":"traceutil/trace.go:171","msg":"trace[1226316174] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"372.49521ms","start":"2026-07-30T23:41:15.051666Z","end":"2026-07-30T23:41:15.424161Z","steps":["trace[1226316174] 'process raft request' (duration: 372.319865ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:41:15.424245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:41:15.051193Z","time spent":"373.003145ms","remote":"127.0.0.1:60960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T23:41:15.424273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:41:15.051658Z","time spent":"372.563602ms","remote":"127.0.0.1:60708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7684,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T23:41:15.462217Z","caller":"traceutil/trace.go:171","msg":"trace[681500797] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"389.978495ms","start":"2026-07-30T23:41:15.0722Z","end":"2026-07-30T23:41:15.462179Z","steps":["trace[681500797] 'process raft request' (duration: 389.449009ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:41:15.462421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:41:15.072181Z","time spent":"390.13889ms","remote":"127.0.0.1:60708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29461,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T23:41:15.462448Z","caller":"traceutil/trace.go:171","msg":"trace[1479894679] linearizableReadLoop","detail":"{readStateIndex:587; appliedIndex:584; }","duration":"115.745623ms","start":"2026-07-30T23:41:15.346685Z","end":"2026-07-30T23:41:15.46243Z","steps":["trace[1479894679] 'read index received' (duration: 115.253549ms)","trace[1479894679] 'applied index is now lower than readState.Index' (duration: 491.094µs)"],"step_count":2} {"level":"info","ts":"2026-07-30T23:41:15.462513Z","caller":"traceutil/trace.go:171","msg":"trace[545809102] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"359.237732ms","start":"2026-07-30T23:41:15.103253Z","end":"2026-07-30T23:41:15.462491Z","steps":["trace[545809102] 'process raft request' (duration: 358.751898ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:41:15.462547Z","caller":"traceutil/trace.go:171","msg":"trace[1658006623] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"347.045626ms","start":"2026-07-30T23:41:15.115476Z","end":"2026-07-30T23:41:15.462522Z","steps":["trace[1658006623] 'process raft request' (duration: 346.604683ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:41:15.462613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:41:15.103237Z","time spent":"359.333274ms","remote":"127.0.0.1:60708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17062,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-30T23:41:15.462657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:41:15.11545Z","time spent":"347.152129ms","remote":"127.0.0.1:60708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":150480,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T23:41:15.462778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.090414ms","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-07-30T23:41:15.46285Z","caller":"traceutil/trace.go:171","msg":"trace[747817801] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:561; }","duration":"116.200447ms","start":"2026-07-30T23:41:15.34663Z","end":"2026-07-30T23:41:15.46283Z","steps":["trace[747817801] 'agreement among raft nodes before linearized reading' (duration: 115.991151ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:41:15.596556Z","caller":"traceutil/trace.go:171","msg":"trace[295366609] linearizableReadLoop","detail":"{readStateIndex:588; appliedIndex:587; }","duration":"134.033073ms","start":"2026-07-30T23:41:15.462496Z","end":"2026-07-30T23:41:15.596529Z","steps":["trace[295366609] 'read index received' (duration: 88.736104ms)","trace[295366609] 'applied index is now lower than readState.Index' (duration: 45.295179ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T23:41:15.596572Z","caller":"traceutil/trace.go:171","msg":"trace[192500329] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"161.949181ms","start":"2026-07-30T23:41:15.434605Z","end":"2026-07-30T23:41:15.596554Z","steps":["trace[192500329] 'process raft request' (duration: 116.60924ms)","trace[192500329] 'compare' (duration: 45.175026ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T23:41:15.597222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.929322ms","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-07-30T23:41:15.597275Z","caller":"traceutil/trace.go:171","msg":"trace[1121005105] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:562; }","duration":"242.987894ms","start":"2026-07-30T23:41:15.354266Z","end":"2026-07-30T23:41:15.597254Z","steps":["trace[1121005105] 'agreement among raft nodes before linearized reading' (duration: 242.344615ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:41:15.60307Z","caller":"traceutil/trace.go:171","msg":"trace[773506958] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"134.839316ms","start":"2026-07-30T23:41:15.468108Z","end":"2026-07-30T23:41:15.602947Z","steps":["trace[773506958] 'process raft request' (duration: 134.62003ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:41:15.603108Z","caller":"traceutil/trace.go:171","msg":"trace[82047116] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"132.2874ms","start":"2026-07-30T23:41:15.470794Z","end":"2026-07-30T23:41:15.603082Z","steps":["trace[82047116] 'process raft request' (duration: 132.073794ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:41:15.603209Z","caller":"traceutil/trace.go:171","msg":"trace[1622558496] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"132.152706ms","start":"2026-07-30T23:41:15.471013Z","end":"2026-07-30T23:41:15.603166Z","steps":["trace[1622558496] 'process raft request' (duration: 131.905949ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:41:15.692048Z","caller":"traceutil/trace.go:171","msg":"trace[323775505] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"125.188768ms","start":"2026-07-30T23:41:15.566817Z","end":"2026-07-30T23:41:15.692006Z","steps":["trace[323775505] 'process raft request' (duration: 124.823217ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:41:15.692047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.421945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T23:41:15.692456Z","caller":"traceutil/trace.go:171","msg":"trace[1675344537] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:566; }","duration":"150.849157ms","start":"2026-07-30T23:41:15.541512Z","end":"2026-07-30T23:41:15.692362Z","steps":["trace[1675344537] 'agreement among raft nodes before linearized reading' (duration: 150.391424ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:41:15.729181Z","caller":"traceutil/trace.go:171","msg":"trace[541020404] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"111.763515ms","start":"2026-07-30T23:41:15.617391Z","end":"2026-07-30T23:41:15.729154Z","steps":["trace[541020404] 'process raft request' (duration: 111.685992ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:41:15.729535Z","caller":"traceutil/trace.go:171","msg":"trace[335035312] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"119.663851ms","start":"2026-07-30T23:41:15.609852Z","end":"2026-07-30T23:41:15.729515Z","steps":["trace[335035312] 'process raft request' (duration: 88.397973ms)","trace[335035312] 'compare' (duration: 30.569658ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T23:41:15.729925Z","caller":"traceutil/trace.go:171","msg":"trace[822223383] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"119.354502ms","start":"2026-07-30T23:41:15.610546Z","end":"2026-07-30T23:41:15.729901Z","steps":["trace[822223383] 'process raft request' (duration: 118.452285ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:41:21.476263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.96614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.61\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-07-30T23:41:21.476299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.135093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-07-30T23:41:21.476291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"548.056211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T23:41:21.476349Z","caller":"traceutil/trace.go:171","msg":"trace[1471471266] range","detail":"{range_begin:/registry/masterleases/199.204.45.61; range_end:; response_count:1; response_revision:622; }","duration":"290.088504ms","start":"2026-07-30T23:41:21.18624Z","end":"2026-07-30T23:41:21.476329Z","steps":["trace[1471471266] 'range keys from in-memory index tree' (duration: 289.798965ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:41:21.476354Z","caller":"traceutil/trace.go:171","msg":"trace[451032609] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:622; }","duration":"387.219326ms","start":"2026-07-30T23:41:21.089118Z","end":"2026-07-30T23:41:21.476337Z","steps":["trace[451032609] 'range keys from in-memory index tree' (duration: 387.01455ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:41:21.476366Z","caller":"traceutil/trace.go:171","msg":"trace[1965481881] range","detail":"{range_begin:/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:622; }","duration":"548.194174ms","start":"2026-07-30T23:41:20.928154Z","end":"2026-07-30T23:41:21.476348Z","steps":["trace[1965481881] 'range keys from in-memory index tree' (duration: 547.964638ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:41:21.47639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:41:21.089105Z","time spent":"387.276767ms","remote":"127.0.0.1:60960","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-07-30T23:41:21.476403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:41:20.928134Z","time spent":"548.261807ms","remote":"127.0.0.1:60904","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":0,"response size":29,"request content":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"warn","ts":"2026-07-30T23:41:21.4764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.983964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-4j2dk\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-07-30T23:41:21.47648Z","caller":"traceutil/trace.go:171","msg":"trace[787828913] range","detail":"{range_begin:/registry/pods/kube-system/cilium-4j2dk; range_end:; response_count:1; response_revision:622; }","duration":"436.121297ms","start":"2026-07-30T23:41:21.040338Z","end":"2026-07-30T23:41:21.47646Z","steps":["trace[787828913] 'range keys from in-memory index tree' (duration: 435.876911ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:41:21.476518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:41:21.040314Z","time spent":"436.19466ms","remote":"127.0.0.1:60876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16985,"request content":"key:\"/registry/pods/kube-system/cilium-4j2dk\" "} {"level":"info","ts":"2026-07-30T23:41:21.924508Z","caller":"traceutil/trace.go:171","msg":"trace[366254271] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"443.178437ms","start":"2026-07-30T23:41:21.481296Z","end":"2026-07-30T23:41:21.924474Z","steps":["trace[366254271] 'process raft request' (duration: 442.837617ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:41:21.924707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:41:21.481277Z","time spent":"443.314321ms","remote":"127.0.0.1:60960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T23:41:21.92457Z","caller":"traceutil/trace.go:171","msg":"trace[1928691654] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"426.364501ms","start":"2026-07-30T23:41:21.498189Z","end":"2026-07-30T23:41:21.924553Z","steps":["trace[1928691654] 'process raft request' (duration: 426.286789ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:41:21.924646Z","caller":"traceutil/trace.go:171","msg":"trace[1474030139] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"443.199757ms","start":"2026-07-30T23:41:21.481393Z","end":"2026-07-30T23:41:21.924593Z","steps":["trace[1474030139] 'process raft request' (duration: 442.95895ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:41:21.925182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:41:21.481361Z","time spent":"443.634789ms","remote":"127.0.0.1:60960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T23:41:21.924948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:41:21.49817Z","time spent":"426.713621ms","remote":"127.0.0.1:60876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T23:41:22.039488Z","caller":"traceutil/trace.go:171","msg":"trace[1621620845] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"534.871937ms","start":"2026-07-30T23:41:21.504001Z","end":"2026-07-30T23:41:22.038873Z","steps":["trace[1621620845] 'process raft request' (duration: 523.90879ms)","trace[1621620845] 'compare' (duration: 10.614806ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T23:41:22.039853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:41:21.503987Z","time spent":"535.579997ms","remote":"127.0.0.1:60698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T23:41:22.042087Z","caller":"traceutil/trace.go:171","msg":"trace[588447613] linearizableReadLoop","detail":"{readStateIndex:654; appliedIndex:651; }","duration":"500.659691ms","start":"2026-07-30T23:41:21.541398Z","end":"2026-07-30T23:41:22.042058Z","steps":["trace[588447613] 'read index received' (duration: 382.947756ms)","trace[588447613] 'applied index is now lower than readState.Index' (duration: 117.710415ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T23:41:22.042243Z","caller":"traceutil/trace.go:171","msg":"trace[1253203255] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"164.781934ms","start":"2026-07-30T23:41:21.87743Z","end":"2026-07-30T23:41:22.042211Z","steps":["trace[1253203255] 'process raft request' (duration: 162.821799ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:41:22.042444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"501.019191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T23:41:22.042552Z","caller":"traceutil/trace.go:171","msg":"trace[287305971] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:627; }","duration":"501.169315ms","start":"2026-07-30T23:41:21.541366Z","end":"2026-07-30T23:41:22.042535Z","steps":["trace[287305971] 'agreement among raft nodes before linearized reading' (duration: 500.942959ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:41:22.042643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:41:21.541351Z","time spent":"501.277128ms","remote":"127.0.0.1:60654","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-07-30T23:41:22.042787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.863774ms","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-07-30T23:41:22.042858Z","caller":"traceutil/trace.go:171","msg":"trace[277680835] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:627; }","duration":"411.005639ms","start":"2026-07-30T23:41:21.631835Z","end":"2026-07-30T23:41:22.04284Z","steps":["trace[277680835] 'agreement among raft nodes before linearized reading' (duration: 410.580037ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:41:22.042901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:41:21.631816Z","time spent":"411.07241ms","remote":"127.0.0.1:60960","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-07-30T23:41:22.043248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.201636ms","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-07-30T23:41:22.043306Z","caller":"traceutil/trace.go:171","msg":"trace[1140432079] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:627; }","duration":"284.477794ms","start":"2026-07-30T23:41:21.758815Z","end":"2026-07-30T23:41:22.043293Z","steps":["trace[1140432079] 'agreement among raft nodes before linearized reading' (duration: 283.624361ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:41:23.220473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.786408ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-30T23:41:23.220696Z","caller":"traceutil/trace.go:171","msg":"trace[1357787386] linearizableReadLoop","detail":"{readStateIndex:658; appliedIndex:655; }","duration":"678.046942ms","start":"2026-07-30T23:41:22.542631Z","end":"2026-07-30T23:41:23.220678Z","steps":["trace[1357787386] 'read index received' (duration: 471.771211ms)","trace[1357787386] 'applied index is now lower than readState.Index' (duration: 206.274561ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T23:41:23.220795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"678.167645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T23:41:23.220825Z","caller":"traceutil/trace.go:171","msg":"trace[577388987] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:630; }","duration":"678.237257ms","start":"2026-07-30T23:41:22.542578Z","end":"2026-07-30T23:41:23.220815Z","steps":["trace[577388987] 'agreement among raft nodes before linearized reading' (duration: 678.157895ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:41:23.220858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:41:22.542556Z","time spent":"678.295089ms","remote":"127.0.0.1:60670","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-07-30T23:41:23.221068Z","caller":"traceutil/trace.go:171","msg":"trace[533730925] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"1.114278411s","start":"2026-07-30T23:41:22.106751Z","end":"2026-07-30T23:41:23.221029Z","steps":["trace[533730925] 'process raft request' (duration: 1.113881689s)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:41:23.221157Z","caller":"traceutil/trace.go:171","msg":"trace[1869018768] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"1.171590673s","start":"2026-07-30T23:41:22.049546Z","end":"2026-07-30T23:41:23.221136Z","steps":["trace[1869018768] 'process raft request' (duration: 964.841208ms)","trace[1869018768] 'compare' (duration: 205.585383ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T23:41:23.22123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:41:22.106731Z","time spent":"1.114427435s","remote":"127.0.0.1:60904","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":818,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-30T23:41:23.221061Z","caller":"traceutil/trace.go:171","msg":"trace[27281483] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"1.166401698s","start":"2026-07-30T23:41:22.054621Z","end":"2026-07-30T23:41:23.221023Z","steps":["trace[27281483] 'process raft request' (duration: 1.165954735s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:41:23.221314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:41:22.04953Z","time spent":"1.171688727s","remote":"127.0.0.1:60960","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-07-30T23:41:23.22233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:41:22.054603Z","time spent":"1.167677854s","remote":"127.0.0.1:60960","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-07-30T23:41:24.046756Z","caller":"traceutil/trace.go:171","msg":"trace[1696817756] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"114.932272ms","start":"2026-07-30T23:41:23.931794Z","end":"2026-07-30T23:41:24.046726Z","steps":["trace[1696817756] 'process raft request' (duration: 114.735696ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:41:24.048797Z","caller":"traceutil/trace.go:171","msg":"trace[1627967972] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"115.62794ms","start":"2026-07-30T23:41:23.933141Z","end":"2026-07-30T23:41:24.048769Z","steps":["trace[1627967972] 'process raft request' (duration: 115.395714ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:41:25.489513Z","caller":"traceutil/trace.go:171","msg":"trace[981945416] linearizableReadLoop","detail":"{readStateIndex:691; appliedIndex:690; }","duration":"263.224548ms","start":"2026-07-30T23:41:25.226257Z","end":"2026-07-30T23:41:25.489481Z","steps":["trace[981945416] 'read index received' (duration: 261.173733ms)","trace[981945416] 'applied index is now lower than readState.Index' (duration: 2.049504ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T23:41:25.489891Z","caller":"traceutil/trace.go:171","msg":"trace[550588799] transaction","detail":"{read_only:false; response_revision:663; number_of_response:1; }","duration":"288.680408ms","start":"2026-07-30T23:41:25.201197Z","end":"2026-07-30T23:41:25.489878Z","steps":["trace[550588799] 'process raft request' (duration: 286.274964ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:41:25.490241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.971358ms","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-07-30T23:41:25.490289Z","caller":"traceutil/trace.go:171","msg":"trace[1666382746] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:663; }","duration":"264.047441ms","start":"2026-07-30T23:41:25.226228Z","end":"2026-07-30T23:41:25.490276Z","steps":["trace[1666382746] 'agreement among raft nodes before linearized reading' (duration: 263.865106ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:41:25.499776Z","caller":"traceutil/trace.go:171","msg":"trace[1965930187] transaction","detail":"{read_only:false; response_revision:664; number_of_response:1; }","duration":"124.867711ms","start":"2026-07-30T23:41:25.374858Z","end":"2026-07-30T23:41:25.499726Z","steps":["trace[1965930187] 'process raft request' (duration: 124.769548ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:41:25.500417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.492469ms","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-07-30T23:41:25.500468Z","caller":"traceutil/trace.go:171","msg":"trace[1708372478] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:664; }","duration":"272.56315ms","start":"2026-07-30T23:41:25.227892Z","end":"2026-07-30T23:41:25.500456Z","steps":["trace[1708372478] 'agreement among raft nodes before linearized reading' (duration: 272.378495ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:41:38.406424Z","caller":"traceutil/trace.go:171","msg":"trace[1107874141] linearizableReadLoop","detail":"{readStateIndex:830; appliedIndex:829; }","duration":"262.116783ms","start":"2026-07-30T23:41:38.144279Z","end":"2026-07-30T23:41:38.406396Z","steps":["trace[1107874141] 'read index received' (duration: 261.868867ms)","trace[1107874141] 'applied index is now lower than readState.Index' (duration: 246.726µs)"],"step_count":2} {"level":"info","ts":"2026-07-30T23:41:38.406528Z","caller":"traceutil/trace.go:171","msg":"trace[1230191658] transaction","detail":"{read_only:false; response_revision:798; number_of_response:1; }","duration":"283.928147ms","start":"2026-07-30T23:41:38.12252Z","end":"2026-07-30T23:41:38.406448Z","steps":["trace[1230191658] 'process raft request' (duration: 283.720682ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:41:38.406772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.477501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-07-30T23:41:38.406876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.888329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-4b48x\" ","response":"range_response_count:1 size:3964"} {"level":"info","ts":"2026-07-30T23:41:38.406959Z","caller":"traceutil/trace.go:171","msg":"trace[387073240] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-4b48x; range_end:; response_count:1; response_revision:798; }","duration":"260.010731ms","start":"2026-07-30T23:41:38.146926Z","end":"2026-07-30T23:41:38.406937Z","steps":["trace[387073240] 'agreement among raft nodes before linearized reading' (duration: 259.787176ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:41:38.406812Z","caller":"traceutil/trace.go:171","msg":"trace[1740693445] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:798; }","duration":"262.571603ms","start":"2026-07-30T23:41:38.144228Z","end":"2026-07-30T23:41:38.4068Z","steps":["trace[1740693445] 'agreement among raft nodes before linearized reading' (duration: 262.330947ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:42:08.819896Z","caller":"traceutil/trace.go:171","msg":"trace[5456759] transaction","detail":"{read_only:false; response_revision:1202; number_of_response:1; }","duration":"203.404014ms","start":"2026-07-30T23:42:08.616469Z","end":"2026-07-30T23:42:08.819873Z","steps":["trace[5456759] 'process raft request' (duration: 203.256601ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:42:08.828796Z","caller":"traceutil/trace.go:171","msg":"trace[1534400484] transaction","detail":"{read_only:false; response_revision:1203; number_of_response:1; }","duration":"212.294512ms","start":"2026-07-30T23:42:08.616487Z","end":"2026-07-30T23:42:08.828781Z","steps":["trace[1534400484] 'process raft request' (duration: 212.221691ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:42:30.626452Z","caller":"traceutil/trace.go:171","msg":"trace[777448420] transaction","detail":"{read_only:false; response_revision:1459; number_of_response:1; }","duration":"224.974519ms","start":"2026-07-30T23:42:30.401454Z","end":"2026-07-30T23:42:30.626428Z","steps":["trace[777448420] 'process raft request' (duration: 224.798377ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:42:30.626902Z","caller":"traceutil/trace.go:171","msg":"trace[4921861] linearizableReadLoop","detail":"{readStateIndex:1506; appliedIndex:1506; }","duration":"200.997386ms","start":"2026-07-30T23:42:30.425886Z","end":"2026-07-30T23:42:30.626884Z","steps":["trace[4921861] 'read index received' (duration: 200.988656ms)","trace[4921861] 'applied index is now lower than readState.Index' (duration: 7.41µs)"],"step_count":2} {"level":"warn","ts":"2026-07-30T23:42:30.627043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.130678ms","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-07-30T23:42:30.627075Z","caller":"traceutil/trace.go:171","msg":"trace[674101159] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1459; }","duration":"201.20767ms","start":"2026-07-30T23:42:30.425857Z","end":"2026-07-30T23:42:30.627065Z","steps":["trace[674101159] 'agreement among raft nodes before linearized reading' (duration: 201.122508ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:42:30.629473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.796598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T23:42:30.630306Z","caller":"traceutil/trace.go:171","msg":"trace[1513037096] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:1460; }","duration":"105.790435ms","start":"2026-07-30T23:42:30.52449Z","end":"2026-07-30T23:42:30.63028Z","steps":["trace[1513037096] 'agreement among raft nodes before linearized reading' (duration: 104.788278ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:42:43.398971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.697586ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-30T23:42:43.399081Z","caller":"traceutil/trace.go:171","msg":"trace[402706778] linearizableReadLoop","detail":"{readStateIndex:1758; appliedIndex:1757; }","duration":"338.559754ms","start":"2026-07-30T23:42:43.060502Z","end":"2026-07-30T23:42:43.399062Z","steps":["trace[402706778] 'read index received' (duration: 230.709876ms)","trace[402706778] 'applied index is now lower than readState.Index' (duration: 107.848358ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T23:42:43.39915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.647006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/perconaxtradbclusterrestores.pxc.percona.com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T23:42:43.399176Z","caller":"traceutil/trace.go:171","msg":"trace[1085259814] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/perconaxtradbclusterrestores.pxc.percona.com; range_end:; response_count:0; response_revision:1691; }","duration":"338.695217ms","start":"2026-07-30T23:42:43.060471Z","end":"2026-07-30T23:42:43.399166Z","steps":["trace[1085259814] 'agreement among raft nodes before linearized reading' (duration: 338.640226ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:42:43.399209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:42:43.060455Z","time spent":"338.747228ms","remote":"127.0.0.1:60708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":103,"response count":0,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/perconaxtradbclusterrestores.pxc.percona.com\" "} {"level":"info","ts":"2026-07-30T23:42:43.399281Z","caller":"traceutil/trace.go:171","msg":"trace[1908198149] transaction","detail":"{read_only:false; response_revision:1691; number_of_response:1; }","duration":"342.537626ms","start":"2026-07-30T23:42:43.056706Z","end":"2026-07-30T23:42:43.399243Z","steps":["trace[1908198149] 'process raft request' (duration: 234.464444ms)","trace[1908198149] 'compare' (duration: 107.521933ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T23:42:43.399432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:42:43.056681Z","time spent":"342.668518ms","remote":"127.0.0.1:60708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6781,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T23:42:43.420154Z","caller":"traceutil/trace.go:171","msg":"trace[1775702215] transaction","detail":"{read_only:false; response_revision:1692; number_of_response:1; }","duration":"213.846748ms","start":"2026-07-30T23:42:43.206283Z","end":"2026-07-30T23:42:43.42013Z","steps":["trace[1775702215] 'process raft request' (duration: 213.519833ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:42:45.105205Z","caller":"traceutil/trace.go:171","msg":"trace[1563965184] transaction","detail":"{read_only:false; response_revision:1708; number_of_response:1; }","duration":"101.778491ms","start":"2026-07-30T23:42:45.003393Z","end":"2026-07-30T23:42:45.105172Z","steps":["trace[1563965184] 'process raft request' (duration: 87.494355ms)","trace[1563965184] 'compare' (duration: 14.032852ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T23:42:56.435634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.864018ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-07-30T23:42:56.667283Z","caller":"traceutil/trace.go:171","msg":"trace[1920191900] transaction","detail":"{read_only:false; response_revision:1876; number_of_response:1; }","duration":"122.215608ms","start":"2026-07-30T23:42:56.545029Z","end":"2026-07-30T23:42:56.667245Z","steps":["trace[1920191900] 'process raft request' (duration: 121.971414ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:42:57.72977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"968.505637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-aa4f7f01-6ea8-4adc-826d-7b3768ec84ee\" ","response":"range_response_count:1 size:3746"} {"level":"info","ts":"2026-07-30T23:42:57.729938Z","caller":"traceutil/trace.go:171","msg":"trace[91489205] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-aa4f7f01-6ea8-4adc-826d-7b3768ec84ee; range_end:; response_count:1; response_revision:1876; }","duration":"968.708931ms","start":"2026-07-30T23:42:56.76118Z","end":"2026-07-30T23:42:57.729889Z","steps":["trace[91489205] 'range keys from in-memory index tree' (duration: 968.376695ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:42:57.73Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:42:56.76117Z","time spent":"968.816982ms","remote":"127.0.0.1:60876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3770,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-aa4f7f01-6ea8-4adc-826d-7b3768ec84ee\" "} {"level":"warn","ts":"2026-07-30T23:42:57.730073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.706922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-30T23:42:57.730089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.395092ms","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-07-30T23:42:57.7302Z","caller":"traceutil/trace.go:171","msg":"trace[1450200631] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1876; }","duration":"186.867604ms","start":"2026-07-30T23:42:57.543311Z","end":"2026-07-30T23:42:57.730179Z","steps":["trace[1450200631] 'range keys from in-memory index tree' (duration: 186.54829ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:42:57.73018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"949.186279ms","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-07-30T23:42:57.730286Z","caller":"traceutil/trace.go:171","msg":"trace[390305970] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1876; }","duration":"493.591004ms","start":"2026-07-30T23:42:57.236602Z","end":"2026-07-30T23:42:57.730193Z","steps":["trace[390305970] 'range keys from in-memory index tree' (duration: 493.244319ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:42:57.730309Z","caller":"traceutil/trace.go:171","msg":"trace[647225921] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1876; }","duration":"949.30106ms","start":"2026-07-30T23:42:56.780976Z","end":"2026-07-30T23:42:57.730277Z","steps":["trace[647225921] 'range keys from in-memory index tree' (duration: 949.175918ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:42:57.730378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:42:57.236571Z","time spent":"493.792847ms","remote":"127.0.0.1:41702","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-07-30T23:42:57.730506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"566.604139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-07-30T23:42:57.730546Z","caller":"traceutil/trace.go:171","msg":"trace[1611375336] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1876; }","duration":"566.66911ms","start":"2026-07-30T23:42:57.163868Z","end":"2026-07-30T23:42:57.730537Z","steps":["trace[1611375336] 'range keys from in-memory index tree' (duration: 566.477737ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:42:57.730577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:42:57.163855Z","time spent":"566.714461ms","remote":"127.0.0.1:60960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-30T23:42:57.730923Z","caller":"traceutil/trace.go:171","msg":"trace[1143755882] linearizableReadLoop","detail":"{readStateIndex:1952; appliedIndex:1951; }","duration":"127.517316ms","start":"2026-07-30T23:42:57.60337Z","end":"2026-07-30T23:42:57.730888Z","steps":["trace[1143755882] 'read index received' (duration: 106.85971ms)","trace[1143755882] 'applied index is now lower than readState.Index' (duration: 20.655856ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T23:42:57.731273Z","caller":"traceutil/trace.go:171","msg":"trace[1018035004] transaction","detail":"{read_only:false; response_revision:1877; number_of_response:1; }","duration":"157.594317ms","start":"2026-07-30T23:42:57.573656Z","end":"2026-07-30T23:42:57.73125Z","steps":["trace[1018035004] 'process raft request' (duration: 136.750958ms)","trace[1018035004] 'compare' (duration: 19.334637ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T23:42:57.731282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"569.794835ms","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-07-30T23:42:57.731449Z","caller":"traceutil/trace.go:171","msg":"trace[1970813784] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1876; }","duration":"570.899891ms","start":"2026-07-30T23:42:57.160537Z","end":"2026-07-30T23:42:57.731437Z","steps":["trace[1970813784] 'range keys from in-memory index tree' (duration: 569.593852ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:42:57.73151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:42:57.160509Z","time spent":"570.989662ms","remote":"127.0.0.1:60960","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-07-30T23:42:57.732081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.665721ms","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-07-30T23:42:57.732259Z","caller":"traceutil/trace.go:171","msg":"trace[1174960942] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1877; }","duration":"128.985126ms","start":"2026-07-30T23:42:57.603255Z","end":"2026-07-30T23:42:57.73224Z","steps":["trace[1174960942] 'agreement among raft nodes before linearized reading' (duration: 127.804179ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:42:57.923793Z","caller":"traceutil/trace.go:171","msg":"trace[1575110440] transaction","detail":"{read_only:false; response_revision:1879; number_of_response:1; }","duration":"187.167788ms","start":"2026-07-30T23:42:57.736589Z","end":"2026-07-30T23:42:57.923757Z","steps":["trace[1575110440] 'process raft request' (duration: 167.263333ms)","trace[1575110440] 'compare' (duration: 19.456179ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T23:42:57.923795Z","caller":"traceutil/trace.go:171","msg":"trace[72122146] transaction","detail":"{read_only:false; response_revision:1880; number_of_response:1; }","duration":"186.476628ms","start":"2026-07-30T23:42:57.737278Z","end":"2026-07-30T23:42:57.923755Z","steps":["trace[72122146] 'process raft request' (duration: 186.323636ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:42:58.641735Z","caller":"traceutil/trace.go:171","msg":"trace[454395441] transaction","detail":"{read_only:false; response_revision:1883; number_of_response:1; }","duration":"642.477357ms","start":"2026-07-30T23:42:57.999238Z","end":"2026-07-30T23:42:58.641715Z","steps":["trace[454395441] 'process raft request' (duration: 642.429666ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:42:58.641763Z","caller":"traceutil/trace.go:171","msg":"trace[303530367] transaction","detail":"{read_only:false; response_revision:1882; number_of_response:1; }","duration":"665.331853ms","start":"2026-07-30T23:42:57.9764Z","end":"2026-07-30T23:42:58.641732Z","steps":["trace[303530367] 'process raft request' (duration: 665.208081ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:42:58.641862Z","caller":"traceutil/trace.go:171","msg":"trace[1863714715] linearizableReadLoop","detail":"{readStateIndex:1958; appliedIndex:1955; }","duration":"577.069414ms","start":"2026-07-30T23:42:58.064784Z","end":"2026-07-30T23:42:58.641854Z","steps":["trace[1863714715] 'read index received' (duration: 482.78252ms)","trace[1863714715] 'applied index is now lower than readState.Index' (duration: 94.285324ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T23:42:58.641924Z","caller":"traceutil/trace.go:171","msg":"trace[1875333983] transaction","detail":"{read_only:false; response_revision:1884; number_of_response:1; }","duration":"206.95891ms","start":"2026-07-30T23:42:58.434959Z","end":"2026-07-30T23:42:58.641918Z","steps":["trace[1875333983] 'process raft request' (duration: 206.730496ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:42:58.641936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:42:57.976387Z","time spent":"665.452055ms","remote":"127.0.0.1:60960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T23:42:58.64186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:42:57.999225Z","time spent":"642.577538ms","remote":"127.0.0.1:60960","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-07-30T23:42:58.641752Z","caller":"traceutil/trace.go:171","msg":"trace[1067029534] transaction","detail":"{read_only:false; response_revision:1881; number_of_response:1; }","duration":"668.858063ms","start":"2026-07-30T23:42:57.972879Z","end":"2026-07-30T23:42:58.641737Z","steps":["trace[1067029534] 'process raft request' (duration: 574.668511ms)","trace[1067029534] 'compare' (duration: 93.868938ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T23:42:58.642082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"577.306867ms","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-07-30T23:42:58.642114Z","caller":"traceutil/trace.go:171","msg":"trace[66274455] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1884; }","duration":"577.380418ms","start":"2026-07-30T23:42:58.064726Z","end":"2026-07-30T23:42:58.642106Z","steps":["trace[66274455] 'agreement among raft nodes before linearized reading' (duration: 577.294777ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:42:58.642102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:42:57.972847Z","time spent":"669.224599ms","remote":"127.0.0.1:60960","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-07-30T23:42:58.642152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:42:58.064705Z","time spent":"577.442539ms","remote":"127.0.0.1:60960","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-07-30T23:42:58.642153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"565.095794ms","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-07-30T23:42:58.642223Z","caller":"traceutil/trace.go:171","msg":"trace[994979647] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1884; }","duration":"565.192415ms","start":"2026-07-30T23:42:58.077012Z","end":"2026-07-30T23:42:58.642204Z","steps":["trace[994979647] 'agreement among raft nodes before linearized reading' (duration: 565.075403ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:42:58.642244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:42:58.076993Z","time spent":"565.246745ms","remote":"127.0.0.1:60960","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-07-30T23:43:01.692632Z","caller":"traceutil/trace.go:171","msg":"trace[1479743486] transaction","detail":"{read_only:false; response_revision:1910; number_of_response:1; }","duration":"112.650745ms","start":"2026-07-30T23:43:01.579953Z","end":"2026-07-30T23:43:01.692604Z","steps":["trace[1479743486] 'process raft request' (duration: 112.463642ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:43:01.697619Z","caller":"traceutil/trace.go:171","msg":"trace[432508459] transaction","detail":"{read_only:false; response_revision:1911; number_of_response:1; }","duration":"117.497824ms","start":"2026-07-30T23:43:01.580079Z","end":"2026-07-30T23:43:01.697577Z","steps":["trace[432508459] 'process raft request' (duration: 117.258641ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:43:02.158771Z","caller":"traceutil/trace.go:171","msg":"trace[1717501877] transaction","detail":"{read_only:false; response_revision:1922; number_of_response:1; }","duration":"204.165844ms","start":"2026-07-30T23:43:01.954566Z","end":"2026-07-30T23:43:02.158732Z","steps":["trace[1717501877] 'process raft request' (duration: 144.958145ms)","trace[1717501877] 'compare' (duration: 59.064727ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T23:43:02.203523Z","caller":"traceutil/trace.go:171","msg":"trace[105118854] transaction","detail":"{read_only:false; response_revision:1923; number_of_response:1; }","duration":"248.381302ms","start":"2026-07-30T23:43:01.955112Z","end":"2026-07-30T23:43:02.203494Z","steps":["trace[105118854] 'process raft request' (duration: 248.23364ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:43:02.242887Z","caller":"traceutil/trace.go:171","msg":"trace[137195315] transaction","detail":"{read_only:false; response_revision:1924; number_of_response:1; }","duration":"117.676009ms","start":"2026-07-30T23:43:02.125184Z","end":"2026-07-30T23:43:02.24286Z","steps":["trace[137195315] 'process raft request' (duration: 117.330214ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:43:04.878377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.336333ms","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-07-30T23:43:04.878494Z","caller":"traceutil/trace.go:171","msg":"trace[2111101010] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1954; }","duration":"110.495435ms","start":"2026-07-30T23:43:04.767971Z","end":"2026-07-30T23:43:04.878466Z","steps":["trace[2111101010] 'range keys from in-memory index tree' (duration: 110.163482ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:43:04.878757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.524771ms","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-07-30T23:43:04.878801Z","caller":"traceutil/trace.go:171","msg":"trace[238771321] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1954; }","duration":"111.621822ms","start":"2026-07-30T23:43:04.767165Z","end":"2026-07-30T23:43:04.878787Z","steps":["trace[238771321] 'range keys from in-memory index tree' (duration: 111.361628ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:43:07.092997Z","caller":"traceutil/trace.go:171","msg":"trace[910025917] transaction","detail":"{read_only:false; response_revision:1968; number_of_response:1; }","duration":"134.170106ms","start":"2026-07-30T23:43:06.958811Z","end":"2026-07-30T23:43:07.092981Z","steps":["trace[910025917] 'process raft request' (duration: 134.068385ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:43:07.154638Z","caller":"traceutil/trace.go:171","msg":"trace[732037843] transaction","detail":"{read_only:false; response_revision:1969; number_of_response:1; }","duration":"195.485218ms","start":"2026-07-30T23:43:06.959114Z","end":"2026-07-30T23:43:07.154599Z","steps":["trace[732037843] 'process raft request' (duration: 194.21119ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:43:11.719394Z","caller":"traceutil/trace.go:171","msg":"trace[1986902901] linearizableReadLoop","detail":"{readStateIndex:2084; appliedIndex:2083; }","duration":"178.340604ms","start":"2026-07-30T23:43:11.541028Z","end":"2026-07-30T23:43:11.719369Z","steps":["trace[1986902901] 'read index received' (duration: 163.351641ms)","trace[1986902901] 'applied index is now lower than readState.Index' (duration: 14.988203ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T23:43:11.719575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.467716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T23:43:11.719608Z","caller":"traceutil/trace.go:171","msg":"trace[551873161] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2005; }","duration":"178.636148ms","start":"2026-07-30T23:43:11.540963Z","end":"2026-07-30T23:43:11.719599Z","steps":["trace[551873161] 'agreement among raft nodes before linearized reading' (duration: 178.499726ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:43:11.719657Z","caller":"traceutil/trace.go:171","msg":"trace[1003458976] transaction","detail":"{read_only:false; response_revision:2005; number_of_response:1; }","duration":"204.577079ms","start":"2026-07-30T23:43:11.515034Z","end":"2026-07-30T23:43:11.719611Z","steps":["trace[1003458976] 'process raft request' (duration: 189.302092ms)","trace[1003458976] 'compare' (duration: 14.904452ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T23:43:12.373884Z","caller":"traceutil/trace.go:171","msg":"trace[1173952096] transaction","detail":"{read_only:false; response_revision:2009; number_of_response:1; }","duration":"109.093661ms","start":"2026-07-30T23:43:12.264772Z","end":"2026-07-30T23:43:12.373866Z","steps":["trace[1173952096] 'process raft request' (duration: 108.968579ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:43:12.835076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.812615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T23:43:12.835171Z","caller":"traceutil/trace.go:171","msg":"trace[1878431666] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2009; }","duration":"294.959076ms","start":"2026-07-30T23:43:12.540194Z","end":"2026-07-30T23:43:12.835153Z","steps":["trace[1878431666] 'range keys from in-memory index tree' (duration: 294.706604ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:43:12.854282Z","caller":"traceutil/trace.go:171","msg":"trace[1998233959] transaction","detail":"{read_only:false; response_revision:2010; number_of_response:1; }","duration":"106.101751ms","start":"2026-07-30T23:43:12.748124Z","end":"2026-07-30T23:43:12.854226Z","steps":["trace[1998233959] 'process raft request' (duration: 105.875198ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:43:12.854993Z","caller":"traceutil/trace.go:171","msg":"trace[1950417445] transaction","detail":"{read_only:false; response_revision:2011; number_of_response:1; }","duration":"106.184611ms","start":"2026-07-30T23:43:12.748787Z","end":"2026-07-30T23:43:12.854971Z","steps":["trace[1950417445] 'process raft request' (duration: 105.840596ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:43:12.855024Z","caller":"traceutil/trace.go:171","msg":"trace[1046967218] transaction","detail":"{read_only:false; response_revision:2012; number_of_response:1; }","duration":"106.12712ms","start":"2026-07-30T23:43:12.748886Z","end":"2026-07-30T23:43:12.855013Z","steps":["trace[1046967218] 'process raft request' (duration: 105.796746ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:43:13.337237Z","caller":"traceutil/trace.go:171","msg":"trace[1810307029] linearizableReadLoop","detail":"{readStateIndex:2092; appliedIndex:2091; }","duration":"148.472145ms","start":"2026-07-30T23:43:13.188742Z","end":"2026-07-30T23:43:13.337214Z","steps":["trace[1810307029] 'read index received' (duration: 148.298013ms)","trace[1810307029] 'applied index is now lower than readState.Index' (duration: 173.242µs)"],"step_count":2} {"level":"info","ts":"2026-07-30T23:43:13.337312Z","caller":"traceutil/trace.go:171","msg":"trace[1077074673] transaction","detail":"{read_only:false; response_revision:2013; number_of_response:1; }","duration":"209.656527ms","start":"2026-07-30T23:43:13.127627Z","end":"2026-07-30T23:43:13.337283Z","steps":["trace[1077074673] 'process raft request' (duration: 209.457884ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:43:13.337466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.702458ms","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-07-30T23:43:13.337529Z","caller":"traceutil/trace.go:171","msg":"trace[1980225228] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2013; }","duration":"148.808159ms","start":"2026-07-30T23:43:13.188707Z","end":"2026-07-30T23:43:13.337515Z","steps":["trace[1980225228] 'agreement among raft nodes before linearized reading' (duration: 148.588566ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:43:13.623697Z","caller":"traceutil/trace.go:171","msg":"trace[7741891] transaction","detail":"{read_only:false; response_revision:2014; number_of_response:1; }","duration":"280.582769ms","start":"2026-07-30T23:43:13.34307Z","end":"2026-07-30T23:43:13.623652Z","steps":["trace[7741891] 'process raft request' (duration: 279.120879ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:43:22.934746Z","caller":"traceutil/trace.go:171","msg":"trace[526111635] transaction","detail":"{read_only:false; response_revision:2070; number_of_response:1; }","duration":"207.491793ms","start":"2026-07-30T23:43:22.727226Z","end":"2026-07-30T23:43:22.934718Z","steps":["trace[526111635] 'process raft request' (duration: 207.272031ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:43:28.731462Z","caller":"traceutil/trace.go:171","msg":"trace[416444170] linearizableReadLoop","detail":"{readStateIndex:2185; appliedIndex:2184; }","duration":"276.420466ms","start":"2026-07-30T23:43:28.455018Z","end":"2026-07-30T23:43:28.731439Z","steps":["trace[416444170] 'read index received' (duration: 276.231644ms)","trace[416444170] 'applied index is now lower than readState.Index' (duration: 187.972µs)"],"step_count":2} {"level":"info","ts":"2026-07-30T23:43:28.731522Z","caller":"traceutil/trace.go:171","msg":"trace[1081264594] transaction","detail":"{read_only:false; response_revision:2103; number_of_response:1; }","duration":"418.33777ms","start":"2026-07-30T23:43:28.313144Z","end":"2026-07-30T23:43:28.731482Z","steps":["trace[1081264594] 'process raft request' (duration: 418.123707ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:43:28.73165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:43:28.313127Z","time spent":"418.444152ms","remote":"127.0.0.1:60960","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-07-30T23:43:28.731663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.630899ms","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-07-30T23:43:28.731695Z","caller":"traceutil/trace.go:171","msg":"trace[1845773913] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2103; }","duration":"276.692069ms","start":"2026-07-30T23:43:28.454993Z","end":"2026-07-30T23:43:28.731685Z","steps":["trace[1845773913] 'agreement among raft nodes before linearized reading' (duration: 276.557338ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:43:28.742556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.995941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T23:43:28.742625Z","caller":"traceutil/trace.go:171","msg":"trace[1534787751] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2104; }","duration":"201.070202ms","start":"2026-07-30T23:43:28.541526Z","end":"2026-07-30T23:43:28.742596Z","steps":["trace[1534787751] 'agreement among raft nodes before linearized reading' (duration: 200.976091ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:43:28.982549Z","caller":"traceutil/trace.go:171","msg":"trace[1222328687] linearizableReadLoop","detail":"{readStateIndex:2187; appliedIndex:2187; }","duration":"238.44368ms","start":"2026-07-30T23:43:28.744082Z","end":"2026-07-30T23:43:28.982526Z","steps":["trace[1222328687] 'read index received' (duration: 238.43834ms)","trace[1222328687] 'applied index is now lower than readState.Index' (duration: 4.23µs)"],"step_count":2} {"level":"info","ts":"2026-07-30T23:43:28.982692Z","caller":"traceutil/trace.go:171","msg":"trace[1342268154] transaction","detail":"{read_only:false; response_revision:2105; number_of_response:1; }","duration":"245.47008ms","start":"2026-07-30T23:43:28.737212Z","end":"2026-07-30T23:43:28.982682Z","steps":["trace[1342268154] 'process raft request' (duration: 245.168505ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:43:28.982784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.679014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T23:43:28.982884Z","caller":"traceutil/trace.go:171","msg":"trace[1519174017] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2105; }","duration":"239.074778ms","start":"2026-07-30T23:43:28.743772Z","end":"2026-07-30T23:43:28.982847Z","steps":["trace[1519174017] 'agreement among raft nodes before linearized reading' (duration: 238.913886ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:43:29.347211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.869778ms","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-07-30T23:43:29.347694Z","caller":"traceutil/trace.go:171","msg":"trace[1351689785] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:2108; }","duration":"181.407214ms","start":"2026-07-30T23:43:29.166271Z","end":"2026-07-30T23:43:29.347678Z","steps":["trace[1351689785] 'count revisions from in-memory index tree' (duration: 180.786806ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:43:29.347304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.367388ms","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-07-30T23:43:29.347908Z","caller":"traceutil/trace.go:171","msg":"trace[1635902303] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2108; }","duration":"220.046136ms","start":"2026-07-30T23:43:29.12785Z","end":"2026-07-30T23:43:29.347896Z","steps":["trace[1635902303] 'range keys from in-memory index tree' (duration: 219.101944ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:43:30.015395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.443479ms","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-07-30T23:43:30.015489Z","caller":"traceutil/trace.go:171","msg":"trace[973885534] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2109; }","duration":"318.590232ms","start":"2026-07-30T23:43:29.696875Z","end":"2026-07-30T23:43:30.015465Z","steps":["trace[973885534] 'range keys from in-memory index tree' (duration: 318.226877ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:43:30.015532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:43:29.696844Z","time spent":"318.678882ms","remote":"127.0.0.1:60960","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":"info","ts":"2026-07-30T23:43:30.018007Z","caller":"traceutil/trace.go:171","msg":"trace[1095682686] transaction","detail":"{read_only:false; response_revision:2110; number_of_response:1; }","duration":"112.461324ms","start":"2026-07-30T23:43:29.905518Z","end":"2026-07-30T23:43:30.017979Z","steps":["trace[1095682686] 'process raft request' (duration: 112.050349ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:43:30.366049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.920357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12382"} {"level":"info","ts":"2026-07-30T23:43:30.36617Z","caller":"traceutil/trace.go:171","msg":"trace[1267873716] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2113; }","duration":"224.117609ms","start":"2026-07-30T23:43:30.142031Z","end":"2026-07-30T23:43:30.366149Z","steps":["trace[1267873716] 'range keys from in-memory index tree' (duration: 223.651253ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:43:31.140554Z","caller":"traceutil/trace.go:171","msg":"trace[147086489] linearizableReadLoop","detail":"{readStateIndex:2197; appliedIndex:2196; }","duration":"153.697869ms","start":"2026-07-30T23:43:30.986833Z","end":"2026-07-30T23:43:31.140531Z","steps":["trace[147086489] 'read index received' (duration: 153.615318ms)","trace[147086489] 'applied index is now lower than readState.Index' (duration: 81.631µs)"],"step_count":2} {"level":"info","ts":"2026-07-30T23:43:31.140732Z","caller":"traceutil/trace.go:171","msg":"trace[266642441] transaction","detail":"{read_only:false; response_revision:2115; number_of_response:1; }","duration":"400.872662ms","start":"2026-07-30T23:43:30.739833Z","end":"2026-07-30T23:43:31.140706Z","steps":["trace[266642441] 'process raft request' (duration: 400.552618ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:43:31.14078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.925042ms","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-07-30T23:43:31.140814Z","caller":"traceutil/trace.go:171","msg":"trace[1073766645] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2115; }","duration":"154.019263ms","start":"2026-07-30T23:43:30.986785Z","end":"2026-07-30T23:43:31.140804Z","steps":["trace[1073766645] 'agreement among raft nodes before linearized reading' (duration: 153.847711ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:43:31.140892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:43:30.739809Z","time spent":"400.996623ms","remote":"127.0.0.1:60960","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-07-30T23:43:31.150958Z","caller":"traceutil/trace.go:171","msg":"trace[654533997] transaction","detail":"{read_only:false; response_revision:2118; number_of_response:1; }","duration":"153.911221ms","start":"2026-07-30T23:43:30.997026Z","end":"2026-07-30T23:43:31.150937Z","steps":["trace[654533997] 'process raft request' (duration: 153.868451ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:43:31.151169Z","caller":"traceutil/trace.go:171","msg":"trace[1980039809] transaction","detail":"{read_only:false; response_revision:2117; number_of_response:1; }","duration":"154.746113ms","start":"2026-07-30T23:43:30.996411Z","end":"2026-07-30T23:43:31.151157Z","steps":["trace[1980039809] 'process raft request' (duration: 154.230577ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:43:31.153569Z","caller":"traceutil/trace.go:171","msg":"trace[1178469130] transaction","detail":"{read_only:false; response_revision:2116; number_of_response:1; }","duration":"157.418998ms","start":"2026-07-30T23:43:30.996112Z","end":"2026-07-30T23:43:31.153531Z","steps":["trace[1178469130] 'process raft request' (duration: 154.397458ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:43:31.686648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.776871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T23:43:31.686744Z","caller":"traceutil/trace.go:171","msg":"trace[1392687668] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2120; }","duration":"146.938364ms","start":"2026-07-30T23:43:31.539774Z","end":"2026-07-30T23:43:31.686712Z","steps":["trace[1392687668] 'range keys from in-memory index tree' (duration: 146.70172ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:43:31.686457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.061909ms","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-07-30T23:43:31.687373Z","caller":"traceutil/trace.go:171","msg":"trace[1181243384] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2120; }","duration":"191.031332ms","start":"2026-07-30T23:43:31.496325Z","end":"2026-07-30T23:43:31.687356Z","steps":["trace[1181243384] 'range keys from in-memory index tree' (duration: 189.910697ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:43:32.375624Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16952569023813872652,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-30T23:43:32.616571Z","caller":"traceutil/trace.go:171","msg":"trace[1590561950] transaction","detail":"{read_only:false; response_revision:2121; number_of_response:1; }","duration":"924.671981ms","start":"2026-07-30T23:43:31.691873Z","end":"2026-07-30T23:43:32.616545Z","steps":["trace[1590561950] 'process raft request' (duration: 923.759369ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:43:32.617883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:43:31.691851Z","time spent":"924.794263ms","remote":"127.0.0.1:60960","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-07-30T23:43:32.761245Z","caller":"traceutil/trace.go:171","msg":"trace[690122201] transaction","detail":"{read_only:false; response_revision:2122; number_of_response:1; }","duration":"1.032999389s","start":"2026-07-30T23:43:31.728222Z","end":"2026-07-30T23:43:32.761221Z","steps":["trace[690122201] 'process raft request' (duration: 1.030902383s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:43:32.761444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:43:31.728207Z","time spent":"1.033138441s","remote":"127.0.0.1:60960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T23:43:32.76146Z","caller":"traceutil/trace.go:171","msg":"trace[1268553156] transaction","detail":"{read_only:false; response_revision:2123; number_of_response:1; }","duration":"729.22668ms","start":"2026-07-30T23:43:32.032211Z","end":"2026-07-30T23:43:32.761438Z","steps":["trace[1268553156] 'process raft request' (duration: 728.959756ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:43:32.761477Z","caller":"traceutil/trace.go:171","msg":"trace[523445840] linearizableReadLoop","detail":"{readStateIndex:2207; appliedIndex:2203; }","duration":"886.421582ms","start":"2026-07-30T23:43:31.875033Z","end":"2026-07-30T23:43:32.761455Z","steps":["trace[523445840] 'read index received' (duration: 740.661218ms)","trace[523445840] 'applied index is now lower than readState.Index' (duration: 145.759564ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T23:43:32.761573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:43:32.032177Z","time spent":"729.337121ms","remote":"127.0.0.1:60960","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-07-30T23:43:32.761595Z","caller":"traceutil/trace.go:171","msg":"trace[1237177660] transaction","detail":"{read_only:false; response_revision:2124; number_of_response:1; }","duration":"501.365549ms","start":"2026-07-30T23:43:32.260219Z","end":"2026-07-30T23:43:32.761585Z","steps":["trace[1237177660] 'process raft request' (duration: 501.120045ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:43:32.761685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"854.469896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-30T23:43:32.761684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:43:32.260188Z","time spent":"501.432069ms","remote":"127.0.0.1:60960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":513,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T23:43:32.761703Z","caller":"traceutil/trace.go:171","msg":"trace[1757416286] range","detail":"{range_begin:/registry/cert-manager.io/clusterissuers/; range_end:/registry/cert-manager.io/clusterissuers0; response_count:0; response_revision:2124; }","duration":"854.547857ms","start":"2026-07-30T23:43:31.907149Z","end":"2026-07-30T23:43:32.761697Z","steps":["trace[1757416286] 'agreement among raft nodes before linearized reading' (duration: 854.510237ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:43:32.761721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:43:31.907116Z","time spent":"854.601457ms","remote":"127.0.0.1:39590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":2,"response size":31,"request content":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true "} {"level":"warn","ts":"2026-07-30T23:43:32.761864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"646.525014ms","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":"warn","ts":"2026-07-30T23:43:32.761896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"710.180848ms","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-07-30T23:43:32.761912Z","caller":"traceutil/trace.go:171","msg":"trace[218265279] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2124; }","duration":"710.25463ms","start":"2026-07-30T23:43:32.051652Z","end":"2026-07-30T23:43:32.761907Z","steps":["trace[218265279] 'agreement among raft nodes before linearized reading' (duration: 710.181099ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:43:32.76191Z","caller":"traceutil/trace.go:171","msg":"trace[93803584] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:2124; }","duration":"646.610844ms","start":"2026-07-30T23:43:32.115284Z","end":"2026-07-30T23:43:32.761894Z","steps":["trace[93803584] 'agreement among raft nodes before linearized reading' (duration: 646.539574ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:43:32.761926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:43:32.051623Z","time spent":"710.29842ms","remote":"127.0.0.1:60960","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-07-30T23:43:32.761944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:43:32.115266Z","time spent":"646.669835ms","remote":"127.0.0.1:59030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":31,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true "} {"level":"warn","ts":"2026-07-30T23:43:32.761986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"710.33777ms","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-07-30T23:43:32.761998Z","caller":"traceutil/trace.go:171","msg":"trace[101465234] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2124; }","duration":"710.389572ms","start":"2026-07-30T23:43:32.051604Z","end":"2026-07-30T23:43:32.761994Z","steps":["trace[101465234] 'agreement among raft nodes before linearized reading' (duration: 710.352171ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:43:32.762012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:43:32.051585Z","time spent":"710.424312ms","remote":"127.0.0.1:60960","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-07-30T23:43:32.762108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"887.07214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T23:43:32.762175Z","caller":"traceutil/trace.go:171","msg":"trace[1513382411] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:2124; }","duration":"887.168571ms","start":"2026-07-30T23:43:31.874995Z","end":"2026-07-30T23:43:32.762164Z","steps":["trace[1513382411] 'agreement among raft nodes before linearized reading' (duration: 887.08507ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:43:32.762202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:43:31.87498Z","time spent":"887.215081ms","remote":"127.0.0.1:60806","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":29,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} {"level":"warn","ts":"2026-07-30T23:43:32.76223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.890752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-30T23:43:32.762247Z","caller":"traceutil/trace.go:171","msg":"trace[1508944016] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:2124; }","duration":"186.948064ms","start":"2026-07-30T23:43:32.575294Z","end":"2026-07-30T23:43:32.762242Z","steps":["trace[1508944016] 'agreement among raft nodes before linearized reading' (duration: 186.903263ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:43:32.762244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.617792ms","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-07-30T23:43:32.762283Z","caller":"traceutil/trace.go:171","msg":"trace[311513609] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2124; }","duration":"384.683224ms","start":"2026-07-30T23:43:32.377586Z","end":"2026-07-30T23:43:32.762269Z","steps":["trace[311513609] 'agreement among raft nodes before linearized reading' (duration: 384.527072ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:43:32.762308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:43:32.377571Z","time spent":"384.730724ms","remote":"127.0.0.1:41702","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-07-30T23:43:33.400918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"511.084868ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-30T23:43:33.401193Z","caller":"traceutil/trace.go:171","msg":"trace[1062566591] transaction","detail":"{read_only:false; response_revision:2129; number_of_response:1; }","duration":"240.722875ms","start":"2026-07-30T23:43:33.160453Z","end":"2026-07-30T23:43:33.401176Z","steps":["trace[1062566591] 'process raft request' (duration: 240.691044ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:43:33.401395Z","caller":"traceutil/trace.go:171","msg":"trace[356234420] transaction","detail":"{read_only:false; response_revision:2128; number_of_response:1; }","duration":"243.708202ms","start":"2026-07-30T23:43:33.157678Z","end":"2026-07-30T23:43:33.401386Z","steps":["trace[356234420] 'process raft request' (duration: 243.431509ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:43:33.401399Z","caller":"traceutil/trace.go:171","msg":"trace[837174289] transaction","detail":"{read_only:false; response_revision:2127; number_of_response:1; }","duration":"244.729395ms","start":"2026-07-30T23:43:33.156632Z","end":"2026-07-30T23:43:33.401362Z","steps":["trace[837174289] 'process raft request' (duration: 244.444472ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:43:33.401362Z","caller":"traceutil/trace.go:171","msg":"trace[758649045] transaction","detail":"{read_only:false; response_revision:2126; number_of_response:1; }","duration":"631.348507ms","start":"2026-07-30T23:43:32.769983Z","end":"2026-07-30T23:43:33.401332Z","steps":["trace[758649045] 'process raft request' (duration: 631.034793ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:43:33.401568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:43:32.769973Z","time spent":"631.536569ms","remote":"127.0.0.1:60960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T23:43:33.401747Z","caller":"traceutil/trace.go:171","msg":"trace[1057392649] transaction","detail":"{read_only:false; response_revision:2125; number_of_response:1; }","duration":"634.544497ms","start":"2026-07-30T23:43:32.767175Z","end":"2026-07-30T23:43:33.40172Z","steps":["trace[1057392649] 'process raft request' (duration: 122.573779ms)","trace[1057392649] 'compare' (duration: 510.822614ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T23:43:33.401692Z","caller":"traceutil/trace.go:171","msg":"trace[2060942055] linearizableReadLoop","detail":"{readStateIndex:2209; appliedIndex:2207; }","duration":"255.981837ms","start":"2026-07-30T23:43:33.145695Z","end":"2026-07-30T23:43:33.401677Z","steps":["trace[2060942055] 'read index received' (duration: 52.786016ms)","trace[2060942055] 'applied index is now lower than readState.Index' (duration: 203.194351ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T23:43:33.401816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:43:32.767112Z","time spent":"634.670889ms","remote":"127.0.0.1:60960","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-07-30T23:43:33.402053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.430817ms","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-07-30T23:43:33.402088Z","caller":"traceutil/trace.go:171","msg":"trace[1910189847] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2129; }","duration":"231.496188ms","start":"2026-07-30T23:43:33.170583Z","end":"2026-07-30T23:43:33.402079Z","steps":["trace[1910189847] 'agreement among raft nodes before linearized reading' (duration: 231.378356ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:43:33.402103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.384342ms","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-07-30T23:43:33.402355Z","caller":"traceutil/trace.go:171","msg":"trace[1740152848] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2129; }","duration":"256.683935ms","start":"2026-07-30T23:43:33.145653Z","end":"2026-07-30T23:43:33.402337Z","steps":["trace[1740152848] 'agreement among raft nodes before linearized reading' (duration: 256.317181ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:45:04.485641Z","caller":"traceutil/trace.go:171","msg":"trace[413371256] linearizableReadLoop","detail":"{readStateIndex:2759; appliedIndex:2758; }","duration":"243.014234ms","start":"2026-07-30T23:45:04.242598Z","end":"2026-07-30T23:45:04.485613Z","steps":["trace[413371256] 'read index received' (duration: 242.770278ms)","trace[413371256] 'applied index is now lower than readState.Index' (duration: 243.086µs)"],"step_count":2} {"level":"warn","ts":"2026-07-30T23:45:04.485854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.225078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-07-30T23:45:04.485892Z","caller":"traceutil/trace.go:171","msg":"trace[245599989] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2655; }","duration":"243.3167ms","start":"2026-07-30T23:45:04.242563Z","end":"2026-07-30T23:45:04.485879Z","steps":["trace[245599989] 'agreement among raft nodes before linearized reading' (duration: 243.156916ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:45:04.485927Z","caller":"traceutil/trace.go:171","msg":"trace[650002783] transaction","detail":"{read_only:false; response_revision:2655; number_of_response:1; }","duration":"277.660997ms","start":"2026-07-30T23:45:04.208227Z","end":"2026-07-30T23:45:04.485888Z","steps":["trace[650002783] 'process raft request' (duration: 277.225147ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:45:13.263334Z","caller":"traceutil/trace.go:171","msg":"trace[763701868] linearizableReadLoop","detail":"{readStateIndex:2861; appliedIndex:2860; }","duration":"112.90496ms","start":"2026-07-30T23:45:13.150403Z","end":"2026-07-30T23:45:13.263308Z","steps":["trace[763701868] 'read index received' (duration: 112.791128ms)","trace[763701868] 'applied index is now lower than readState.Index' (duration: 112.792µs)"],"step_count":2} {"level":"warn","ts":"2026-07-30T23:45:13.263533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.111445ms","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-07-30T23:45:13.263566Z","caller":"traceutil/trace.go:171","msg":"trace[2103342609] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2755; }","duration":"113.199567ms","start":"2026-07-30T23:45:13.150355Z","end":"2026-07-30T23:45:13.263555Z","steps":["trace[2103342609] 'agreement among raft nodes before linearized reading' (duration: 113.056244ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:45:13.263691Z","caller":"traceutil/trace.go:171","msg":"trace[41085292] transaction","detail":"{read_only:false; response_revision:2755; number_of_response:1; }","duration":"148.224102ms","start":"2026-07-30T23:45:13.115455Z","end":"2026-07-30T23:45:13.263679Z","steps":["trace[41085292] 'process raft request' (duration: 147.665479ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:45:14.023269Z","caller":"traceutil/trace.go:171","msg":"trace[519356312] transaction","detail":"{read_only:false; response_revision:2757; number_of_response:1; }","duration":"202.468103ms","start":"2026-07-30T23:45:13.82077Z","end":"2026-07-30T23:45:14.023238Z","steps":["trace[519356312] 'process raft request' (duration: 202.264078ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:45:14.024789Z","caller":"traceutil/trace.go:171","msg":"trace[1681591518] transaction","detail":"{read_only:false; response_revision:2758; number_of_response:1; }","duration":"203.981417ms","start":"2026-07-30T23:45:13.820781Z","end":"2026-07-30T23:45:14.024762Z","steps":["trace[1681591518] 'process raft request' (duration: 203.801003ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:45:14.025004Z","caller":"traceutil/trace.go:171","msg":"trace[1505072302] transaction","detail":"{read_only:false; response_revision:2759; number_of_response:1; }","duration":"203.549428ms","start":"2026-07-30T23:45:13.821441Z","end":"2026-07-30T23:45:14.02499Z","steps":["trace[1505072302] 'process raft request' (duration: 203.271771ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:45:14.025147Z","caller":"traceutil/trace.go:171","msg":"trace[538095349] linearizableReadLoop","detail":"{readStateIndex:2865; appliedIndex:2862; }","duration":"156.275518ms","start":"2026-07-30T23:45:13.868845Z","end":"2026-07-30T23:45:14.02512Z","steps":["trace[538095349] 'read index received' (duration: 154.276671ms)","trace[538095349] 'applied index is now lower than readState.Index' (duration: 1.997326ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T23:45:14.025265Z","caller":"traceutil/trace.go:171","msg":"trace[302551077] transaction","detail":"{read_only:false; response_revision:2760; number_of_response:1; }","duration":"144.061174ms","start":"2026-07-30T23:45:13.881105Z","end":"2026-07-30T23:45:14.025166Z","steps":["trace[302551077] 'process raft request' (duration: 143.836247ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:45:14.025438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.379665ms","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-07-30T23:45:14.025488Z","caller":"traceutil/trace.go:171","msg":"trace[1120279313] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2760; }","duration":"106.479018ms","start":"2026-07-30T23:45:13.918995Z","end":"2026-07-30T23:45:14.025474Z","steps":["trace[1120279313] 'agreement among raft nodes before linearized reading' (duration: 106.310414ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:45:14.025485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.590155ms","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-07-30T23:45:14.025605Z","caller":"traceutil/trace.go:171","msg":"trace[1575251254] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2760; }","duration":"156.749859ms","start":"2026-07-30T23:45:13.868813Z","end":"2026-07-30T23:45:14.025562Z","steps":["trace[1575251254] 'agreement among raft nodes before linearized reading' (duration: 156.477102ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:45:40.30655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.488972ms","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-07-30T23:45:40.306651Z","caller":"traceutil/trace.go:171","msg":"trace[956118572] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2907; }","duration":"109.639055ms","start":"2026-07-30T23:45:40.196988Z","end":"2026-07-30T23:45:40.306627Z","steps":["trace[956118572] 'range keys from in-memory index tree' (duration: 109.345799ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:45:46.368398Z","caller":"traceutil/trace.go:171","msg":"trace[191310068] transaction","detail":"{read_only:false; response_revision:2937; number_of_response:1; }","duration":"200.879648ms","start":"2026-07-30T23:45:46.167486Z","end":"2026-07-30T23:45:46.368365Z","steps":["trace[191310068] 'process raft request' (duration: 197.529798ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:45:46.368406Z","caller":"traceutil/trace.go:171","msg":"trace[1246524061] transaction","detail":"{read_only:false; response_revision:2938; number_of_response:1; }","duration":"193.914403ms","start":"2026-07-30T23:45:46.174461Z","end":"2026-07-30T23:45:46.368375Z","steps":["trace[1246524061] 'process raft request' (duration: 193.800601ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:45:46.368725Z","caller":"traceutil/trace.go:171","msg":"trace[1922801100] transaction","detail":"{read_only:false; response_revision:2939; number_of_response:1; }","duration":"193.477814ms","start":"2026-07-30T23:45:46.175221Z","end":"2026-07-30T23:45:46.368699Z","steps":["trace[1922801100] 'process raft request' (duration: 193.117726ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:45:47.68897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"909.311126ms","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-07-30T23:45:47.6891Z","caller":"traceutil/trace.go:171","msg":"trace[1315131112] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:2940; }","duration":"909.455639ms","start":"2026-07-30T23:45:46.779624Z","end":"2026-07-30T23:45:47.68908Z","steps":["trace[1315131112] 'range keys from in-memory index tree' (duration: 909.294255ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:45:47.689593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"987.061095ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-30T23:45:47.689673Z","caller":"traceutil/trace.go:171","msg":"trace[1186239814] transaction","detail":"{read_only:false; response_revision:2941; number_of_response:1; }","duration":"1.265899633s","start":"2026-07-30T23:45:46.42376Z","end":"2026-07-30T23:45:47.689659Z","steps":["trace[1186239814] 'process raft request' (duration: 278.714087ms)","trace[1186239814] 'compare' (duration: 986.306688ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T23:45:47.689829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:45:46.42375Z","time spent":"1.266051656s","remote":"127.0.0.1:60960","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-07-30T23:45:47.770556Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.067993882s","expected-duration":"1s"} {"level":"info","ts":"2026-07-30T23:45:47.772232Z","caller":"traceutil/trace.go:171","msg":"trace[897318489] transaction","detail":"{read_only:false; response_revision:2942; number_of_response:1; }","duration":"1.346016023s","start":"2026-07-30T23:45:46.426194Z","end":"2026-07-30T23:45:47.77221Z","steps":["trace[897318489] 'process raft request' (duration: 1.345678276s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:45:47.772359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:45:46.426182Z","time spent":"1.346110745s","remote":"127.0.0.1:60960","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-07-30T23:45:47.772431Z","caller":"traceutil/trace.go:171","msg":"trace[1794687991] transaction","detail":"{read_only:false; response_revision:2943; number_of_response:1; }","duration":"1.346084455s","start":"2026-07-30T23:45:46.426303Z","end":"2026-07-30T23:45:47.772387Z","steps":["trace[1794687991] 'process raft request' (duration: 1.345683077s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:45:47.772595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:45:46.42627Z","time spent":"1.346224137s","remote":"127.0.0.1:60960","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-07-30T23:45:48.167671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.638302ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-07-30T23:45:48.167848Z","caller":"traceutil/trace.go:171","msg":"trace[182573271] linearizableReadLoop","detail":"{readStateIndex:3056; appliedIndex:3052; }","duration":"1.625791096s","start":"2026-07-30T23:45:46.542044Z","end":"2026-07-30T23:45:48.167835Z","steps":["trace[182573271] 'read index received' (duration: 160.379416ms)","trace[182573271] 'applied index is now lower than readState.Index' (duration: 1.46541096s)"],"step_count":2} {"level":"warn","ts":"2026-07-30T23:45:48.168126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.626073861s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T23:45:48.168154Z","caller":"traceutil/trace.go:171","msg":"trace[1362816488] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2943; }","duration":"1.626141544s","start":"2026-07-30T23:45:46.542005Z","end":"2026-07-30T23:45:48.168146Z","steps":["trace[1362816488] 'agreement among raft nodes before linearized reading' (duration: 1.626080542s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:45:48.168177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:45:46.541983Z","time spent":"1.626188465s","remote":"127.0.0.1:60670","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-07-30T23:45:48.168484Z","caller":"traceutil/trace.go:171","msg":"trace[140209379] transaction","detail":"{read_only:false; response_revision:2944; number_of_response:1; }","duration":"725.443869ms","start":"2026-07-30T23:45:47.443011Z","end":"2026-07-30T23:45:48.168455Z","steps":["trace[140209379] 'process raft request' (duration: 724.743644ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:45:48.169139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:45:47.442982Z","time spent":"725.584073ms","remote":"127.0.0.1:60960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T23:45:48.174733Z","caller":"traceutil/trace.go:171","msg":"trace[1733715592] transaction","detail":"{read_only:false; response_revision:2945; number_of_response:1; }","duration":"303.712477ms","start":"2026-07-30T23:45:47.871009Z","end":"2026-07-30T23:45:48.174722Z","steps":["trace[1733715592] 'process raft request' (duration: 297.430537ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:45:48.17475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"879.82814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-07-30T23:45:48.174807Z","caller":"traceutil/trace.go:171","msg":"trace[446905444] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2945; }","duration":"879.955862ms","start":"2026-07-30T23:45:47.294835Z","end":"2026-07-30T23:45:48.174791Z","steps":["trace[446905444] 'agreement among raft nodes before linearized reading' (duration: 879.767548ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:45:48.174822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:45:47.870974Z","time spent":"303.806148ms","remote":"127.0.0.1:60960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":514,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T23:45:48.174816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.436529269s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-30T23:45:48.174898Z","caller":"traceutil/trace.go:171","msg":"trace[877864741] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:2945; }","duration":"1.436676602s","start":"2026-07-30T23:45:46.738205Z","end":"2026-07-30T23:45:48.174882Z","steps":["trace[877864741] 'agreement among raft nodes before linearized reading' (duration: 1.436541649s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:45:48.174923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:45:47.294802Z","time spent":"880.044604ms","remote":"127.0.0.1:60892","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":1393,"request content":"key:\"/registry/services/specs/auth-system/keycloak-headless\" "} {"level":"warn","ts":"2026-07-30T23:45:48.174947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:45:46.738182Z","time spent":"1.436754824s","remote":"127.0.0.1:32916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":31,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-07-30T23:45:48.175182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.600235993s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/auth-system/keycloak-0.18c735fa55c20662\" ","response":"range_response_count:1 size:781"} {"level":"info","ts":"2026-07-30T23:45:48.175212Z","caller":"traceutil/trace.go:171","msg":"trace[51817141] range","detail":"{range_begin:/registry/events/auth-system/keycloak-0.18c735fa55c20662; range_end:; response_count:1; response_revision:2945; }","duration":"1.600321595s","start":"2026-07-30T23:45:46.574883Z","end":"2026-07-30T23:45:48.175205Z","steps":["trace[51817141] 'agreement among raft nodes before linearized reading' (duration: 1.600233433s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:45:48.175229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:45:46.574853Z","time spent":"1.600372486s","remote":"127.0.0.1:60736","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.18c735fa55c20662\" "} {"level":"warn","ts":"2026-07-30T23:45:48.175561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"709.722303ms","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-07-30T23:45:48.175626Z","caller":"traceutil/trace.go:171","msg":"trace[1363408044] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2945; }","duration":"709.839305ms","start":"2026-07-30T23:45:47.465774Z","end":"2026-07-30T23:45:48.175614Z","steps":["trace[1363408044] 'agreement among raft nodes before linearized reading' (duration: 709.402706ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:45:48.175724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:45:47.465761Z","time spent":"709.942648ms","remote":"127.0.0.1:60960","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-07-30T23:48:14.977158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.411093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/challenges/\" range_end:\"/registry/acme.cert-manager.io/challenges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T23:48:14.977277Z","caller":"traceutil/trace.go:171","msg":"trace[246958797] range","detail":"{range_begin:/registry/acme.cert-manager.io/challenges/; range_end:/registry/acme.cert-manager.io/challenges0; response_count:0; response_revision:3807; }","duration":"109.551775ms","start":"2026-07-30T23:48:14.867707Z","end":"2026-07-30T23:48:14.977259Z","steps":["trace[246958797] 'count revisions from in-memory index tree' (duration: 109.343912ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:49:15.449449Z","caller":"traceutil/trace.go:171","msg":"trace[1239654735] transaction","detail":"{read_only:false; response_revision:4205; number_of_response:1; }","duration":"110.449138ms","start":"2026-07-30T23:49:15.33897Z","end":"2026-07-30T23:49:15.449419Z","steps":["trace[1239654735] 'process raft request' (duration: 105.098733ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:49:31.221156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.322942ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-30T23:49:31.221325Z","caller":"traceutil/trace.go:171","msg":"trace[1778696623] linearizableReadLoop","detail":"{readStateIndex:4863; appliedIndex:4862; }","duration":"688.062422ms","start":"2026-07-30T23:49:30.533233Z","end":"2026-07-30T23:49:31.221296Z","steps":["trace[1778696623] 'read index received' (duration: 486.525808ms)","trace[1778696623] 'applied index is now lower than readState.Index' (duration: 201.535064ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T23:49:31.221419Z","caller":"traceutil/trace.go:171","msg":"trace[503908160] transaction","detail":"{read_only:false; response_revision:4682; number_of_response:1; }","duration":"696.760452ms","start":"2026-07-30T23:49:30.524646Z","end":"2026-07-30T23:49:31.221407Z","steps":["trace[503908160] 'process raft request' (duration: 495.080845ms)","trace[503908160] 'compare' (duration: 201.076259ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T23:49:31.221489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:49:30.524588Z","time spent":"696.873604ms","remote":"127.0.0.1:60876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2693,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-30T23:49:31.221613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"688.357456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config\" ","response":"range_response_count:1 size:708"} {"level":"info","ts":"2026-07-30T23:49:31.22171Z","caller":"traceutil/trace.go:171","msg":"trace[1425169077] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config; range_end:; response_count:1; response_revision:4682; }","duration":"688.458808ms","start":"2026-07-30T23:49:30.533219Z","end":"2026-07-30T23:49:31.221678Z","steps":["trace[1425169077] 'agreement among raft nodes before linearized reading' (duration: 688.205024ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:49:31.221745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:49:30.533212Z","time spent":"688.525958ms","remote":"127.0.0.1:60756","response type":"/etcdserverpb.KV/Range","request count":0,"request size":89,"response count":1,"response size":732,"request content":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config\" "} {"level":"info","ts":"2026-07-30T23:49:31.257727Z","caller":"traceutil/trace.go:171","msg":"trace[58686377] transaction","detail":"{read_only:false; response_revision:4683; number_of_response:1; }","duration":"724.336973ms","start":"2026-07-30T23:49:30.533362Z","end":"2026-07-30T23:49:31.257699Z","steps":["trace[58686377] 'process raft request' (duration: 724.08185ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:49:31.257769Z","caller":"traceutil/trace.go:171","msg":"trace[1510314400] transaction","detail":"{read_only:false; response_revision:4685; number_of_response:1; }","duration":"711.522276ms","start":"2026-07-30T23:49:30.546222Z","end":"2026-07-30T23:49:31.257744Z","steps":["trace[1510314400] 'process raft request' (duration: 711.460385ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:49:31.257873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:49:30.533328Z","time spent":"724.458874ms","remote":"127.0.0.1:60960","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-07-30T23:49:31.25794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:49:30.546201Z","time spent":"711.661547ms","remote":"127.0.0.1:60822","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4721,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T23:49:31.257992Z","caller":"traceutil/trace.go:171","msg":"trace[80059514] transaction","detail":"{read_only:false; response_revision:4684; number_of_response:1; }","duration":"724.142621ms","start":"2026-07-30T23:49:30.533838Z","end":"2026-07-30T23:49:31.257981Z","steps":["trace[80059514] 'process raft request' (duration: 723.784786ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:49:31.25811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:49:30.533827Z","time spent":"724.230492ms","remote":"127.0.0.1:32928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5430,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T23:49:31.260605Z","caller":"traceutil/trace.go:171","msg":"trace[1045509712] transaction","detail":"{read_only:false; response_revision:4686; number_of_response:1; }","duration":"165.72844ms","start":"2026-07-30T23:49:31.094842Z","end":"2026-07-30T23:49:31.260571Z","steps":["trace[1045509712] 'process raft request' (duration: 165.460606ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:49:31.261109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"722.66589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-30T23:49:31.261074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.559762ms","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-07-30T23:49:31.261149Z","caller":"traceutil/trace.go:171","msg":"trace[855561826] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4686; }","duration":"722.727081ms","start":"2026-07-30T23:49:30.538411Z","end":"2026-07-30T23:49:31.261139Z","steps":["trace[855561826] 'agreement among raft nodes before linearized reading' (duration: 722.67413ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:49:31.261175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:49:30.538401Z","time spent":"722.768591ms","remote":"127.0.0.1:60654","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-07-30T23:49:31.261184Z","caller":"traceutil/trace.go:171","msg":"trace[2145660273] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4686; }","duration":"182.731865ms","start":"2026-07-30T23:49:31.078428Z","end":"2026-07-30T23:49:31.26116Z","steps":["trace[2145660273] 'agreement among raft nodes before linearized reading' (duration: 182.436551ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:49:31.262838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"600.122126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:46 size:1954231"} {"level":"info","ts":"2026-07-30T23:49:31.2629Z","caller":"traceutil/trace.go:171","msg":"trace[554263779] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:46; response_revision:4686; }","duration":"600.241998ms","start":"2026-07-30T23:49:30.662642Z","end":"2026-07-30T23:49:31.262884Z","steps":["trace[554263779] 'agreement among raft nodes before linearized reading' (duration: 598.466953ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:49:31.262935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:49:30.662608Z","time spent":"600.316949ms","remote":"127.0.0.1:60770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":46,"response size":1954255,"request content":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" "} {"level":"warn","ts":"2026-07-30T23:49:31.265357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.210395ms","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-07-30T23:49:31.26546Z","caller":"traceutil/trace.go:171","msg":"trace[1792291881] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4686; }","duration":"345.93345ms","start":"2026-07-30T23:49:30.919503Z","end":"2026-07-30T23:49:31.265436Z","steps":["trace[1792291881] 'agreement among raft nodes before linearized reading' (duration: 341.197885ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:49:31.265542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:49:30.91947Z","time spent":"346.038561ms","remote":"127.0.0.1:60960","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":"info","ts":"2026-07-30T23:49:31.400632Z","caller":"traceutil/trace.go:171","msg":"trace[2016170994] transaction","detail":"{read_only:false; response_revision:4692; number_of_response:1; }","duration":"100.480789ms","start":"2026-07-30T23:49:31.300128Z","end":"2026-07-30T23:49:31.400609Z","steps":["trace[2016170994] 'process raft request' (duration: 86.978432ms)","trace[2016170994] 'compare' (duration: 13.252084ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T23:49:31.820941Z","caller":"traceutil/trace.go:171","msg":"trace[222650650] transaction","detail":"{read_only:false; response_revision:4699; number_of_response:1; }","duration":"329.056927ms","start":"2026-07-30T23:49:31.491825Z","end":"2026-07-30T23:49:31.820882Z","steps":["trace[222650650] 'process raft request' (duration: 229.165616ms)","trace[222650650] 'compare' (duration: 99.566877ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T23:49:31.821098Z","caller":"traceutil/trace.go:171","msg":"trace[939656621] transaction","detail":"{read_only:false; response_revision:4700; number_of_response:1; }","duration":"291.773301ms","start":"2026-07-30T23:49:31.529284Z","end":"2026-07-30T23:49:31.821058Z","steps":["trace[939656621] 'process raft request' (duration: 291.605869ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:49:31.821176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:49:31.491793Z","time spent":"329.27214ms","remote":"127.0.0.1:60756","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1327,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-30T23:49:31.821321Z","caller":"traceutil/trace.go:171","msg":"trace[860562250] linearizableReadLoop","detail":"{readStateIndex:4881; appliedIndex:4880; }","duration":"327.14742ms","start":"2026-07-30T23:49:31.494136Z","end":"2026-07-30T23:49:31.821284Z","steps":["trace[860562250] 'read index received' (duration: 226.837784ms)","trace[860562250] 'applied index is now lower than readState.Index' (duration: 100.306076ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T23:49:31.821631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.496386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:3958"} {"level":"info","ts":"2026-07-30T23:49:31.821674Z","caller":"traceutil/trace.go:171","msg":"trace[534983445] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:4700; }","duration":"327.599746ms","start":"2026-07-30T23:49:31.494057Z","end":"2026-07-30T23:49:31.821657Z","steps":["trace[534983445] 'agreement among raft nodes before linearized reading' (duration: 327.378743ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:49:31.821688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.118644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-30T23:49:31.821711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:49:31.493993Z","time spent":"327.709498ms","remote":"127.0.0.1:60756","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3982,"request content":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager\" "} {"level":"info","ts":"2026-07-30T23:49:31.821747Z","caller":"traceutil/trace.go:171","msg":"trace[1466412567] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4700; }","duration":"281.256855ms","start":"2026-07-30T23:49:31.540473Z","end":"2026-07-30T23:49:31.82173Z","steps":["trace[1466412567] 'agreement among raft nodes before linearized reading' (duration: 281.140224ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:49:31.821792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.285118ms","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-07-30T23:49:31.821829Z","caller":"traceutil/trace.go:171","msg":"trace[388137514] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4700; }","duration":"292.354619ms","start":"2026-07-30T23:49:31.529463Z","end":"2026-07-30T23:49:31.821818Z","steps":["trace[388137514] 'agreement among raft nodes before linearized reading' (duration: 292.264988ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:49:31.822062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.903785ms","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-07-30T23:49:31.822101Z","caller":"traceutil/trace.go:171","msg":"trace[220515407] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4700; }","duration":"231.987815ms","start":"2026-07-30T23:49:31.590103Z","end":"2026-07-30T23:49:31.822091Z","steps":["trace[220515407] 'agreement among raft nodes before linearized reading' (duration: 231.869194ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:49:31.821688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.504382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:4736"} {"level":"info","ts":"2026-07-30T23:49:31.822235Z","caller":"traceutil/trace.go:171","msg":"trace[1568075299] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:4700; }","duration":"322.095701ms","start":"2026-07-30T23:49:31.500117Z","end":"2026-07-30T23:49:31.822212Z","steps":["trace[1568075299] 'agreement among raft nodes before linearized reading' (duration: 321.411502ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:49:31.822345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:49:31.500096Z","time spent":"322.221652ms","remote":"127.0.0.1:60822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":93,"response count":1,"response size":4760,"request content":"key:\"/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager\" "} {"level":"warn","ts":"2026-07-30T23:49:31.822152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.158976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/local-path-storage/local-path-provisioner\" ","response":"range_response_count:1 size:897"} {"level":"info","ts":"2026-07-30T23:49:31.825384Z","caller":"traceutil/trace.go:171","msg":"trace[1541216753] range","detail":"{range_begin:/registry/serviceaccounts/local-path-storage/local-path-provisioner; range_end:; response_count:1; response_revision:4700; }","duration":"321.37204ms","start":"2026-07-30T23:49:31.503942Z","end":"2026-07-30T23:49:31.825314Z","steps":["trace[1541216753] 'agreement among raft nodes before linearized reading' (duration: 318.083314ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:49:31.825572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:49:31.503916Z","time spent":"321.577802ms","remote":"127.0.0.1:60904","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":1,"response size":921,"request content":"key:\"/registry/serviceaccounts/local-path-storage/local-path-provisioner\" "} {"level":"info","ts":"2026-07-30T23:49:32.028205Z","caller":"traceutil/trace.go:171","msg":"trace[1343472710] transaction","detail":"{read_only:false; response_revision:4704; number_of_response:1; }","duration":"124.257378ms","start":"2026-07-30T23:49:31.903913Z","end":"2026-07-30T23:49:32.02817Z","steps":["trace[1343472710] 'process raft request' (duration: 39.415816ms)","trace[1343472710] 'compare' (duration: 84.431996ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T23:49:32.028784Z","caller":"traceutil/trace.go:171","msg":"trace[530607056] linearizableReadLoop","detail":"{readStateIndex:4886; appliedIndex:4885; }","duration":"122.234029ms","start":"2026-07-30T23:49:31.906345Z","end":"2026-07-30T23:49:32.028579Z","steps":["trace[530607056] 'read index received' (duration: 36.975512ms)","trace[530607056] 'applied index is now lower than readState.Index' (duration: 85.200076ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T23:49:32.029197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.562444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0\" ","response":"range_response_count:1 size:650"} {"level":"info","ts":"2026-07-30T23:49:32.030299Z","caller":"traceutil/trace.go:171","msg":"trace[1421551218] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0; range_end:; response_count:1; response_revision:4704; }","duration":"122.959129ms","start":"2026-07-30T23:49:31.906321Z","end":"2026-07-30T23:49:32.02928Z","steps":["trace[1421551218] 'agreement among raft nodes before linearized reading' (duration: 122.364581ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:49:36.825583Z","caller":"traceutil/trace.go:171","msg":"trace[1242944559] linearizableReadLoop","detail":"{readStateIndex:5023; appliedIndex:5021; }","duration":"108.035186ms","start":"2026-07-30T23:49:36.717527Z","end":"2026-07-30T23:49:36.825562Z","steps":["trace[1242944559] 'read index received' (duration: 23.439412ms)","trace[1242944559] 'applied index is now lower than readState.Index' (duration: 84.595024ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T23:49:36.825649Z","caller":"traceutil/trace.go:171","msg":"trace[78590497] transaction","detail":"{read_only:false; response_revision:4835; number_of_response:1; }","duration":"108.079377ms","start":"2026-07-30T23:49:36.717504Z","end":"2026-07-30T23:49:36.825584Z","steps":["trace[78590497] 'process raft request' (duration: 94.809205ms)","trace[78590497] 'compare' (duration: 13.025009ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T23:49:36.825692Z","caller":"traceutil/trace.go:171","msg":"trace[1269123459] transaction","detail":"{read_only:false; response_revision:4836; number_of_response:1; }","duration":"105.105805ms","start":"2026-07-30T23:49:36.720574Z","end":"2026-07-30T23:49:36.82568Z","steps":["trace[1269123459] 'process raft request' (duration: 104.918043ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:49:36.825978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.418142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-prometheus-node-exporter-jc2m2-tls-pmxww\" ","response":"range_response_count:1 size:2625"} {"level":"info","ts":"2026-07-30T23:49:36.82605Z","caller":"traceutil/trace.go:171","msg":"trace[1769310885] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-prometheus-node-exporter-jc2m2-tls-pmxww; range_end:; response_count:1; response_revision:4836; }","duration":"108.542454ms","start":"2026-07-30T23:49:36.71749Z","end":"2026-07-30T23:49:36.826033Z","steps":["trace[1769310885] 'agreement among raft nodes before linearized reading' (duration: 108.351711ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:49:36.836955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.065158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-07-30T23:49:36.837041Z","caller":"traceutil/trace.go:171","msg":"trace[86753006] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:4837; }","duration":"119.16565ms","start":"2026-07-30T23:49:36.717856Z","end":"2026-07-30T23:49:36.837022Z","steps":["trace[86753006] 'agreement among raft nodes before linearized reading' (duration: 118.905966ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:49:37.0705Z","caller":"traceutil/trace.go:171","msg":"trace[1594727996] linearizableReadLoop","detail":"{readStateIndex:5026; appliedIndex:5025; }","duration":"231.573696ms","start":"2026-07-30T23:49:36.838894Z","end":"2026-07-30T23:49:37.070468Z","steps":["trace[1594727996] 'read index received' (duration: 182.41613ms)","trace[1594727996] 'applied index is now lower than readState.Index' (duration: 49.156706ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T23:49:37.070524Z","caller":"traceutil/trace.go:171","msg":"trace[1232943117] transaction","detail":"{read_only:false; number_of_response:1; response_revision:4838; }","duration":"242.934092ms","start":"2026-07-30T23:49:36.827553Z","end":"2026-07-30T23:49:37.070487Z","steps":["trace[1232943117] 'process raft request' (duration: 193.469862ms)","trace[1232943117] 'compare' (duration: 49.167276ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T23:49:37.070931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.192607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:3958"} {"level":"info","ts":"2026-07-30T23:49:37.070981Z","caller":"traceutil/trace.go:171","msg":"trace[1395525758] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:4839; }","duration":"230.299119ms","start":"2026-07-30T23:49:36.840667Z","end":"2026-07-30T23:49:37.070966Z","steps":["trace[1395525758] 'agreement among raft nodes before linearized reading' (duration: 230.100836ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:49:37.071424Z","caller":"traceutil/trace.go:171","msg":"trace[1027054155] transaction","detail":"{read_only:false; response_revision:4839; number_of_response:1; }","duration":"153.794736ms","start":"2026-07-30T23:49:36.917616Z","end":"2026-07-30T23:49:37.071411Z","steps":["trace[1027054155] 'process raft request' (duration: 152.793232ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:49:37.071212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.252805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:12973"} {"level":"info","ts":"2026-07-30T23:49:37.072181Z","caller":"traceutil/trace.go:171","msg":"trace[2110528720] range","detail":"{range_begin:/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4839; }","duration":"233.165318ms","start":"2026-07-30T23:49:36.838842Z","end":"2026-07-30T23:49:37.072007Z","steps":["trace[2110528720] 'agreement among raft nodes before linearized reading' (duration: 231.83523ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:49:37.072522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.594355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2207411"} {"level":"info","ts":"2026-07-30T23:49:37.072587Z","caller":"traceutil/trace.go:171","msg":"trace[1518699871] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4839; }","duration":"164.721386ms","start":"2026-07-30T23:49:36.90785Z","end":"2026-07-30T23:49:37.072572Z","steps":["trace[1518699871] 'agreement among raft nodes before linearized reading' (duration: 163.367987ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:49:37.209584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.509934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1817"} {"level":"info","ts":"2026-07-30T23:49:37.209702Z","caller":"traceutil/trace.go:171","msg":"trace[956886865] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:4841; }","duration":"107.911674ms","start":"2026-07-30T23:49:37.101764Z","end":"2026-07-30T23:49:37.209676Z","steps":["trace[956886865] 'agreement among raft nodes before linearized reading' (duration: 103.426332ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:49:37.486868Z","caller":"traceutil/trace.go:171","msg":"trace[784111069] linearizableReadLoop","detail":"{readStateIndex:5031; appliedIndex:5030; }","duration":"221.156781ms","start":"2026-07-30T23:49:37.265686Z","end":"2026-07-30T23:49:37.486843Z","steps":["trace[784111069] 'read index received' (duration: 221.039569ms)","trace[784111069] 'applied index is now lower than readState.Index' (duration: 116.002µs)"],"step_count":2} {"level":"info","ts":"2026-07-30T23:49:37.487022Z","caller":"traceutil/trace.go:171","msg":"trace[381014638] transaction","detail":"{read_only:false; response_revision:4843; number_of_response:1; }","duration":"221.429705ms","start":"2026-07-30T23:49:37.265546Z","end":"2026-07-30T23:49:37.486975Z","steps":["trace[381014638] 'process raft request' (duration: 221.139911ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:49:37.487296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.584528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0\" ","response":"range_response_count:1 size:1342"} {"level":"info","ts":"2026-07-30T23:49:37.487352Z","caller":"traceutil/trace.go:171","msg":"trace[819117326] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0; range_end:; response_count:1; response_revision:4843; }","duration":"221.669208ms","start":"2026-07-30T23:49:37.265667Z","end":"2026-07-30T23:49:37.487336Z","steps":["trace[819117326] 'agreement among raft nodes before linearized reading' (duration: 221.304133ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:49:37.496467Z","caller":"traceutil/trace.go:171","msg":"trace[643340332] transaction","detail":"{read_only:false; response_revision:4844; number_of_response:1; }","duration":"214.465149ms","start":"2026-07-30T23:49:37.28197Z","end":"2026-07-30T23:49:37.496435Z","steps":["trace[643340332] 'process raft request' (duration: 214.292337ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:49:37.496833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.526663ms","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-07-30T23:49:37.496831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.648096ms","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-07-30T23:49:37.496892Z","caller":"traceutil/trace.go:171","msg":"trace[2054083292] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4846; }","duration":"175.637435ms","start":"2026-07-30T23:49:37.321242Z","end":"2026-07-30T23:49:37.49688Z","steps":["trace[2054083292] 'agreement among raft nodes before linearized reading' (duration: 175.474413ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:49:37.496862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.024539ms","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-07-30T23:49:37.496949Z","caller":"traceutil/trace.go:171","msg":"trace[1818704185] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4846; }","duration":"186.789199ms","start":"2026-07-30T23:49:37.310111Z","end":"2026-07-30T23:49:37.4969Z","steps":["trace[1818704185] 'agreement among raft nodes before linearized reading' (duration: 186.544185ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:49:37.496984Z","caller":"traceutil/trace.go:171","msg":"trace[1381286355] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4846; }","duration":"189.217101ms","start":"2026-07-30T23:49:37.307756Z","end":"2026-07-30T23:49:37.496973Z","steps":["trace[1381286355] 'agreement among raft nodes before linearized reading' (duration: 188.920398ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:49:37.496858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.58448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/alertmanager-operated\" ","response":"range_response_count:1 size:1285"} {"level":"info","ts":"2026-07-30T23:49:37.497029Z","caller":"traceutil/trace.go:171","msg":"trace[723780010] range","detail":"{range_begin:/registry/services/specs/monitoring/alertmanager-operated; range_end:; response_count:1; response_revision:4846; }","duration":"230.772043ms","start":"2026-07-30T23:49:37.266243Z","end":"2026-07-30T23:49:37.497015Z","steps":["trace[723780010] 'agreement among raft nodes before linearized reading' (duration: 230.5243ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:49:37.497034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.751994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-07-30T23:49:37.497118Z","caller":"traceutil/trace.go:171","msg":"trace[523822860] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4846; }","duration":"193.854405ms","start":"2026-07-30T23:49:37.303244Z","end":"2026-07-30T23:49:37.497098Z","steps":["trace[523822860] 'agreement among raft nodes before linearized reading' (duration: 193.680673ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:49:43.716776Z","caller":"traceutil/trace.go:171","msg":"trace[193379907] linearizableReadLoop","detail":"{readStateIndex:5137; appliedIndex:5136; }","duration":"166.328736ms","start":"2026-07-30T23:49:43.550425Z","end":"2026-07-30T23:49:43.716753Z","steps":["trace[193379907] 'read index received' (duration: 166.228755ms)","trace[193379907] 'applied index is now lower than readState.Index' (duration: 98.751µs)"],"step_count":2} {"level":"info","ts":"2026-07-30T23:49:43.716884Z","caller":"traceutil/trace.go:171","msg":"trace[2068714052] transaction","detail":"{read_only:false; response_revision:4947; number_of_response:1; }","duration":"166.533159ms","start":"2026-07-30T23:49:43.550327Z","end":"2026-07-30T23:49:43.71686Z","steps":["trace[2068714052] 'process raft request' (duration: 166.260746ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:49:43.717145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.833173ms","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-07-30T23:49:43.717306Z","caller":"traceutil/trace.go:171","msg":"trace[1742126087] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4947; }","duration":"167.010516ms","start":"2026-07-30T23:49:43.550277Z","end":"2026-07-30T23:49:43.717287Z","steps":["trace[1742126087] 'agreement among raft nodes before linearized reading' (duration: 166.63066ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:49:44.196542Z","caller":"traceutil/trace.go:171","msg":"trace[1643072554] transaction","detail":"{read_only:false; response_revision:4948; number_of_response:1; }","duration":"646.023141ms","start":"2026-07-30T23:49:43.550491Z","end":"2026-07-30T23:49:44.196514Z","steps":["trace[1643072554] 'process raft request' (duration: 639.796065ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:49:44.196689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:49:43.550472Z","time spent":"646.144313ms","remote":"127.0.0.1:60960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T23:49:44.19683Z","caller":"traceutil/trace.go:171","msg":"trace[1063966090] linearizableReadLoop","detail":"{readStateIndex:5140; appliedIndex:5137; }","duration":"479.972949ms","start":"2026-07-30T23:49:43.716846Z","end":"2026-07-30T23:49:44.196819Z","steps":["trace[1063966090] 'read index received' (duration: 473.457789ms)","trace[1063966090] 'applied index is now lower than readState.Index' (duration: 6.51421ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T23:49:44.197207Z","caller":"traceutil/trace.go:171","msg":"trace[1367445769] transaction","detail":"{read_only:false; response_revision:4950; number_of_response:1; }","duration":"643.086811ms","start":"2026-07-30T23:49:43.55411Z","end":"2026-07-30T23:49:44.197197Z","steps":["trace[1367445769] 'process raft request' (duration: 642.519543ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:49:44.197268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:49:43.554092Z","time spent":"643.141091ms","remote":"127.0.0.1:60960","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-07-30T23:49:44.197461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"646.287384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T23:49:44.197498Z","caller":"traceutil/trace.go:171","msg":"trace[1501738934] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4950; }","duration":"646.330736ms","start":"2026-07-30T23:49:43.551158Z","end":"2026-07-30T23:49:44.197489Z","steps":["trace[1501738934] 'agreement among raft nodes before linearized reading' (duration: 646.260275ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:49:44.197489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"566.465401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-28561266-6b6b-4440-9051-f18bbade2a91\" ","response":"range_response_count:1 size:3645"} {"level":"warn","ts":"2026-07-30T23:49:44.197528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:49:43.551152Z","time spent":"646.370546ms","remote":"127.0.0.1:60670","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-07-30T23:49:44.19756Z","caller":"traceutil/trace.go:171","msg":"trace[1367573033] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-28561266-6b6b-4440-9051-f18bbade2a91; range_end:; response_count:1; response_revision:4950; }","duration":"566.574882ms","start":"2026-07-30T23:49:43.630967Z","end":"2026-07-30T23:49:44.197542Z","steps":["trace[1367573033] 'agreement among raft nodes before linearized reading' (duration: 566.37056ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:49:44.197615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:49:43.630942Z","time spent":"566.664354ms","remote":"127.0.0.1:60876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3669,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-28561266-6b6b-4440-9051-f18bbade2a91\" "} {"level":"warn","ts":"2026-07-30T23:49:44.197737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"647.266788ms","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-07-30T23:49:44.197772Z","caller":"traceutil/trace.go:171","msg":"trace[230834877] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4950; }","duration":"647.308318ms","start":"2026-07-30T23:49:43.550455Z","end":"2026-07-30T23:49:44.197764Z","steps":["trace[230834877] 'agreement among raft nodes before linearized reading' (duration: 647.192937ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:49:44.197803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:49:43.55045Z","time spent":"647.345359ms","remote":"127.0.0.1:60960","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-07-30T23:49:44.198068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"566.34769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-grafana-5d74fcd549-q2tzp.18c73635f2e7ca81\" ","response":"range_response_count:1 size:898"} {"level":"info","ts":"2026-07-30T23:49:44.198113Z","caller":"traceutil/trace.go:171","msg":"trace[884350365] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-grafana-5d74fcd549-q2tzp.18c73635f2e7ca81; range_end:; response_count:1; response_revision:4950; }","duration":"566.419261ms","start":"2026-07-30T23:49:43.631683Z","end":"2026-07-30T23:49:44.198102Z","steps":["trace[884350365] 'agreement among raft nodes before linearized reading' (duration: 566.301439ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:49:44.198167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:49:43.631671Z","time spent":"566.488462ms","remote":"127.0.0.1:60736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":93,"response count":1,"response size":922,"request content":"key:\"/registry/events/monitoring/kube-prometheus-stack-grafana-5d74fcd549-q2tzp.18c73635f2e7ca81\" "} {"level":"info","ts":"2026-07-30T23:49:44.198691Z","caller":"traceutil/trace.go:171","msg":"trace[1948257036] transaction","detail":"{read_only:false; response_revision:4949; number_of_response:1; }","duration":"646.129793ms","start":"2026-07-30T23:49:43.550549Z","end":"2026-07-30T23:49:44.196679Z","steps":["trace[1948257036] 'process raft request' (duration: 645.90778ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:49:44.198784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:49:43.550544Z","time spent":"648.19692ms","remote":"127.0.0.1:60960","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-07-30T23:49:44.415022Z","caller":"traceutil/trace.go:171","msg":"trace[2096591501] linearizableReadLoop","detail":"{readStateIndex:5143; appliedIndex:5140; }","duration":"217.90489ms","start":"2026-07-30T23:49:44.197098Z","end":"2026-07-30T23:49:44.415003Z","steps":["trace[2096591501] 'read index received' (duration: 217.496484ms)","trace[2096591501] 'applied index is now lower than readState.Index' (duration: 406.906µs)"],"step_count":2} {"level":"info","ts":"2026-07-30T23:49:44.415202Z","caller":"traceutil/trace.go:171","msg":"trace[802919540] transaction","detail":"{read_only:false; response_revision:4951; number_of_response:1; }","duration":"694.156798ms","start":"2026-07-30T23:49:43.721019Z","end":"2026-07-30T23:49:44.415175Z","steps":["trace[802919540] 'process raft request' (duration: 693.442197ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:49:44.415344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:49:43.720997Z","time spent":"694.272569ms","remote":"127.0.0.1:60960","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-07-30T23:49:44.415442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"510.672236ms","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-07-30T23:49:44.415557Z","caller":"traceutil/trace.go:171","msg":"trace[1534862970] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4953; }","duration":"510.842168ms","start":"2026-07-30T23:49:43.904697Z","end":"2026-07-30T23:49:44.415539Z","steps":["trace[1534862970] 'agreement among raft nodes before linearized reading' (duration: 510.520154ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:49:44.415679Z","caller":"traceutil/trace.go:171","msg":"trace[777916415] transaction","detail":"{read_only:false; response_revision:4952; number_of_response:1; }","duration":"574.379848ms","start":"2026-07-30T23:49:43.841287Z","end":"2026-07-30T23:49:44.415666Z","steps":["trace[777916415] 'process raft request' (duration: 573.536277ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:49:44.415689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:49:43.904648Z","time spent":"511.003041ms","remote":"127.0.0.1:60960","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-07-30T23:49:44.415741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:49:43.841254Z","time spent":"574.457179ms","remote":"127.0.0.1:60860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10889,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T23:49:44.415971Z","caller":"traceutil/trace.go:171","msg":"trace[100568484] transaction","detail":"{read_only:false; response_revision:4953; number_of_response:1; }","duration":"524.87654ms","start":"2026-07-30T23:49:43.891084Z","end":"2026-07-30T23:49:44.41596Z","steps":["trace[100568484] 'process raft request' (duration: 523.841426ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:49:44.416036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:49:43.891061Z","time spent":"524.941692ms","remote":"127.0.0.1:60960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T23:49:44.416064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"478.477596ms","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-07-30T23:49:44.41614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"611.313953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/sshkeys/\" range_end:\"/registry/secretgen.k14s.io/sshkeys0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T23:49:44.41615Z","caller":"traceutil/trace.go:171","msg":"trace[34604256] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4953; }","duration":"478.601567ms","start":"2026-07-30T23:49:43.93753Z","end":"2026-07-30T23:49:44.416131Z","steps":["trace[34604256] 'agreement among raft nodes before linearized reading' (duration: 477.642584ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:49:44.41619Z","caller":"traceutil/trace.go:171","msg":"trace[764453166] range","detail":"{range_begin:/registry/secretgen.k14s.io/sshkeys/; range_end:/registry/secretgen.k14s.io/sshkeys0; response_count:0; response_revision:4953; }","duration":"611.397415ms","start":"2026-07-30T23:49:43.804779Z","end":"2026-07-30T23:49:44.416176Z","steps":["trace[764453166] 'agreement among raft nodes before linearized reading' (duration: 611.325044ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:49:44.416199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:49:43.937519Z","time spent":"478.671608ms","remote":"127.0.0.1:60960","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-07-30T23:49:44.416235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:49:43.804761Z","time spent":"611.458215ms","remote":"127.0.0.1:39280","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":29,"request content":"key:\"/registry/secretgen.k14s.io/sshkeys/\" range_end:\"/registry/secretgen.k14s.io/sshkeys0\" count_only:true "} {"level":"info","ts":"2026-07-30T23:49:44.470549Z","caller":"traceutil/trace.go:171","msg":"trace[2129054605] transaction","detail":"{read_only:false; response_revision:4954; number_of_response:1; }","duration":"268.041985ms","start":"2026-07-30T23:49:44.202467Z","end":"2026-07-30T23:49:44.470509Z","steps":["trace[2129054605] 'process raft request' (duration: 267.787972ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:49:44.470614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.852164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T23:49:44.47073Z","caller":"traceutil/trace.go:171","msg":"trace[1874753009] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4957; }","duration":"270.997346ms","start":"2026-07-30T23:49:44.199717Z","end":"2026-07-30T23:49:44.470714Z","steps":["trace[1874753009] 'agreement among raft nodes before linearized reading' (duration: 270.828044ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:49:44.471138Z","caller":"traceutil/trace.go:171","msg":"trace[202133544] transaction","detail":"{read_only:false; response_revision:4957; number_of_response:1; }","duration":"147.410367ms","start":"2026-07-30T23:49:44.323713Z","end":"2026-07-30T23:49:44.471123Z","steps":["trace[202133544] 'process raft request' (duration: 146.789477ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:49:44.471307Z","caller":"traceutil/trace.go:171","msg":"trace[2042134791] transaction","detail":"{read_only:false; response_revision:4956; number_of_response:1; }","duration":"266.410024ms","start":"2026-07-30T23:49:44.204886Z","end":"2026-07-30T23:49:44.471296Z","steps":["trace[2042134791] 'process raft request' (duration: 265.570162ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:49:44.471382Z","caller":"traceutil/trace.go:171","msg":"trace[44911657] transaction","detail":"{read_only:false; response_revision:4955; number_of_response:1; }","duration":"268.360331ms","start":"2026-07-30T23:49:44.20298Z","end":"2026-07-30T23:49:44.471341Z","steps":["trace[44911657] 'process raft request' (duration: 267.401967ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:50:00.777051Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16952569023813879874,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-30T23:50:01.277561Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16952569023813879874,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-30T23:50:01.455389Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.186937418s","expected-duration":"1s"} {"level":"info","ts":"2026-07-30T23:50:01.455734Z","caller":"traceutil/trace.go:171","msg":"trace[79008095] transaction","detail":"{read_only:false; response_revision:5153; number_of_response:1; }","duration":"1.187330773s","start":"2026-07-30T23:50:00.268375Z","end":"2026-07-30T23:50:01.455705Z","steps":["trace[79008095] 'process raft request' (duration: 1.187187021s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:50:01.455886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:50:00.268351Z","time spent":"1.187442304s","remote":"127.0.0.1:60960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T23:50:02.044495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:50:01.736541Z","time spent":"307.944972ms","remote":"127.0.0.1:38270","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-07-30T23:50:02.045042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.379279ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-30T23:50:02.045737Z","caller":"traceutil/trace.go:171","msg":"trace[324016276] transaction","detail":"{read_only:false; response_revision:5155; number_of_response:1; }","duration":"1.641661972s","start":"2026-07-30T23:50:00.404046Z","end":"2026-07-30T23:50:02.045708Z","steps":["trace[324016276] 'process raft request' (duration: 1.641207426s)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:50:02.045824Z","caller":"traceutil/trace.go:171","msg":"trace[263675100] transaction","detail":"{read_only:false; response_revision:5156; number_of_response:1; }","duration":"1.567161264s","start":"2026-07-30T23:50:00.478639Z","end":"2026-07-30T23:50:02.0458Z","steps":["trace[263675100] 'process raft request' (duration: 1.566705058s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:50:02.045903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:50:00.478598Z","time spent":"1.567265585s","remote":"127.0.0.1:60960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T23:50:02.045903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:50:00.404021Z","time spent":"1.641800533s","remote":"127.0.0.1:60960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":544,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T23:50:02.04607Z","caller":"traceutil/trace.go:171","msg":"trace[1428904010] linearizableReadLoop","detail":"{readStateIndex:5352; appliedIndex:5348; }","duration":"1.76947583s","start":"2026-07-30T23:50:00.276509Z","end":"2026-07-30T23:50:02.045985Z","steps":["trace[1428904010] 'read index received' (duration: 1.179083392s)","trace[1428904010] 'applied index is now lower than readState.Index' (duration: 590.382357ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T23:50:02.046327Z","caller":"traceutil/trace.go:171","msg":"trace[214654922] transaction","detail":"{read_only:false; response_revision:5157; number_of_response:1; }","duration":"168.543207ms","start":"2026-07-30T23:50:01.877769Z","end":"2026-07-30T23:50:02.046312Z","steps":["trace[214654922] 'process raft request' (duration: 167.619875ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:50:02.046363Z","caller":"traceutil/trace.go:171","msg":"trace[1769030610] transaction","detail":"{read_only:false; response_revision:5154; number_of_response:1; }","duration":"1.775779205s","start":"2026-07-30T23:50:00.270086Z","end":"2026-07-30T23:50:02.045865Z","steps":["trace[1769030610] 'process raft request' (duration: 1.511468434s)","trace[1769030610] 'compare' (duration: 262.989644ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T23:50:02.046523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.746506719s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-30T23:50:02.04666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"834.525788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.61\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-07-30T23:50:02.046718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.493968865s","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-07-30T23:50:02.046736Z","caller":"traceutil/trace.go:171","msg":"trace[256010234] range","detail":"{range_begin:/registry/masterleases/199.204.45.61; range_end:; response_count:1; response_revision:5157; }","duration":"834.66979ms","start":"2026-07-30T23:50:01.212047Z","end":"2026-07-30T23:50:02.046717Z","steps":["trace[256010234] 'agreement among raft nodes before linearized reading' (duration: 834.436677ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:50:02.046755Z","caller":"traceutil/trace.go:171","msg":"trace[963289516] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5157; }","duration":"1.494025795s","start":"2026-07-30T23:50:00.552718Z","end":"2026-07-30T23:50:02.046744Z","steps":["trace[963289516] 'agreement among raft nodes before linearized reading' (duration: 1.493877674s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:50:02.046742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.505018914s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-30T23:50:02.046781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:50:01.212024Z","time spent":"834.746701ms","remote":"127.0.0.1:60698","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.61\" "} {"level":"warn","ts":"2026-07-30T23:50:02.046776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.240690219s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:17229"} {"level":"warn","ts":"2026-07-30T23:50:02.04679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:50:00.552702Z","time spent":"1.494078816s","remote":"127.0.0.1:60960","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-07-30T23:50:02.046792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.559841435s","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-07-30T23:50:02.046807Z","caller":"traceutil/trace.go:171","msg":"trace[1647884540] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5157; }","duration":"1.505131135s","start":"2026-07-30T23:50:00.541665Z","end":"2026-07-30T23:50:02.046796Z","steps":["trace[1647884540] 'agreement among raft nodes before linearized reading' (duration: 1.505041824s)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:50:02.046822Z","caller":"traceutil/trace.go:171","msg":"trace[1255552285] range","detail":"{range_begin:/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:5157; }","duration":"1.24077411s","start":"2026-07-30T23:50:00.806032Z","end":"2026-07-30T23:50:02.046806Z","steps":["trace[1255552285] 'agreement among raft nodes before linearized reading' (duration: 1.240681159s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:50:02.046821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.488148696s","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-07-30T23:50:02.046841Z","caller":"traceutil/trace.go:171","msg":"trace[1681205850] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5157; }","duration":"1.559931997s","start":"2026-07-30T23:50:00.486894Z","end":"2026-07-30T23:50:02.046826Z","steps":["trace[1681205850] 'agreement among raft nodes before linearized reading' (duration: 1.559825485s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:50:02.046857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:50:00.806008Z","time spent":"1.240842741s","remote":"127.0.0.1:60876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":73,"response count":1,"response size":17253,"request content":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" "} {"level":"warn","ts":"2026-07-30T23:50:02.046896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:50:00.486876Z","time spent":"1.560010897s","remote":"127.0.0.1:60960","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-07-30T23:50:02.04691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:50:00.541646Z","time spent":"1.505253257s","remote":"127.0.0.1:60654","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-07-30T23:50:02.046641Z","caller":"traceutil/trace.go:171","msg":"trace[821172111] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5157; }","duration":"1.746669772s","start":"2026-07-30T23:50:00.299951Z","end":"2026-07-30T23:50:02.046621Z","steps":["trace[821172111] 'agreement among raft nodes before linearized reading' (duration: 1.746402147s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:50:02.047081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:50:00.299931Z","time spent":"1.747135518s","remote":"127.0.0.1:60670","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-07-30T23:50:02.046549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:50:00.270064Z","time spent":"1.776388875s","remote":"127.0.0.1:60960","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-07-30T23:50:02.046668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.770152379s","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-07-30T23:50:02.047206Z","caller":"traceutil/trace.go:171","msg":"trace[1619322015] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5157; }","duration":"1.770689146s","start":"2026-07-30T23:50:00.276504Z","end":"2026-07-30T23:50:02.047193Z","steps":["trace[1619322015] 'agreement among raft nodes before linearized reading' (duration: 1.770051878s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:50:02.047251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:50:00.27648Z","time spent":"1.770760807s","remote":"127.0.0.1:60960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-30T23:50:02.046677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.487365015s","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-07-30T23:50:02.047343Z","caller":"traceutil/trace.go:171","msg":"trace[2050241512] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5157; }","duration":"1.488072584s","start":"2026-07-30T23:50:00.559259Z","end":"2026-07-30T23:50:02.047332Z","steps":["trace[2050241512] 'agreement among raft nodes before linearized reading' (duration: 1.48710148s)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:50:02.046866Z","caller":"traceutil/trace.go:171","msg":"trace[1206823031] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5157; }","duration":"1.488213557s","start":"2026-07-30T23:50:00.55864Z","end":"2026-07-30T23:50:02.046854Z","steps":["trace[1206823031] 'agreement among raft nodes before linearized reading' (duration: 1.488059735s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:50:02.047381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:50:00.559196Z","time spent":"1.488176156s","remote":"127.0.0.1:60960","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-07-30T23:50:02.04739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:50:00.55863Z","time spent":"1.488748864s","remote":"127.0.0.1:60960","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-07-30T23:50:02.048136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.206327684s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-07-30T23:50:02.048187Z","caller":"traceutil/trace.go:171","msg":"trace[729674762] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:5157; }","duration":"1.207571651s","start":"2026-07-30T23:50:00.840599Z","end":"2026-07-30T23:50:02.04817Z","steps":["trace[729674762] 'agreement among raft nodes before linearized reading' (duration: 1.206100542s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:50:02.048412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:50:00.840588Z","time spent":"1.207811724s","remote":"127.0.0.1:60830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":139,"response count":1,"response size":2106,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" "} {"level":"warn","ts":"2026-07-30T23:50:02.35876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.452651ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"info","ts":"2026-07-30T23:50:02.359036Z","caller":"traceutil/trace.go:171","msg":"trace[55520501] linearizableReadLoop","detail":"{readStateIndex:5354; appliedIndex:5353; }","duration":"306.012462ms","start":"2026-07-30T23:50:02.052996Z","end":"2026-07-30T23:50:02.359008Z","steps":["trace[55520501] 'read index received' (duration: 161.235607ms)","trace[55520501] 'applied index is now lower than readState.Index' (duration: 144.774395ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T23:50:02.359186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:50:02.050933Z","time spent":"308.244553ms","remote":"127.0.0.1:60698","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-07-30T23:50:02.359373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.355517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-32586a42-af9f-4a2d-bfe5-8afb0aee6c13\" ","response":"range_response_count:1 size:1435"} {"level":"info","ts":"2026-07-30T23:50:02.359473Z","caller":"traceutil/trace.go:171","msg":"trace[1043196016] range","detail":"{range_begin:/registry/persistentvolumes/pvc-32586a42-af9f-4a2d-bfe5-8afb0aee6c13; range_end:; response_count:1; response_revision:5157; }","duration":"306.486609ms","start":"2026-07-30T23:50:02.052968Z","end":"2026-07-30T23:50:02.359454Z","steps":["trace[1043196016] 'agreement among raft nodes before linearized reading' (duration: 306.245576ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:50:02.359515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:50:02.052956Z","time spent":"306.55062ms","remote":"127.0.0.1:60814","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1459,"request content":"key:\"/registry/persistentvolumes/pvc-32586a42-af9f-4a2d-bfe5-8afb0aee6c13\" "} {"level":"info","ts":"2026-07-30T23:50:02.403846Z","caller":"traceutil/trace.go:171","msg":"trace[362879784] transaction","detail":"{read_only:false; response_revision:5158; number_of_response:1; }","duration":"350.707787ms","start":"2026-07-30T23:50:02.053122Z","end":"2026-07-30T23:50:02.40383Z","steps":["trace[362879784] 'process raft request' (duration: 350.533524ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:50:02.40401Z","caller":"traceutil/trace.go:171","msg":"trace[1454568064] transaction","detail":"{read_only:false; response_revision:5159; number_of_response:1; }","duration":"350.141379ms","start":"2026-07-30T23:50:02.053838Z","end":"2026-07-30T23:50:02.40398Z","steps":["trace[1454568064] 'process raft request' (duration: 349.958266ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:50:02.404086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:50:02.053828Z","time spent":"350.197959ms","remote":"127.0.0.1:60960","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-07-30T23:50:02.404231Z","caller":"traceutil/trace.go:171","msg":"trace[852255717] transaction","detail":"{read_only:false; response_revision:5161; number_of_response:1; }","duration":"350.071918ms","start":"2026-07-30T23:50:02.054142Z","end":"2026-07-30T23:50:02.404214Z","steps":["trace[852255717] 'process raft request' (duration: 349.975977ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:50:02.404222Z","caller":"traceutil/trace.go:171","msg":"trace[1554833349] transaction","detail":"{read_only:false; response_revision:5160; number_of_response:1; }","duration":"350.307441ms","start":"2026-07-30T23:50:02.053894Z","end":"2026-07-30T23:50:02.404202Z","steps":["trace[1554833349] 'process raft request' (duration: 350.055007ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:50:02.404321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:50:02.054111Z","time spent":"350.167939ms","remote":"127.0.0.1:60960","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-07-30T23:50:02.40435Z","caller":"traceutil/trace.go:171","msg":"trace[1653975776] transaction","detail":"{read_only:false; response_revision:5162; number_of_response:1; }","duration":"349.329377ms","start":"2026-07-30T23:50:02.055006Z","end":"2026-07-30T23:50:02.404335Z","steps":["trace[1653975776] 'process raft request' (duration: 349.173525ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:50:02.404366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:50:02.053101Z","time spent":"350.800568ms","remote":"127.0.0.1:60960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T23:50:02.404431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:50:02.054999Z","time spent":"349.395468ms","remote":"127.0.0.1:60960","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-07-30T23:50:02.404476Z","caller":"traceutil/trace.go:171","msg":"trace[478081343] transaction","detail":"{read_only:false; response_revision:5163; number_of_response:1; }","duration":"345.357394ms","start":"2026-07-30T23:50:02.059109Z","end":"2026-07-30T23:50:02.404466Z","steps":["trace[478081343] 'process raft request' (duration: 345.156601ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:50:02.404426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:50:02.05389Z","time spent":"350.419862ms","remote":"127.0.0.1:60960","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-07-30T23:50:02.404537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:50:02.059101Z","time spent":"345.406745ms","remote":"127.0.0.1:60876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17188,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T23:50:02.404673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.199899ms","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":"warn","ts":"2026-07-30T23:50:02.404697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.424892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T23:50:02.404741Z","caller":"traceutil/trace.go:171","msg":"trace[1238162458] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5164; }","duration":"350.486764ms","start":"2026-07-30T23:50:02.054242Z","end":"2026-07-30T23:50:02.404729Z","steps":["trace[1238162458] 'agreement among raft nodes before linearized reading' (duration: 350.419763ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:50:02.404764Z","caller":"traceutil/trace.go:171","msg":"trace[504898137] transaction","detail":"{read_only:false; response_revision:5164; number_of_response:1; }","duration":"280.252365ms","start":"2026-07-30T23:50:02.124501Z","end":"2026-07-30T23:50:02.404753Z","steps":["trace[504898137] 'process raft request' (duration: 279.920141ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:50:02.404781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:50:02.054235Z","time spent":"350.539944ms","remote":"127.0.0.1:60670","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-07-30T23:50:02.404752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.138172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3316"} {"level":"info","ts":"2026-07-30T23:50:02.404793Z","caller":"traceutil/trace.go:171","msg":"trace[1203338318] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:5164; }","duration":"270.251829ms","start":"2026-07-30T23:50:02.134448Z","end":"2026-07-30T23:50:02.404699Z","steps":["trace[1203338318] 'agreement among raft nodes before linearized reading' (duration: 270.165628ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:50:02.404757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.316517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheusrules/\" range_end:\"/registry/monitoring.coreos.com/prometheusrules0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-30T23:50:02.404932Z","caller":"traceutil/trace.go:171","msg":"trace[81004728] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheusrules/; range_end:/registry/monitoring.coreos.com/prometheusrules0; response_count:0; response_revision:5164; }","duration":"329.55769ms","start":"2026-07-30T23:50:02.075352Z","end":"2026-07-30T23:50:02.40491Z","steps":["trace[81004728] 'agreement among raft nodes before linearized reading' (duration: 329.299807ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:50:02.404941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.988493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-30T23:50:02.404996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:50:02.075327Z","time spent":"329.653972ms","remote":"127.0.0.1:60838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":32,"response size":31,"request content":"key:\"/registry/monitoring.coreos.com/prometheusrules/\" range_end:\"/registry/monitoring.coreos.com/prometheusrules0\" count_only:true "} {"level":"info","ts":"2026-07-30T23:50:02.404825Z","caller":"traceutil/trace.go:171","msg":"trace[637369638] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:5164; }","duration":"348.223693ms","start":"2026-07-30T23:50:02.056588Z","end":"2026-07-30T23:50:02.404812Z","steps":["trace[637369638] 'agreement among raft nodes before linearized reading' (duration: 348.068281ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:50:02.405008Z","caller":"traceutil/trace.go:171","msg":"trace[1409754510] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:5164; }","duration":"272.104165ms","start":"2026-07-30T23:50:02.132894Z","end":"2026-07-30T23:50:02.404998Z","steps":["trace[1409754510] 'agreement among raft nodes before linearized reading' (duration: 272.014714ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:50:02.405048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:50:02.056582Z","time spent":"348.457757ms","remote":"127.0.0.1:41504","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":"info","ts":"2026-07-30T23:50:02.485296Z","caller":"traceutil/trace.go:171","msg":"trace[1910614785] transaction","detail":"{read_only:false; response_revision:5165; number_of_response:1; }","duration":"124.356469ms","start":"2026-07-30T23:50:02.360892Z","end":"2026-07-30T23:50:02.485249Z","steps":["trace[1910614785] 'process raft request' (duration: 98.281727ms)","trace[1910614785] 'compare' (duration: 25.89702ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T23:50:02.485448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.18482ms","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-07-30T23:50:02.48601Z","caller":"traceutil/trace.go:171","msg":"trace[955265590] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5165; }","duration":"122.759667ms","start":"2026-07-30T23:50:02.363208Z","end":"2026-07-30T23:50:02.485967Z","steps":["trace[955265590] 'agreement among raft nodes before linearized reading' (duration: 122.059327ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:50:35.920721Z","caller":"traceutil/trace.go:171","msg":"trace[802178921] linearizableReadLoop","detail":"{readStateIndex:5658; appliedIndex:5657; }","duration":"250.55179ms","start":"2026-07-30T23:50:35.670143Z","end":"2026-07-30T23:50:35.920695Z","steps":["trace[802178921] 'read index received' (duration: 250.353557ms)","trace[802178921] 'applied index is now lower than readState.Index' (duration: 197.383µs)"],"step_count":2} {"level":"info","ts":"2026-07-30T23:50:35.920791Z","caller":"traceutil/trace.go:171","msg":"trace[223107976] transaction","detail":"{read_only:false; response_revision:5454; number_of_response:1; }","duration":"267.17313ms","start":"2026-07-30T23:50:35.653597Z","end":"2026-07-30T23:50:35.92077Z","steps":["trace[223107976] 'process raft request' (duration: 266.913436ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:50:35.920984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.851384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1990"} {"level":"info","ts":"2026-07-30T23:50:35.921031Z","caller":"traceutil/trace.go:171","msg":"trace[593006044] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:5454; }","duration":"250.951935ms","start":"2026-07-30T23:50:35.670066Z","end":"2026-07-30T23:50:35.921018Z","steps":["trace[593006044] 'agreement among raft nodes before linearized reading' (duration: 250.777363ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:50:35.921059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.017209ms","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-07-30T23:50:35.921206Z","caller":"traceutil/trace.go:171","msg":"trace[1297843285] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:5454; }","duration":"209.269033ms","start":"2026-07-30T23:50:35.711904Z","end":"2026-07-30T23:50:35.921173Z","steps":["trace[1297843285] 'agreement among raft nodes before linearized reading' (duration: 208.982379ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:50:36.594783Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2890} {"level":"info","ts":"2026-07-30T23:50:36.688445Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2890,"took":"89.588485ms","hash":1599010367,"current-db-size-bytes":52862976,"current-db-size":"53 MB","current-db-size-in-use-bytes":28450816,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-07-30T23:50:36.688528Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1599010367,"revision":2890,"compact-revision":-1} {"level":"info","ts":"2026-07-30T23:50:46.266508Z","caller":"traceutil/trace.go:171","msg":"trace[231098484] linearizableReadLoop","detail":"{readStateIndex:5721; appliedIndex:5720; }","duration":"128.033828ms","start":"2026-07-30T23:50:46.138451Z","end":"2026-07-30T23:50:46.266485Z","steps":["trace[231098484] 'read index received' (duration: 127.978337ms)","trace[231098484] 'applied index is now lower than readState.Index' (duration: 54.251µs)"],"step_count":2} {"level":"info","ts":"2026-07-30T23:50:46.266634Z","caller":"traceutil/trace.go:171","msg":"trace[1843144986] transaction","detail":"{read_only:false; response_revision:5513; number_of_response:1; }","duration":"129.215132ms","start":"2026-07-30T23:50:46.137388Z","end":"2026-07-30T23:50:46.266603Z","steps":["trace[1843144986] 'process raft request' (duration: 128.967039ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:50:46.266671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.19837ms","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-07-30T23:50:46.266728Z","caller":"traceutil/trace.go:171","msg":"trace[1207107605] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:5513; }","duration":"128.271671ms","start":"2026-07-30T23:50:46.138446Z","end":"2026-07-30T23:50:46.266717Z","steps":["trace[1207107605] 'agreement among raft nodes before linearized reading' (duration: 128.174879ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:50:46.321562Z","caller":"traceutil/trace.go:171","msg":"trace[433523653] transaction","detail":"{read_only:false; response_revision:5514; number_of_response:1; }","duration":"117.887933ms","start":"2026-07-30T23:50:46.203648Z","end":"2026-07-30T23:50:46.321536Z","steps":["trace[433523653] 'process raft request' (duration: 97.821909ms)","trace[433523653] 'compare' (duration: 19.74504ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T23:50:46.323879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.72948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2207411"} {"level":"info","ts":"2026-07-30T23:50:46.323944Z","caller":"traceutil/trace.go:171","msg":"trace[1973028759] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:5516; }","duration":"101.844272ms","start":"2026-07-30T23:50:46.222087Z","end":"2026-07-30T23:50:46.323931Z","steps":["trace[1973028759] 'agreement among raft nodes before linearized reading' (duration: 99.553892ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:50:46.713341Z","caller":"traceutil/trace.go:171","msg":"trace[1422303453] transaction","detail":"{read_only:false; response_revision:5517; number_of_response:1; }","duration":"291.490991ms","start":"2026-07-30T23:50:46.421773Z","end":"2026-07-30T23:50:46.713264Z","steps":["trace[1422303453] 'process raft request' (duration: 291.308348ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:50:46.852651Z","caller":"traceutil/trace.go:171","msg":"trace[618700365] linearizableReadLoop","detail":"{readStateIndex:5726; appliedIndex:5724; }","duration":"211.69411ms","start":"2026-07-30T23:50:46.640915Z","end":"2026-07-30T23:50:46.852609Z","steps":["trace[618700365] 'read index received' (duration: 72.240662ms)","trace[618700365] 'applied index is now lower than readState.Index' (duration: 139.451088ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T23:50:46.852879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.941403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-30T23:50:46.852974Z","caller":"traceutil/trace.go:171","msg":"trace[369603282] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:5517; }","duration":"212.093224ms","start":"2026-07-30T23:50:46.64086Z","end":"2026-07-30T23:50:46.852953Z","steps":["trace[369603282] 'agreement among raft nodes before linearized reading' (duration: 211.866271ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:50:46.853052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.567433ms","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-07-30T23:50:46.853112Z","caller":"traceutil/trace.go:171","msg":"trace[1250473233] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5517; }","duration":"153.687825ms","start":"2026-07-30T23:50:46.69941Z","end":"2026-07-30T23:50:46.853098Z","steps":["trace[1250473233] 'agreement among raft nodes before linearized reading' (duration: 153.557173ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:50:46.853109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.296681ms","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-07-30T23:50:46.853163Z","caller":"traceutil/trace.go:171","msg":"trace[1555188808] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5517; }","duration":"166.426793ms","start":"2026-07-30T23:50:46.686723Z","end":"2026-07-30T23:50:46.85315Z","steps":["trace[1555188808] 'agreement among raft nodes before linearized reading' (duration: 166.278501ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:50:46.853161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.695759ms","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-07-30T23:50:46.853249Z","caller":"traceutil/trace.go:171","msg":"trace[1358710857] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5517; }","duration":"123.818651ms","start":"2026-07-30T23:50:46.729407Z","end":"2026-07-30T23:50:46.853226Z","steps":["trace[1358710857] 'agreement among raft nodes before linearized reading' (duration: 123.653989ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:50:46.853565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.751723ms","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-07-30T23:50:46.853608Z","caller":"traceutil/trace.go:171","msg":"trace[1279448669] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5517; }","duration":"127.832204ms","start":"2026-07-30T23:50:46.725767Z","end":"2026-07-30T23:50:46.853599Z","steps":["trace[1279448669] 'agreement among raft nodes before linearized reading' (duration: 127.165385ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:50:46.853908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.048474ms","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-07-30T23:50:46.853953Z","caller":"traceutil/trace.go:171","msg":"trace[422923137] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5517; }","duration":"168.092975ms","start":"2026-07-30T23:50:46.685849Z","end":"2026-07-30T23:50:46.853942Z","steps":["trace[422923137] 'agreement among raft nodes before linearized reading' (duration: 167.002931ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:50:47.189004Z","caller":"traceutil/trace.go:171","msg":"trace[1801038220] transaction","detail":"{read_only:false; response_revision:5523; number_of_response:1; }","duration":"119.533355ms","start":"2026-07-30T23:50:47.069431Z","end":"2026-07-30T23:50:47.188964Z","steps":["trace[1801038220] 'process raft request' (duration: 119.327902ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:51:47.382683Z","caller":"traceutil/trace.go:171","msg":"trace[1945471642] transaction","detail":"{read_only:false; response_revision:5962; number_of_response:1; }","duration":"142.657596ms","start":"2026-07-30T23:51:47.240003Z","end":"2026-07-30T23:51:47.38266Z","steps":["trace[1945471642] 'process raft request' (duration: 125.438114ms)","trace[1945471642] 'compare' (duration: 16.920198ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T23:51:47.382912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.622488ms","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-07-30T23:51:47.382936Z","caller":"traceutil/trace.go:171","msg":"trace[1670497395] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5963; }","duration":"136.665649ms","start":"2026-07-30T23:51:47.246264Z","end":"2026-07-30T23:51:47.38293Z","steps":["trace[1670497395] 'agreement among raft nodes before linearized reading' (duration: 136.542427ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:53:46.457704Z","caller":"traceutil/trace.go:171","msg":"trace[181537232] transaction","detail":"{read_only:false; response_revision:6768; number_of_response:1; }","duration":"149.99383ms","start":"2026-07-30T23:53:46.307677Z","end":"2026-07-30T23:53:46.457671Z","steps":["trace[181537232] 'process raft request' (duration: 149.786784ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:53:46.546921Z","caller":"traceutil/trace.go:171","msg":"trace[1543659983] transaction","detail":"{read_only:false; response_revision:6769; number_of_response:1; }","duration":"202.164555ms","start":"2026-07-30T23:53:46.344732Z","end":"2026-07-30T23:53:46.546896Z","steps":["trace[1543659983] 'process raft request' (duration: 198.267007ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:53:47.568301Z","caller":"traceutil/trace.go:171","msg":"trace[313792653] transaction","detail":"{read_only:false; response_revision:6771; number_of_response:1; }","duration":"351.8164ms","start":"2026-07-30T23:53:47.216456Z","end":"2026-07-30T23:53:47.568272Z","steps":["trace[313792653] 'process raft request' (duration: 351.613713ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:53:47.568562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:53:47.21643Z","time spent":"351.962825ms","remote":"127.0.0.1:60960","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-07-30T23:53:47.583434Z","caller":"traceutil/trace.go:171","msg":"trace[1518262484] linearizableReadLoop","detail":"{readStateIndex:7025; appliedIndex:7021; }","duration":"126.446632ms","start":"2026-07-30T23:53:47.456968Z","end":"2026-07-30T23:53:47.583414Z","steps":["trace[1518262484] 'read index received' (duration: 111.057291ms)","trace[1518262484] 'applied index is now lower than readState.Index' (duration: 15.387841ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T23:53:47.583609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.638089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/podmonitors/\" range_end:\"/registry/monitoring.coreos.com/podmonitors0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-30T23:53:47.583693Z","caller":"traceutil/trace.go:171","msg":"trace[2084734250] range","detail":"{range_begin:/registry/monitoring.coreos.com/podmonitors/; range_end:/registry/monitoring.coreos.com/podmonitors0; response_count:0; response_revision:6774; }","duration":"126.729562ms","start":"2026-07-30T23:53:47.456949Z","end":"2026-07-30T23:53:47.583679Z","steps":["trace[2084734250] 'agreement among raft nodes before linearized reading' (duration: 126.535186ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:53:47.584161Z","caller":"traceutil/trace.go:171","msg":"trace[1743140331] transaction","detail":"{read_only:false; response_revision:6772; number_of_response:1; }","duration":"365.817507ms","start":"2026-07-30T23:53:47.218325Z","end":"2026-07-30T23:53:47.584143Z","steps":["trace[1743140331] 'process raft request' (duration: 364.929457ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:53:47.584652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:53:47.21831Z","time spent":"365.911499ms","remote":"127.0.0.1:60960","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-07-30T23:53:47.585004Z","caller":"traceutil/trace.go:171","msg":"trace[203015009] transaction","detail":"{read_only:false; response_revision:6773; number_of_response:1; }","duration":"358.296971ms","start":"2026-07-30T23:53:47.226693Z","end":"2026-07-30T23:53:47.58499Z","steps":["trace[203015009] 'process raft request' (duration: 356.636327ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:53:47.585189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T23:53:47.226669Z","time spent":"358.439186ms","remote":"127.0.0.1:60960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T23:53:47.585455Z","caller":"traceutil/trace.go:171","msg":"trace[2067703109] transaction","detail":"{read_only:false; response_revision:6774; number_of_response:1; }","duration":"298.632416ms","start":"2026-07-30T23:53:47.286809Z","end":"2026-07-30T23:53:47.585442Z","steps":["trace[2067703109] 'process raft request' (duration: 296.562868ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:54:08.352051Z","caller":"traceutil/trace.go:171","msg":"trace[1210120924] transaction","detail":"{read_only:false; response_revision:6933; number_of_response:1; }","duration":"128.925794ms","start":"2026-07-30T23:54:08.223095Z","end":"2026-07-30T23:54:08.352021Z","steps":["trace[1210120924] 'process raft request' (duration: 48.428031ms)","trace[1210120924] 'compare' (duration: 80.207794ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T23:54:24.438235Z","caller":"traceutil/trace.go:171","msg":"trace[656441796] linearizableReadLoop","detail":"{readStateIndex:7277; appliedIndex:7276; }","duration":"158.595065ms","start":"2026-07-30T23:54:24.279617Z","end":"2026-07-30T23:54:24.438212Z","steps":["trace[656441796] 'read index received' (duration: 158.234404ms)","trace[656441796] 'applied index is now lower than readState.Index' (duration: 359.311µs)"],"step_count":2} {"level":"info","ts":"2026-07-30T23:54:24.438381Z","caller":"traceutil/trace.go:171","msg":"trace[2138133318] transaction","detail":"{read_only:false; response_revision:7016; number_of_response:1; }","duration":"194.558182ms","start":"2026-07-30T23:54:24.243806Z","end":"2026-07-30T23:54:24.438364Z","steps":["trace[2138133318] 'process raft request' (duration: 194.183381ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T23:54:24.438548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.919344ms","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-07-30T23:54:24.439142Z","caller":"traceutil/trace.go:171","msg":"trace[1535623940] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:7016; }","duration":"159.507583ms","start":"2026-07-30T23:54:24.279599Z","end":"2026-07-30T23:54:24.439107Z","steps":["trace[1535623940] 'agreement among raft nodes before linearized reading' (duration: 158.823661ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T23:55:36.600431Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5459} {"level":"info","ts":"2026-07-30T23:55:36.681741Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5459,"took":"79.807185ms","hash":1431722801,"current-db-size-bytes":52862976,"current-db-size":"53 MB","current-db-size-in-use-bytes":18710528,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-07-30T23:55:36.681835Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1431722801,"revision":5459,"compact-revision":2890} {"level":"info","ts":"2026-07-30T23:57:24.574022Z","caller":"traceutil/trace.go:171","msg":"trace[2144089655] transaction","detail":"{read_only:false; response_revision:7953; number_of_response:1; }","duration":"104.223961ms","start":"2026-07-30T23:57:24.469765Z","end":"2026-07-30T23:57:24.573989Z","steps":["trace[2144089655] 'process raft request' (duration: 84.986787ms)","trace[2144089655] 'compare' (duration: 19.06033ms)"],"step_count":2}