{"level":"warn","ts":"2026-05-26T08:46:22.582369Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-26T08:46:22.582485Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.240: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.240:2380","--initial-cluster=instance=https://199.204.45.240:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.240:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.240:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-26T08:46:22.582569Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-26T08:46:22.582592Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.240:2380"]} {"level":"info","ts":"2026-05-26T08:46:22.58261Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-26T08:46:22.583251Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.240:2379"]} {"level":"info","ts":"2026-05-26T08:46:22.583325Z","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.240:2380"],"listen-peer-urls":["https://199.204.45.240:2380"],"advertise-client-urls":["https://199.204.45.240:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.240:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.240:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-26T08:46:22.5889Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.324158ms"} {"level":"info","ts":"2026-05-26T08:46:22.604727Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"4e46287fb5aa4785","cluster-id":"cae704afa9813e87"} {"level":"info","ts":"2026-05-26T08:46:22.604814Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 switched to configuration voters=()"} {"level":"info","ts":"2026-05-26T08:46:22.60484Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 became follower at term 0"} {"level":"info","ts":"2026-05-26T08:46:22.604852Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 4e46287fb5aa4785 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-26T08:46:22.604865Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 became follower at term 1"} {"level":"info","ts":"2026-05-26T08:46:22.60496Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 switched to configuration voters=(5640240112301918085)"} {"level":"warn","ts":"2026-05-26T08:46:22.612317Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-26T08:46:22.615436Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-26T08:46:22.616856Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-26T08:46:22.619165Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"4e46287fb5aa4785","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-26T08:46:22.619464Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-26T08:46:22.61954Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-26T08:46:22.619554Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-26T08:46:22.619868Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"4e46287fb5aa4785","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-26T08:46:22.620579Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 switched to configuration voters=(5640240112301918085)"} {"level":"info","ts":"2026-05-26T08:46:22.620784Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"cae704afa9813e87","local-member-id":"4e46287fb5aa4785","added-peer-id":"4e46287fb5aa4785","added-peer-peer-urls":["https://199.204.45.240:2380"]} {"level":"info","ts":"2026-05-26T08:46:22.621869Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-26T08:46:22.622104Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"4e46287fb5aa4785","initial-advertise-peer-urls":["https://199.204.45.240:2380"],"listen-peer-urls":["https://199.204.45.240:2380"],"advertise-client-urls":["https://199.204.45.240:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.240:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-26T08:46:22.622135Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-26T08:46:22.622239Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.240:2380"} {"level":"info","ts":"2026-05-26T08:46:22.622254Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.240:2380"} {"level":"info","ts":"2026-05-26T08:46:23.206205Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 is starting a new election at term 1"} {"level":"info","ts":"2026-05-26T08:46:23.206282Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-26T08:46:23.20634Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 received MsgPreVoteResp from 4e46287fb5aa4785 at term 1"} {"level":"info","ts":"2026-05-26T08:46:23.20636Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 became candidate at term 2"} {"level":"info","ts":"2026-05-26T08:46:23.206376Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 received MsgVoteResp from 4e46287fb5aa4785 at term 2"} {"level":"info","ts":"2026-05-26T08:46:23.206392Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e46287fb5aa4785 became leader at term 2"} {"level":"info","ts":"2026-05-26T08:46:23.206413Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 4e46287fb5aa4785 elected leader 4e46287fb5aa4785 at term 2"} {"level":"info","ts":"2026-05-26T08:46:23.209292Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"4e46287fb5aa4785","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.240:2379]}","request-path":"/0/members/4e46287fb5aa4785/attributes","cluster-id":"cae704afa9813e87","publish-timeout":"7s"} {"level":"info","ts":"2026-05-26T08:46:23.209313Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-26T08:46:23.20955Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-26T08:46:23.209608Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-26T08:46:23.21044Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"cae704afa9813e87","local-member-id":"4e46287fb5aa4785","cluster-version":"3.5"} {"level":"info","ts":"2026-05-26T08:46:23.210384Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-26T08:46:23.210551Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-26T08:46:23.210555Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-26T08:46:23.210583Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-26T08:46:23.213515Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-26T08:46:23.218564Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.240:2379"} {"level":"warn","ts":"2026-05-26T08:46:26.876002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"684.685134ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-05-26T08:46:26.876136Z","caller":"traceutil/trace.go:171","msg":"trace[1534850532] linearizableReadLoop","detail":"{readStateIndex:92; appliedIndex:91; }","duration":"323.860836ms","start":"2026-05-26T08:46:26.552256Z","end":"2026-05-26T08:46:26.876116Z","steps":["trace[1534850532] 'read index received' (duration: 50.63µs)","trace[1534850532] 'applied index is now lower than readState.Index' (duration: 323.808196ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T08:46:26.876167Z","caller":"traceutil/trace.go:171","msg":"trace[1336789941] transaction","detail":"{read_only:false; response_revision:88; number_of_response:1; }","duration":"781.309426ms","start":"2026-05-26T08:46:26.094823Z","end":"2026-05-26T08:46:26.876132Z","steps":["trace[1336789941] 'process raft request' (duration: 95.948079ms)","trace[1336789941] 'compare' (duration: 684.529101ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T08:46:26.876292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.999659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-26T08:46:26.876339Z","caller":"traceutil/trace.go:171","msg":"trace[1214351584] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:88; }","duration":"324.208623ms","start":"2026-05-26T08:46:26.552121Z","end":"2026-05-26T08:46:26.876329Z","steps":["trace[1214351584] 'agreement among raft nodes before linearized reading' (duration: 324.06503ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:46:26.876395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:46:26.552091Z","time spent":"324.294885ms","remote":"127.0.0.1:43018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-26T08:46:26.876303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:46:26.094812Z","time spent":"781.409068ms","remote":"127.0.0.1:43414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":649,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-26T08:46:28.806507Z","caller":"traceutil/trace.go:171","msg":"trace[709310330] transaction","detail":"{read_only:false; response_revision:213; number_of_response:1; }","duration":"114.515694ms","start":"2026-05-26T08:46:28.691951Z","end":"2026-05-26T08:46:28.806466Z","steps":["trace[709310330] 'process raft request' (duration: 114.381821ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:46:29.109881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.37561ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-26T08:46:29.109988Z","caller":"traceutil/trace.go:171","msg":"trace[1161434703] linearizableReadLoop","detail":"{readStateIndex:222; appliedIndex:221; }","duration":"138.464287ms","start":"2026-05-26T08:46:28.971508Z","end":"2026-05-26T08:46:29.109972Z","steps":["trace[1161434703] 'read index received' (duration: 29.71836ms)","trace[1161434703] 'applied index is now lower than readState.Index' (duration: 108.744987ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T08:46:29.110116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.740736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-26T08:46:29.1101Z","caller":"traceutil/trace.go:171","msg":"trace[526221284] transaction","detail":"{read_only:false; response_revision:216; number_of_response:1; }","duration":"272.652804ms","start":"2026-05-26T08:46:28.837366Z","end":"2026-05-26T08:46:29.110019Z","steps":["trace[526221284] 'process raft request' (duration: 163.945178ms)","trace[526221284] 'compare' (duration: 108.258497ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T08:46:29.11015Z","caller":"traceutil/trace.go:171","msg":"trace[1841230077] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:216; }","duration":"109.817187ms","start":"2026-05-26T08:46:29.000324Z","end":"2026-05-26T08:46:29.110141Z","steps":["trace[1841230077] 'agreement among raft nodes before linearized reading' (duration: 109.730506ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:46:29.110287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.731722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3492"} {"level":"info","ts":"2026-05-26T08:46:29.110412Z","caller":"traceutil/trace.go:171","msg":"trace[1159669730] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:216; }","duration":"138.880645ms","start":"2026-05-26T08:46:28.9715Z","end":"2026-05-26T08:46:29.110381Z","steps":["trace[1159669730] 'agreement among raft nodes before linearized reading' (duration: 138.552139ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T08:46:29.621415Z","caller":"traceutil/trace.go:171","msg":"trace[1291844825] transaction","detail":"{read_only:false; response_revision:233; number_of_response:1; }","duration":"115.17494ms","start":"2026-05-26T08:46:29.506217Z","end":"2026-05-26T08:46:29.621392Z","steps":["trace[1291844825] 'process raft request' (duration: 33.953517ms)","trace[1291844825] 'compare' (duration: 81.123601ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T08:46:30.121932Z","caller":"traceutil/trace.go:171","msg":"trace[1470533450] transaction","detail":"{read_only:false; response_revision:246; number_of_response:1; }","duration":"113.029814ms","start":"2026-05-26T08:46:30.008874Z","end":"2026-05-26T08:46:30.121904Z","steps":["trace[1470533450] 'process raft request' (duration: 112.908152ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:46:30.317559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.724398ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-26T08:46:30.317806Z","caller":"traceutil/trace.go:171","msg":"trace[670803845] transaction","detail":"{read_only:false; response_revision:252; number_of_response:1; }","duration":"180.269538ms","start":"2026-05-26T08:46:30.137473Z","end":"2026-05-26T08:46:30.317742Z","steps":["trace[670803845] 'process raft request' (duration: 66.296856ms)","trace[670803845] 'compare' (duration: 113.574035ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T08:46:30.317885Z","caller":"traceutil/trace.go:171","msg":"trace[573372243] transaction","detail":"{read_only:false; response_revision:253; number_of_response:1; }","duration":"167.006343ms","start":"2026-05-26T08:46:30.150839Z","end":"2026-05-26T08:46:30.317845Z","steps":["trace[573372243] 'process raft request' (duration: 166.81904ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:46:30.681982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.184406ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-26T08:46:30.682104Z","caller":"traceutil/trace.go:171","msg":"trace[1950778708] linearizableReadLoop","detail":"{readStateIndex:275; appliedIndex:274; }","duration":"107.127171ms","start":"2026-05-26T08:46:30.574957Z","end":"2026-05-26T08:46:30.682084Z","steps":["trace[1950778708] 'read index received' (duration: 5.73022ms)","trace[1950778708] 'applied index is now lower than readState.Index' (duration: 101.395441ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T08:46:30.682195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.229693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T08:46:30.682249Z","caller":"traceutil/trace.go:171","msg":"trace[1031712964] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-controller; range_end:; response_count:0; response_revision:269; }","duration":"107.326315ms","start":"2026-05-26T08:46:30.574913Z","end":"2026-05-26T08:46:30.682239Z","steps":["trace[1031712964] 'agreement among raft nodes before linearized reading' (duration: 107.222023ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T08:46:30.682176Z","caller":"traceutil/trace.go:171","msg":"trace[803279406] transaction","detail":"{read_only:false; response_revision:269; number_of_response:1; }","duration":"110.580918ms","start":"2026-05-26T08:46:30.571542Z","end":"2026-05-26T08:46:30.682123Z","steps":["trace[803279406] 'compare' (duration: 101.038874ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T08:46:32.394981Z","caller":"traceutil/trace.go:171","msg":"trace[1987177577] transaction","detail":"{read_only:false; response_revision:309; number_of_response:1; }","duration":"461.993517ms","start":"2026-05-26T08:46:31.932963Z","end":"2026-05-26T08:46:32.394957Z","steps":["trace[1987177577] 'process raft request' (duration: 461.848054ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T08:46:32.395004Z","caller":"traceutil/trace.go:171","msg":"trace[1302802959] linearizableReadLoop","detail":"{readStateIndex:319; appliedIndex:319; }","duration":"438.600479ms","start":"2026-05-26T08:46:31.95637Z","end":"2026-05-26T08:46:32.39497Z","steps":["trace[1302802959] 'read index received' (duration: 438.586299ms)","trace[1302802959] 'applied index is now lower than readState.Index' (duration: 11.99µs)"],"step_count":2} {"level":"warn","ts":"2026-05-26T08:46:32.395124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:46:31.932939Z","time spent":"462.119439ms","remote":"127.0.0.1:43248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":192,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-26T08:46:32.395241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.823323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:2920"} {"level":"info","ts":"2026-05-26T08:46:32.395309Z","caller":"traceutil/trace.go:171","msg":"trace[563339984] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:309; }","duration":"438.963036ms","start":"2026-05-26T08:46:31.956327Z","end":"2026-05-26T08:46:32.39529Z","steps":["trace[563339984] 'agreement among raft nodes before linearized reading' (duration: 438.758942ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:46:32.395363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:46:31.956309Z","time spent":"439.040547ms","remote":"127.0.0.1:43236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":2943,"request content":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" "} {"level":"warn","ts":"2026-05-26T08:46:32.899695Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5153699498625437633,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-26T08:46:33.047625Z","caller":"traceutil/trace.go:171","msg":"trace[780834890] transaction","detail":"{read_only:false; number_of_response:0; response_revision:309; }","duration":"1.088401336s","start":"2026-05-26T08:46:31.959202Z","end":"2026-05-26T08:46:33.047603Z","steps":["trace[780834890] 'process raft request' (duration: 1.088376525s)"],"step_count":1} {"level":"info","ts":"2026-05-26T08:46:33.047676Z","caller":"traceutil/trace.go:171","msg":"trace[1165030600] transaction","detail":"{read_only:false; number_of_response:0; response_revision:309; }","duration":"1.089721522s","start":"2026-05-26T08:46:31.957934Z","end":"2026-05-26T08:46:33.047655Z","steps":["trace[1165030600] 'process raft request' (duration: 1.08961514s)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:46:33.047749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:46:31.959183Z","time spent":"1.088512968s","remote":"127.0.0.1:43236","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-26T08:46:33.047774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:46:31.957921Z","time spent":"1.089802743s","remote":"127.0.0.1:43236","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-26T08:46:33.047887Z","caller":"traceutil/trace.go:171","msg":"trace[1101469711] transaction","detail":"{read_only:false; number_of_response:0; response_revision:309; }","duration":"1.090634709s","start":"2026-05-26T08:46:31.957241Z","end":"2026-05-26T08:46:33.047876Z","steps":["trace[1101469711] 'process raft request' (duration: 1.090208781s)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:46:33.047944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:46:31.957223Z","time spent":"1.09069708s","remote":"127.0.0.1:43236","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-26T08:46:34.688522Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.641017823s","expected-duration":"1s"} {"level":"info","ts":"2026-05-26T08:46:34.688873Z","caller":"traceutil/trace.go:171","msg":"trace[1029543895] linearizableReadLoop","detail":"{readStateIndex:323; appliedIndex:319; }","duration":"2.289431053s","start":"2026-05-26T08:46:32.39942Z","end":"2026-05-26T08:46:34.688851Z","steps":["trace[1029543895] 'read index received' (duration: 648.049213ms)","trace[1029543895] 'applied index is now lower than readState.Index' (duration: 1.64138091s)"],"step_count":2} {"level":"warn","ts":"2026-05-26T08:46:34.689072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.289631416s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T08:46:34.689131Z","caller":"traceutil/trace.go:171","msg":"trace[1189682194] transaction","detail":"{read_only:false; response_revision:310; number_of_response:1; }","duration":"2.264177244s","start":"2026-05-26T08:46:32.42494Z","end":"2026-05-26T08:46:34.689117Z","steps":["trace[1189682194] 'process raft request' (duration: 2.263789407s)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:46:34.689158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.328541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-26T08:46:34.689099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.147144212s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-26T08:46:34.689193Z","caller":"traceutil/trace.go:171","msg":"trace[1364782086] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:310; }","duration":"201.406432ms","start":"2026-05-26T08:46:34.487774Z","end":"2026-05-26T08:46:34.689181Z","steps":["trace[1364782086] 'agreement among raft nodes before linearized reading' (duration: 201.357811ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T08:46:34.6892Z","caller":"traceutil/trace.go:171","msg":"trace[1237279598] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:310; }","duration":"2.147279695s","start":"2026-05-26T08:46:32.541907Z","end":"2026-05-26T08:46:34.689187Z","steps":["trace[1237279598] 'agreement among raft nodes before linearized reading' (duration: 2.147109332s)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:46:34.689237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:46:32.54189Z","time spent":"2.147338016s","remote":"127.0.0.1:43306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-26T08:46:34.689237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:46:32.424917Z","time spent":"2.264251835s","remote":"127.0.0.1:43236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4311,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-26T08:46:34.689392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"522.101309ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T08:46:34.689447Z","caller":"traceutil/trace.go:171","msg":"trace[1394809959] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:310; }","duration":"522.1588ms","start":"2026-05-26T08:46:34.167283Z","end":"2026-05-26T08:46:34.689442Z","steps":["trace[1394809959] 'agreement among raft nodes before linearized reading' (duration: 522.094929ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:46:34.689566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.640462862s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:5897"} {"level":"warn","ts":"2026-05-26T08:46:34.689607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.640540243s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:3980"} {"level":"info","ts":"2026-05-26T08:46:34.689622Z","caller":"traceutil/trace.go:171","msg":"trace[725880829] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:310; }","duration":"1.640585184s","start":"2026-05-26T08:46:33.049032Z","end":"2026-05-26T08:46:34.689617Z","steps":["trace[725880829] 'agreement among raft nodes before linearized reading' (duration: 1.640551343s)"],"step_count":1} {"level":"info","ts":"2026-05-26T08:46:34.689625Z","caller":"traceutil/trace.go:171","msg":"trace[1196904353] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:310; }","duration":"1.640549153s","start":"2026-05-26T08:46:33.049061Z","end":"2026-05-26T08:46:34.68961Z","steps":["trace[1196904353] 'agreement among raft nodes before linearized reading' (duration: 1.640421781s)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:46:34.689677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:46:33.049043Z","time spent":"1.640622355s","remote":"127.0.0.1:43236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":5920,"request content":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" "} {"level":"warn","ts":"2026-05-26T08:46:34.68971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.824107721s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-26T08:46:34.689154Z","caller":"traceutil/trace.go:171","msg":"trace[68722324] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:0; response_revision:310; }","duration":"2.289782689s","start":"2026-05-26T08:46:32.399353Z","end":"2026-05-26T08:46:34.689135Z","steps":["trace[68722324] 'agreement among raft nodes before linearized reading' (duration: 2.289611156s)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:46:34.689642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:46:33.049016Z","time spent":"1.640621434s","remote":"127.0.0.1:43236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":4003,"request content":"key:\"/registry/pods/kube-system/etcd-instance\" "} {"level":"info","ts":"2026-05-26T08:46:34.689755Z","caller":"traceutil/trace.go:171","msg":"trace[1124804393] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:310; }","duration":"1.824242764s","start":"2026-05-26T08:46:32.865502Z","end":"2026-05-26T08:46:34.689744Z","steps":["trace[1124804393] 'agreement among raft nodes before linearized reading' (duration: 1.824101131s)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:46:34.689754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.31363839s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-26T08:46:34.689768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:46:32.39932Z","time spent":"2.290435913s","remote":"127.0.0.1:43248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":0,"response size":28,"request content":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" "} {"level":"warn","ts":"2026-05-26T08:46:34.68979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:46:32.865482Z","time spent":"1.824297236s","remote":"127.0.0.1:43306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-26T08:46:34.689794Z","caller":"traceutil/trace.go:171","msg":"trace[1089053971] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:310; }","duration":"1.313698762s","start":"2026-05-26T08:46:33.376081Z","end":"2026-05-26T08:46:34.68978Z","steps":["trace[1089053971] 'agreement among raft nodes before linearized reading' (duration: 1.313641111s)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:46:34.689822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:46:33.376066Z","time spent":"1.313748673s","remote":"127.0.0.1:43306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-26T08:46:34.689835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.640538954s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4326"} {"level":"info","ts":"2026-05-26T08:46:34.689853Z","caller":"traceutil/trace.go:171","msg":"trace[1728219922] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:310; }","duration":"1.640612065s","start":"2026-05-26T08:46:33.049236Z","end":"2026-05-26T08:46:34.689848Z","steps":["trace[1728219922] 'agreement among raft nodes before linearized reading' (duration: 1.640493593s)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:46:34.689905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:46:33.049228Z","time spent":"1.640673186s","remote":"127.0.0.1:43236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":4349,"request content":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" "} {"level":"warn","ts":"2026-05-26T08:46:34.689984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.526868951s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-26T08:46:34.690069Z","caller":"traceutil/trace.go:171","msg":"trace[178503433] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:310; }","duration":"1.526937002s","start":"2026-05-26T08:46:33.163069Z","end":"2026-05-26T08:46:34.690006Z","steps":["trace[178503433] 'agreement among raft nodes before linearized reading' (duration: 1.526626426s)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:46:34.69014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:46:33.163021Z","time spent":"1.527111455s","remote":"127.0.0.1:43306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-26T08:46:35.46702Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5153699498625437638,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-26T08:46:35.638684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"944.037933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-05-26T08:46:35.638787Z","caller":"traceutil/trace.go:171","msg":"trace[1422408679] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:310; }","duration":"944.159655ms","start":"2026-05-26T08:46:34.694603Z","end":"2026-05-26T08:46:35.638762Z","steps":["trace[1422408679] 'range keys from in-memory index tree' (duration: 943.970032ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:46:35.638855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:46:34.694584Z","time spent":"944.253957ms","remote":"127.0.0.1:43170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":374,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-05-26T08:46:35.638877Z","caller":"traceutil/trace.go:171","msg":"trace[1413127785] transaction","detail":"{read_only:false; response_revision:311; number_of_response:1; }","duration":"943.973832ms","start":"2026-05-26T08:46:34.694883Z","end":"2026-05-26T08:46:35.638856Z","steps":["trace[1413127785] 'process raft request' (duration: 901.209646ms)","trace[1413127785] 'compare' (duration: 42.267786ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T08:46:35.638998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:46:34.694869Z","time spent":"944.058514ms","remote":"127.0.0.1:43306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-26T08:46:35.638683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"944.107464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:3980"} {"level":"info","ts":"2026-05-26T08:46:35.639094Z","caller":"traceutil/trace.go:171","msg":"trace[480056763] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:310; }","duration":"944.599904ms","start":"2026-05-26T08:46:34.694481Z","end":"2026-05-26T08:46:35.63908Z","steps":["trace[480056763] 'range keys from in-memory index tree' (duration: 943.994452ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:46:35.639138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:46:34.694466Z","time spent":"944.660365ms","remote":"127.0.0.1:43236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":4003,"request content":"key:\"/registry/pods/kube-system/etcd-instance\" "} {"level":"info","ts":"2026-05-26T08:46:35.639425Z","caller":"traceutil/trace.go:171","msg":"trace[2016207278] transaction","detail":"{read_only:false; response_revision:312; number_of_response:1; }","duration":"944.387031ms","start":"2026-05-26T08:46:34.69502Z","end":"2026-05-26T08:46:35.639407Z","steps":["trace[2016207278] 'process raft request' (duration: 943.776198ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:46:35.639521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:46:34.695013Z","time spent":"944.463742ms","remote":"127.0.0.1:43306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-26T08:46:35.639761Z","caller":"traceutil/trace.go:171","msg":"trace[1336267912] linearizableReadLoop","detail":"{readStateIndex:328; appliedIndex:323; }","duration":"673.467576ms","start":"2026-05-26T08:46:34.966281Z","end":"2026-05-26T08:46:35.639749Z","steps":["trace[1336267912] 'read index received' (duration: 629.854784ms)","trace[1336267912] 'applied index is now lower than readState.Index' (duration: 43.610672ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T08:46:35.63982Z","caller":"traceutil/trace.go:171","msg":"trace[804206800] transaction","detail":"{read_only:false; response_revision:313; number_of_response:1; }","duration":"944.632215ms","start":"2026-05-26T08:46:34.695174Z","end":"2026-05-26T08:46:35.639806Z","steps":["trace[804206800] 'process raft request' (duration: 944.187707ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T08:46:35.639875Z","caller":"traceutil/trace.go:171","msg":"trace[864564917] transaction","detail":"{read_only:false; response_revision:314; number_of_response:1; }","duration":"943.409921ms","start":"2026-05-26T08:46:34.696455Z","end":"2026-05-26T08:46:35.639865Z","steps":["trace[864564917] 'process raft request' (duration: 943.205057ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:46:35.639906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:46:34.695167Z","time spent":"944.706656ms","remote":"127.0.0.1:43306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-26T08:46:35.639947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:46:34.696443Z","time spent":"943.460393ms","remote":"127.0.0.1:43306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-26T08:46:35.639845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"673.570239ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T08:46:35.640117Z","caller":"traceutil/trace.go:171","msg":"trace[1843675741] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:315; }","duration":"673.891053ms","start":"2026-05-26T08:46:34.966211Z","end":"2026-05-26T08:46:35.640102Z","steps":["trace[1843675741] 'agreement among raft nodes before linearized reading' (duration: 673.589918ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T08:46:35.640133Z","caller":"traceutil/trace.go:171","msg":"trace[10530288] transaction","detail":"{read_only:false; response_revision:315; number_of_response:1; }","duration":"831.357443ms","start":"2026-05-26T08:46:34.808764Z","end":"2026-05-26T08:46:35.640122Z","steps":["trace[10530288] 'process raft request' (duration: 830.946895ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:46:35.640168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:46:34.966181Z","time spent":"673.974775ms","remote":"127.0.0.1:43034","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-26T08:46:35.640195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:46:34.808735Z","time spent":"831.425255ms","remote":"127.0.0.1:43306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-26T08:46:37.432291Z","caller":"traceutil/trace.go:171","msg":"trace[1167206331] transaction","detail":"{read_only:false; response_revision:349; number_of_response:1; }","duration":"116.897088ms","start":"2026-05-26T08:46:37.315353Z","end":"2026-05-26T08:46:37.432251Z","steps":["trace[1167206331] 'process raft request' (duration: 79.47992ms)","trace[1167206331] 'compare' (duration: 37.160142ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T08:46:38.045065Z","caller":"traceutil/trace.go:171","msg":"trace[806321280] transaction","detail":"{read_only:false; response_revision:384; number_of_response:1; }","duration":"124.146755ms","start":"2026-05-26T08:46:37.920899Z","end":"2026-05-26T08:46:38.045046Z","steps":["trace[806321280] 'process raft request' (duration: 88.404803ms)","trace[806321280] 'compare' (duration: 35.214883ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T08:46:38.045482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.316257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-05-26T08:46:38.045577Z","caller":"traceutil/trace.go:171","msg":"trace[285142050] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:384; }","duration":"100.453979ms","start":"2026-05-26T08:46:37.945101Z","end":"2026-05-26T08:46:38.045555Z","steps":["trace[285142050] 'agreement among raft nodes before linearized reading' (duration: 100.278576ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T08:46:38.245006Z","caller":"traceutil/trace.go:171","msg":"trace[1871103629] transaction","detail":"{read_only:false; response_revision:394; number_of_response:1; }","duration":"103.59041ms","start":"2026-05-26T08:46:38.14139Z","end":"2026-05-26T08:46:38.24498Z","steps":["trace[1871103629] 'process raft request' (duration: 103.546819ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T08:46:38.245376Z","caller":"traceutil/trace.go:171","msg":"trace[1058019320] transaction","detail":"{read_only:false; response_revision:391; number_of_response:1; }","duration":"106.462383ms","start":"2026-05-26T08:46:38.138902Z","end":"2026-05-26T08:46:38.245364Z","steps":["trace[1058019320] 'process raft request' (duration: 95.272989ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T08:46:38.245525Z","caller":"traceutil/trace.go:171","msg":"trace[832528153] transaction","detail":"{read_only:false; response_revision:392; number_of_response:1; }","duration":"105.511756ms","start":"2026-05-26T08:46:38.140005Z","end":"2026-05-26T08:46:38.245516Z","steps":["trace[832528153] 'process raft request' (duration: 104.824763ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T08:46:38.245793Z","caller":"traceutil/trace.go:171","msg":"trace[57170790] transaction","detail":"{read_only:false; response_revision:393; number_of_response:1; }","duration":"104.421095ms","start":"2026-05-26T08:46:38.141361Z","end":"2026-05-26T08:46:38.245782Z","steps":["trace[57170790] 'process raft request' (duration: 103.507989ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:46:54.167153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.329437ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-26T08:46:54.167706Z","caller":"traceutil/trace.go:171","msg":"trace[1994577423] linearizableReadLoop","detail":"{readStateIndex:523; appliedIndex:522; }","duration":"426.492436ms","start":"2026-05-26T08:46:53.741191Z","end":"2026-05-26T08:46:54.167683Z","steps":["trace[1994577423] 'read index received' (duration: 26.62µs)","trace[1994577423] 'applied index is now lower than readState.Index' (duration: 426.464066ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T08:46:54.167984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.705689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-26T08:46:54.168044Z","caller":"traceutil/trace.go:171","msg":"trace[1552315478] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:502; }","duration":"426.793721ms","start":"2026-05-26T08:46:53.741235Z","end":"2026-05-26T08:46:54.168029Z","steps":["trace[1552315478] 'agreement among raft nodes before linearized reading' (duration: 426.655319ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:46:54.16807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:46:53.741218Z","time spent":"426.846522ms","remote":"127.0.0.1:43306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-26T08:46:54.168086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.34852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-26T08:46:54.168151Z","caller":"traceutil/trace.go:171","msg":"trace[2126855434] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:502; }","duration":"424.429691ms","start":"2026-05-26T08:46:53.743704Z","end":"2026-05-26T08:46:54.168133Z","steps":["trace[2126855434] 'agreement among raft nodes before linearized reading' (duration: 424.319909ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:46:54.168186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:46:53.743698Z","time spent":"424.479562ms","remote":"127.0.0.1:43306","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-05-26T08:46:54.168162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.203562ms","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-05-26T08:46:54.168317Z","caller":"traceutil/trace.go:171","msg":"trace[1403397498] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:502; }","duration":"344.374714ms","start":"2026-05-26T08:46:53.823888Z","end":"2026-05-26T08:46:54.168262Z","steps":["trace[1403397498] 'agreement among raft nodes before linearized reading' (duration: 344.13325ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:46:54.168375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:46:53.823863Z","time spent":"344.498527ms","remote":"127.0.0.1:43306","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-05-26T08:46:54.168456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.254178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-26T08:46:54.168503Z","caller":"traceutil/trace.go:171","msg":"trace[947278042] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:502; }","duration":"427.32689ms","start":"2026-05-26T08:46:53.741162Z","end":"2026-05-26T08:46:54.168488Z","steps":["trace[947278042] 'agreement among raft nodes before linearized reading' (duration: 426.641169ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:46:54.168538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:46:53.741147Z","time spent":"427.3828ms","remote":"127.0.0.1:43306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-26T08:46:54.493983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.024136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T08:46:54.494075Z","caller":"traceutil/trace.go:171","msg":"trace[363659770] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:506; }","duration":"184.135737ms","start":"2026-05-26T08:46:54.309916Z","end":"2026-05-26T08:46:54.494051Z","steps":["trace[363659770] 'range keys from in-memory index tree' (duration: 183.917784ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T08:47:00.825889Z","caller":"traceutil/trace.go:171","msg":"trace[695986792] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"229.448851ms","start":"2026-05-26T08:47:00.596411Z","end":"2026-05-26T08:47:00.82586Z","steps":["trace[695986792] 'process raft request' (duration: 228.821631ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:47:01.259474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.761212ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-26T08:47:01.259572Z","caller":"traceutil/trace.go:171","msg":"trace[577339927] linearizableReadLoop","detail":"{readStateIndex:641; appliedIndex:640; }","duration":"226.896462ms","start":"2026-05-26T08:47:01.032661Z","end":"2026-05-26T08:47:01.259558Z","steps":["trace[577339927] 'read index received' (duration: 50.201µs)","trace[577339927] 'applied index is now lower than readState.Index' (duration: 226.845361ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T08:47:01.259809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.489446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T08:47:01.259833Z","caller":"traceutil/trace.go:171","msg":"trace[430088607] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:614; }","duration":"181.553506ms","start":"2026-05-26T08:47:01.07827Z","end":"2026-05-26T08:47:01.259824Z","steps":["trace[430088607] 'agreement among raft nodes before linearized reading' (duration: 181.509005ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T08:47:01.259824Z","caller":"traceutil/trace.go:171","msg":"trace[1364479819] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"382.656965ms","start":"2026-05-26T08:47:00.877144Z","end":"2026-05-26T08:47:01.259801Z","steps":["trace[1364479819] 'process raft request' (duration: 123.506587ms)","trace[1364479819] 'compare' (duration: 258.608859ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T08:47:01.259929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:47:00.877085Z","time spent":"382.792817ms","remote":"127.0.0.1:43568","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-26T08:47:01.259914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.230177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-867bc\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-05-26T08:47:01.259998Z","caller":"traceutil/trace.go:171","msg":"trace[1270610322] range","detail":"{range_begin:/registry/pods/kube-system/cilium-867bc; range_end:; response_count:1; response_revision:614; }","duration":"227.353499ms","start":"2026-05-26T08:47:01.032622Z","end":"2026-05-26T08:47:01.259976Z","steps":["trace[1270610322] 'agreement among raft nodes before linearized reading' (duration: 227.053725ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:47:01.709428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.481718ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-26T08:47:01.709604Z","caller":"traceutil/trace.go:171","msg":"trace[400873699] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"433.405507ms","start":"2026-05-26T08:47:01.276131Z","end":"2026-05-26T08:47:01.709536Z","steps":["trace[400873699] 'process raft request' (duration: 159.733646ms)","trace[400873699] 'compare' (duration: 273.360916ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T08:47:01.709717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:47:01.276113Z","time spent":"433.57052ms","remote":"127.0.0.1:43236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-26T08:47:02.579835Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5153699498625438213,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-26T08:47:02.954583Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.357997159s","expected-duration":"1s"} {"level":"info","ts":"2026-05-26T08:47:03.053948Z","caller":"traceutil/trace.go:171","msg":"trace[1761239972] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"1.457412908s","start":"2026-05-26T08:47:01.596489Z","end":"2026-05-26T08:47:03.053902Z","steps":["trace[1761239972] 'process raft request' (duration: 1.358351114s)","trace[1761239972] 'compare' (duration: 98.611657ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T08:47:03.054075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:47:01.596462Z","time spent":"1.4575555s","remote":"127.0.0.1:43306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-26T08:47:03.054593Z","caller":"traceutil/trace.go:171","msg":"trace[1714817313] linearizableReadLoop","detail":"{readStateIndex:645; appliedIndex:642; }","duration":"974.885099ms","start":"2026-05-26T08:47:02.07965Z","end":"2026-05-26T08:47:03.054535Z","steps":["trace[1714817313] 'read index received' (duration: 875.312517ms)","trace[1714817313] 'applied index is now lower than readState.Index' (duration: 99.571372ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T08:47:03.054635Z","caller":"traceutil/trace.go:171","msg":"trace[37764517] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"1.362296454s","start":"2026-05-26T08:47:01.692328Z","end":"2026-05-26T08:47:03.054624Z","steps":["trace[37764517] 'process raft request' (duration: 1.361568133s)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:47:03.054738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:47:01.692307Z","time spent":"1.362399046s","remote":"127.0.0.1:43156","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036325,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-26T08:47:03.054728Z","caller":"traceutil/trace.go:171","msg":"trace[1069931443] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"1.326420971s","start":"2026-05-26T08:47:01.728247Z","end":"2026-05-26T08:47:03.054668Z","steps":["trace[1069931443] 'process raft request' (duration: 1.326235568s)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:47:03.05485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"807.880609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-05-26T08:47:03.054817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"975.103682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T08:47:03.054898Z","caller":"traceutil/trace.go:171","msg":"trace[2144313118] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:618; }","duration":"807.9759ms","start":"2026-05-26T08:47:02.24691Z","end":"2026-05-26T08:47:03.054885Z","steps":["trace[2144313118] 'agreement among raft nodes before linearized reading' (duration: 807.809988ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:47:03.054946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:47:02.246872Z","time spent":"808.063162ms","remote":"127.0.0.1:43306","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-05-26T08:47:03.054948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:47:01.728229Z","time spent":"1.326609924s","remote":"127.0.0.1:43230","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4539,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-26T08:47:03.054963Z","caller":"traceutil/trace.go:171","msg":"trace[1591554433] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:618; }","duration":"975.321846ms","start":"2026-05-26T08:47:02.079586Z","end":"2026-05-26T08:47:03.054908Z","steps":["trace[1591554433] 'agreement among raft nodes before linearized reading' (duration: 975.068032ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:47:03.055055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:47:02.079554Z","time spent":"975.489078ms","remote":"127.0.0.1:43018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-26T08:47:03.055264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"807.891139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-26T08:47:03.055306Z","caller":"traceutil/trace.go:171","msg":"trace[1221323614] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:618; }","duration":"807.94113ms","start":"2026-05-26T08:47:02.247349Z","end":"2026-05-26T08:47:03.05529Z","steps":["trace[1221323614] 'agreement among raft nodes before linearized reading' (duration: 807.767057ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:47:03.055331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:47:02.247342Z","time spent":"807.983151ms","remote":"127.0.0.1:43306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-26T08:47:03.057625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"809.425334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-26T08:47:03.05776Z","caller":"traceutil/trace.go:171","msg":"trace[300268092] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:618; }","duration":"809.628507ms","start":"2026-05-26T08:47:02.248115Z","end":"2026-05-26T08:47:03.057743Z","steps":["trace[300268092] 'agreement among raft nodes before linearized reading' (duration: 806.917834ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:47:03.057857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:47:02.248033Z","time spent":"809.81008ms","remote":"127.0.0.1:43306","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":"warn","ts":"2026-05-26T08:47:03.058557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"808.195464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-26T08:47:03.058755Z","caller":"traceutil/trace.go:171","msg":"trace[192222432] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:618; }","duration":"808.274485ms","start":"2026-05-26T08:47:02.250341Z","end":"2026-05-26T08:47:03.058616Z","steps":["trace[192222432] 'agreement among raft nodes before linearized reading' (duration: 804.649268ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:47:03.058818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:47:02.250304Z","time spent":"808.497468ms","remote":"127.0.0.1:43306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-26T08:47:21.508189Z","caller":"traceutil/trace.go:171","msg":"trace[614285858] transaction","detail":"{read_only:false; response_revision:775; number_of_response:1; }","duration":"353.186241ms","start":"2026-05-26T08:47:21.154968Z","end":"2026-05-26T08:47:21.508155Z","steps":["trace[614285858] 'process raft request' (duration: 352.982478ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:47:21.508415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:47:21.154939Z","time spent":"353.361874ms","remote":"127.0.0.1:43306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-26T08:47:21.517139Z","caller":"traceutil/trace.go:171","msg":"trace[1941111746] linearizableReadLoop","detail":"{readStateIndex:808; appliedIndex:806; }","duration":"352.292918ms","start":"2026-05-26T08:47:21.164826Z","end":"2026-05-26T08:47:21.517119Z","steps":["trace[1941111746] 'read index received' (duration: 343.133844ms)","trace[1941111746] 'applied index is now lower than readState.Index' (duration: 9.158084ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T08:47:21.517256Z","caller":"traceutil/trace.go:171","msg":"trace[1137522938] transaction","detail":"{read_only:false; response_revision:776; number_of_response:1; }","duration":"361.827569ms","start":"2026-05-26T08:47:21.155406Z","end":"2026-05-26T08:47:21.517233Z","steps":["trace[1137522938] 'process raft request' (duration: 361.538504ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:47:21.51737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:47:21.155388Z","time spent":"361.91689ms","remote":"127.0.0.1:43236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4857,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-26T08:47:21.517437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.591313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-26T08:47:21.517467Z","caller":"traceutil/trace.go:171","msg":"trace[1117440847] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:776; }","duration":"352.669114ms","start":"2026-05-26T08:47:21.16479Z","end":"2026-05-26T08:47:21.517459Z","steps":["trace[1117440847] 'agreement among raft nodes before linearized reading' (duration: 352.425801ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:47:21.517491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:47:21.164774Z","time spent":"352.712164ms","remote":"127.0.0.1:43306","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":"warn","ts":"2026-05-26T08:47:21.517471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.731904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-26T08:47:21.517531Z","caller":"traceutil/trace.go:171","msg":"trace[1200813088] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:776; }","duration":"341.825736ms","start":"2026-05-26T08:47:21.175689Z","end":"2026-05-26T08:47:21.517515Z","steps":["trace[1200813088] 'agreement among raft nodes before linearized reading' (duration: 341.652093ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:47:21.517559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:47:21.175676Z","time spent":"341.876936ms","remote":"127.0.0.1:43306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-26T08:47:21.517532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.960097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-26T08:47:21.517631Z","caller":"traceutil/trace.go:171","msg":"trace[762512119] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:776; }","duration":"346.072268ms","start":"2026-05-26T08:47:21.171538Z","end":"2026-05-26T08:47:21.51761Z","steps":["trace[762512119] 'agreement among raft nodes before linearized reading' (duration: 345.797954ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:47:21.517685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:47:21.171528Z","time spent":"346.146229ms","remote":"127.0.0.1:43306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-26T08:47:24.871991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"819.875863ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-26T08:47:24.872404Z","caller":"traceutil/trace.go:171","msg":"trace[889663367] linearizableReadLoop","detail":"{readStateIndex:832; appliedIndex:830; }","duration":"134.629025ms","start":"2026-05-26T08:47:24.737755Z","end":"2026-05-26T08:47:24.872384Z","steps":["trace[889663367] 'read index received' (duration: 51.821µs)","trace[889663367] 'applied index is now lower than readState.Index' (duration: 134.575794ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T08:47:24.872754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.9927ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-26T08:47:24.872799Z","caller":"traceutil/trace.go:171","msg":"trace[568149944] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:799; }","duration":"135.086281ms","start":"2026-05-26T08:47:24.737702Z","end":"2026-05-26T08:47:24.872788Z","steps":["trace[568149944] 'agreement among raft nodes before linearized reading' (duration: 134.744386ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T08:47:24.87276Z","caller":"traceutil/trace.go:171","msg":"trace[1650567674] transaction","detail":"{read_only:false; response_revision:799; number_of_response:1; }","duration":"697.471684ms","start":"2026-05-26T08:47:24.175261Z","end":"2026-05-26T08:47:24.872732Z","steps":["trace[1650567674] 'process raft request' (duration: 696.828315ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:47:24.87327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:47:24.175222Z","time spent":"697.919681ms","remote":"127.0.0.1:43274","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-26T08:47:59.567336Z","caller":"traceutil/trace.go:171","msg":"trace[605236952] linearizableReadLoop","detail":"{readStateIndex:1090; appliedIndex:1089; }","duration":"218.230071ms","start":"2026-05-26T08:47:59.349086Z","end":"2026-05-26T08:47:59.567316Z","steps":["trace[605236952] 'read index received' (duration: 218.14002ms)","trace[605236952] 'applied index is now lower than readState.Index' (duration: 89.231µs)"],"step_count":2} {"level":"info","ts":"2026-05-26T08:47:59.567376Z","caller":"traceutil/trace.go:171","msg":"trace[1395557404] transaction","detail":"{read_only:false; response_revision:1047; number_of_response:1; }","duration":"218.311403ms","start":"2026-05-26T08:47:59.349042Z","end":"2026-05-26T08:47:59.567353Z","steps":["trace[1395557404] 'process raft request' (duration: 218.14293ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:47:59.567536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.418314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:1490"} {"level":"info","ts":"2026-05-26T08:47:59.567571Z","caller":"traceutil/trace.go:171","msg":"trace[1916409397] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:1047; }","duration":"218.519305ms","start":"2026-05-26T08:47:59.349042Z","end":"2026-05-26T08:47:59.567562Z","steps":["trace[1916409397] 'agreement among raft nodes before linearized reading' (duration: 218.360153ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T08:47:59.574501Z","caller":"traceutil/trace.go:171","msg":"trace[1885064139] transaction","detail":"{read_only:false; response_revision:1048; number_of_response:1; }","duration":"108.377091ms","start":"2026-05-26T08:47:59.466106Z","end":"2026-05-26T08:47:59.574483Z","steps":["trace[1885064139] 'process raft request' (duration: 108.27092ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T08:48:10.119258Z","caller":"traceutil/trace.go:171","msg":"trace[1074784513] linearizableReadLoop","detail":"{readStateIndex:1156; appliedIndex:1155; }","duration":"266.580873ms","start":"2026-05-26T08:48:09.852661Z","end":"2026-05-26T08:48:10.119242Z","steps":["trace[1074784513] 'read index received' (duration: 266.444151ms)","trace[1074784513] 'applied index is now lower than readState.Index' (duration: 136.142µs)"],"step_count":2} {"level":"warn","ts":"2026-05-26T08:48:10.119531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.894642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-26T08:48:10.119597Z","caller":"traceutil/trace.go:171","msg":"trace[960625409] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1110; }","duration":"208.003924ms","start":"2026-05-26T08:48:09.91157Z","end":"2026-05-26T08:48:10.119574Z","steps":["trace[960625409] 'agreement among raft nodes before linearized reading' (duration: 207.819841ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:48:10.119674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.723419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-26T08:48:10.119725Z","caller":"traceutil/trace.go:171","msg":"trace[415058314] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1110; }","duration":"207.848001ms","start":"2026-05-26T08:48:09.911871Z","end":"2026-05-26T08:48:10.119719Z","steps":["trace[415058314] 'agreement among raft nodes before linearized reading' (duration: 207.628599ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T08:48:10.119342Z","caller":"traceutil/trace.go:171","msg":"trace[1771524640] transaction","detail":"{read_only:false; response_revision:1110; number_of_response:1; }","duration":"428.562799ms","start":"2026-05-26T08:48:09.690759Z","end":"2026-05-26T08:48:10.119322Z","steps":["trace[1771524640] 'process raft request' (duration: 428.387906ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:48:10.119942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:48:09.690727Z","time spent":"429.128806ms","remote":"127.0.0.1:43306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-26T08:48:10.120049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.398273ms","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-05-26T08:48:10.120068Z","caller":"traceutil/trace.go:171","msg":"trace[549063002] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1110; }","duration":"267.503095ms","start":"2026-05-26T08:48:09.85256Z","end":"2026-05-26T08:48:10.120063Z","steps":["trace[549063002] 'agreement among raft nodes before linearized reading' (duration: 266.759895ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:48:10.120215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.694664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-26T08:48:10.120258Z","caller":"traceutil/trace.go:171","msg":"trace[354089704] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1110; }","duration":"209.784235ms","start":"2026-05-26T08:48:09.910461Z","end":"2026-05-26T08:48:10.120245Z","steps":["trace[354089704] 'agreement among raft nodes before linearized reading' (duration: 208.894904ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T08:48:17.321666Z","caller":"traceutil/trace.go:171","msg":"trace[376661620] transaction","detail":"{read_only:false; response_revision:1140; number_of_response:1; }","duration":"104.311046ms","start":"2026-05-26T08:48:17.217313Z","end":"2026-05-26T08:48:17.321624Z","steps":["trace[376661620] 'process raft request' (duration: 104.099693ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T08:48:22.393852Z","caller":"traceutil/trace.go:171","msg":"trace[671942978] transaction","detail":"{read_only:false; response_revision:1163; number_of_response:1; }","duration":"104.057091ms","start":"2026-05-26T08:48:22.289774Z","end":"2026-05-26T08:48:22.393831Z","steps":["trace[671942978] 'process raft request' (duration: 103.835118ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T08:48:22.393769Z","caller":"traceutil/trace.go:171","msg":"trace[686308696] transaction","detail":"{read_only:false; response_revision:1162; number_of_response:1; }","duration":"104.139631ms","start":"2026-05-26T08:48:22.289558Z","end":"2026-05-26T08:48:22.393698Z","steps":["trace[686308696] 'process raft request' (duration: 103.874538ms)"],"step_count":1}