{"level":"warn","ts":"2026-02-11T21:53:26.425578Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-11T21:53:26.425677Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.53: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.53:2380","--initial-cluster=instance=https://199.204.45.53:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.53:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.53: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-02-11T21:53:26.427282Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-11T21:53:26.427301Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.53:2380"]} {"level":"info","ts":"2026-02-11T21:53:26.430432Z","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-02-11T21:53:26.431481Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.53:2379"]} {"level":"info","ts":"2026-02-11T21:53:26.432601Z","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":2,"max-cpu-available":2,"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.53:2380"],"listen-peer-urls":["https://199.204.45.53:2380"],"advertise-client-urls":["https://199.204.45.53:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.53:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.53: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-02-11T21:53:26.446275Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"12.199037ms"} {"level":"info","ts":"2026-02-11T21:53:26.469622Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"c47ca1a7b1fe506b","cluster-id":"95bd21e158fabf07"} {"level":"info","ts":"2026-02-11T21:53:26.469697Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b switched to configuration voters=()"} {"level":"info","ts":"2026-02-11T21:53:26.469717Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became follower at term 0"} {"level":"info","ts":"2026-02-11T21:53:26.469724Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft c47ca1a7b1fe506b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-11T21:53:26.469743Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became follower at term 1"} {"level":"info","ts":"2026-02-11T21:53:26.469772Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b switched to configuration voters=(14158369070163841131)"} {"level":"warn","ts":"2026-02-11T21:53:26.489765Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-11T21:53:26.505435Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-11T21:53:26.510885Z","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-02-11T21:53:26.514755Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"c47ca1a7b1fe506b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-11T21:53:26.516332Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"c47ca1a7b1fe506b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-11T21:53:26.517041Z","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-02-11T21:53:26.517186Z","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-02-11T21:53:26.517995Z","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-02-11T21:53:26.518309Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b switched to configuration voters=(14158369070163841131)"} {"level":"info","ts":"2026-02-11T21:53:26.519086Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"95bd21e158fabf07","local-member-id":"c47ca1a7b1fe506b","added-peer-id":"c47ca1a7b1fe506b","added-peer-peer-urls":["https://199.204.45.53:2380"]} {"level":"info","ts":"2026-02-11T21:53:26.541034Z","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-02-11T21:53:26.541123Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.53:2380"} {"level":"info","ts":"2026-02-11T21:53:26.541927Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.53:2380"} {"level":"info","ts":"2026-02-11T21:53:26.541993Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"c47ca1a7b1fe506b","initial-advertise-peer-urls":["https://199.204.45.53:2380"],"listen-peer-urls":["https://199.204.45.53:2380"],"advertise-client-urls":["https://199.204.45.53:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.53:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-11T21:53:26.542064Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-11T21:53:26.971873Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b is starting a new election at term 1"} {"level":"info","ts":"2026-02-11T21:53:26.97196Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became pre-candidate at term 1"} {"level":"info","ts":"2026-02-11T21:53:26.972007Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b received MsgPreVoteResp from c47ca1a7b1fe506b at term 1"} {"level":"info","ts":"2026-02-11T21:53:26.972061Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became candidate at term 2"} {"level":"info","ts":"2026-02-11T21:53:26.97214Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b received MsgVoteResp from c47ca1a7b1fe506b at term 2"} {"level":"info","ts":"2026-02-11T21:53:26.97217Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became leader at term 2"} {"level":"info","ts":"2026-02-11T21:53:26.972201Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: c47ca1a7b1fe506b elected leader c47ca1a7b1fe506b at term 2"} {"level":"info","ts":"2026-02-11T21:53:26.974457Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"c47ca1a7b1fe506b","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.53:2379]}","request-path":"/0/members/c47ca1a7b1fe506b/attributes","cluster-id":"95bd21e158fabf07","publish-timeout":"7s"} {"level":"info","ts":"2026-02-11T21:53:26.974535Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-11T21:53:26.974831Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-11T21:53:26.974955Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-11T21:53:26.976642Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-11T21:53:26.981595Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"95bd21e158fabf07","local-member-id":"c47ca1a7b1fe506b","cluster-version":"3.5"} {"level":"info","ts":"2026-02-11T21:53:26.981714Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-11T21:53:26.981777Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-11T21:53:26.987271Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.53:2379"} {"level":"info","ts":"2026-02-11T21:53:26.998893Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-11T21:53:26.998913Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"warn","ts":"2026-02-11T21:53:30.728349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.895124ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-11T21:53:30.728678Z","caller":"traceutil/trace.go:171","msg":"trace[355225669] transaction","detail":"{read_only:false; response_revision:208; number_of_response:1; }","duration":"364.311722ms","start":"2026-02-11T21:53:30.364347Z","end":"2026-02-11T21:53:30.728659Z","steps":["trace[355225669] 'process raft request' (duration: 20.421265ms)","trace[355225669] 'compare' (duration: 342.717689ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T21:53:30.728922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T21:53:30.364335Z","time spent":"364.481519ms","remote":"127.0.0.1:48460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":720,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-11T21:53:37.920319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.371618074s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/namespace-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T21:53:37.920401Z","caller":"traceutil/trace.go:171","msg":"trace[712712916] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/namespace-controller; range_end:; response_count:0; response_revision:332; }","duration":"2.371738448s","start":"2026-02-11T21:53:35.548645Z","end":"2026-02-11T21:53:37.920384Z","steps":["trace[712712916] 'range keys from in-memory index tree' (duration: 2.371561682s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T21:53:37.920442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T21:53:35.54863Z","time spent":"2.371801121s","remote":"127.0.0.1:48294","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":0,"response size":29,"request content":"key:\"/registry/serviceaccounts/kube-system/namespace-controller\" "} {"level":"warn","ts":"2026-02-11T21:53:37.920545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"831.893965ms","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-02-11T21:53:37.92062Z","caller":"traceutil/trace.go:171","msg":"trace[866571811] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:332; }","duration":"831.995688ms","start":"2026-02-11T21:53:37.088603Z","end":"2026-02-11T21:53:37.920599Z","steps":["trace[866571811] 'range keys from in-memory index tree' (duration: 831.823883ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T21:53:37.920671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T21:53:37.088593Z","time spent":"832.0675ms","remote":"127.0.0.1:48368","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-02-11T21:53:37.920766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"833.922788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-02-11T21:53:37.920625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"713.975221ms","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-02-11T21:53:37.920824Z","caller":"traceutil/trace.go:171","msg":"trace[1414661353] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:332; }","duration":"834.016881ms","start":"2026-02-11T21:53:37.086798Z","end":"2026-02-11T21:53:37.920814Z","steps":["trace[1414661353] 'range keys from in-memory index tree' (duration: 833.852696ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T21:53:37.920854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T21:53:37.086778Z","time spent":"834.068573ms","remote":"127.0.0.1:48368","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-02-11T21:53:37.92085Z","caller":"traceutil/trace.go:171","msg":"trace[15458133] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:332; }","duration":"714.239828ms","start":"2026-02-11T21:53:37.206599Z","end":"2026-02-11T21:53:37.920839Z","steps":["trace[15458133] 'range keys from in-memory index tree' (duration: 713.884997ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T21:53:37.920995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T21:53:37.206578Z","time spent":"714.401974ms","remote":"127.0.0.1:48368","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-02-11T21:53:37.921027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.159200291s","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-02-11T21:53:37.921061Z","caller":"traceutil/trace.go:171","msg":"trace[1158749597] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:332; }","duration":"1.159288374s","start":"2026-02-11T21:53:36.761763Z","end":"2026-02-11T21:53:37.921052Z","steps":["trace[1158749597] 'range keys from in-memory index tree' (duration: 1.159129499s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T21:53:37.921088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T21:53:36.761745Z","time spent":"1.159336495s","remote":"127.0.0.1:48368","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-02-11T21:53:38.431083Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5794897207348021227,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-11T21:53:38.931661Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5794897207348021227,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-11T21:53:39.287386Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.35740795s","expected-duration":"1s"} {"level":"info","ts":"2026-02-11T21:53:39.287981Z","caller":"traceutil/trace.go:171","msg":"trace[1640532377] transaction","detail":"{read_only:false; response_revision:333; number_of_response:1; }","duration":"1.35805088s","start":"2026-02-11T21:53:37.929909Z","end":"2026-02-11T21:53:39.28796Z","steps":["trace[1640532377] 'process raft request' (duration: 1.357933507s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T21:53:39.288475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T21:53:37.929891Z","time spent":"1.358314899s","remote":"127.0.0.1:48368","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T21:53:40.456154Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.168424309s","expected-duration":"1s"} {"level":"info","ts":"2026-02-11T21:53:40.456821Z","caller":"traceutil/trace.go:171","msg":"trace[378095556] linearizableReadLoop","detail":"{readStateIndex:347; appliedIndex:344; }","duration":"2.526672935s","start":"2026-02-11T21:53:37.930102Z","end":"2026-02-11T21:53:40.456775Z","steps":["trace[378095556] 'read index received' (duration: 1.358490104s)","trace[378095556] 'applied index is now lower than readState.Index' (duration: 1.168181861s)"],"step_count":2} {"level":"info","ts":"2026-02-11T21:53:40.457254Z","caller":"traceutil/trace.go:171","msg":"trace[593619793] transaction","detail":"{read_only:false; response_revision:334; number_of_response:1; }","duration":"2.527027716s","start":"2026-02-11T21:53:37.930214Z","end":"2026-02-11T21:53:40.457242Z","steps":["trace[593619793] 'process raft request' (duration: 2.526320505s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T21:53:40.457506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T21:53:37.930199Z","time spent":"2.527207151s","remote":"127.0.0.1:48368","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T21:53:40.457789Z","caller":"traceutil/trace.go:171","msg":"trace[270358118] transaction","detail":"{read_only:false; response_revision:335; number_of_response:1; }","duration":"2.527460769s","start":"2026-02-11T21:53:37.930315Z","end":"2026-02-11T21:53:40.457776Z","steps":["trace[270358118] 'process raft request' (duration: 2.526357316s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T21:53:40.457958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T21:53:37.930307Z","time spent":"2.527569222s","remote":"127.0.0.1:48368","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-02-11T21:53:40.458214Z","caller":"traceutil/trace.go:171","msg":"trace[1518484169] transaction","detail":"{read_only:false; response_revision:336; number_of_response:1; }","duration":"2.527577241s","start":"2026-02-11T21:53:37.930614Z","end":"2026-02-11T21:53:40.458191Z","steps":["trace[1518484169] 'process raft request' (duration: 2.526123818s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T21:53:40.458524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T21:53:37.930604Z","time spent":"2.527879772s","remote":"127.0.0.1:48368","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-02-11T21:53:40.458814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.528687446s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-02-11T21:53:40.458905Z","caller":"traceutil/trace.go:171","msg":"trace[721131839] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:336; }","duration":"2.52882125s","start":"2026-02-11T21:53:37.930063Z","end":"2026-02-11T21:53:40.458884Z","steps":["trace[721131839] 'agreement among raft nodes before linearized reading' (duration: 2.528650775s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T21:53:40.459114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T21:53:37.930046Z","time spent":"2.528993555s","remote":"127.0.0.1:48192","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":375,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-02-11T21:53:40.471469Z","caller":"traceutil/trace.go:171","msg":"trace[491339926] transaction","detail":"{read_only:false; response_revision:337; number_of_response:1; }","duration":"1.074576127s","start":"2026-02-11T21:53:39.396872Z","end":"2026-02-11T21:53:40.471448Z","steps":["trace[491339926] 'process raft request' (duration: 1.074496025s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T21:53:40.47166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T21:53:39.396851Z","time spent":"1.074658199s","remote":"127.0.0.1:48368","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-02-11T21:53:40.472164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.470545541s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T21:53:40.472247Z","caller":"traceutil/trace.go:171","msg":"trace[2124441516] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:337; }","duration":"2.470629854s","start":"2026-02-11T21:53:38.001606Z","end":"2026-02-11T21:53:40.472236Z","steps":["trace[2124441516] 'agreement among raft nodes before linearized reading' (duration: 2.470533531s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T21:53:40.472854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.193529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6894"} {"level":"info","ts":"2026-02-11T21:53:40.473114Z","caller":"traceutil/trace.go:171","msg":"trace[490066691] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:337; }","duration":"389.770526ms","start":"2026-02-11T21:53:40.083333Z","end":"2026-02-11T21:53:40.473103Z","steps":["trace[490066691] 'agreement among raft nodes before linearized reading' (duration: 389.20598ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T21:53:40.473501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T21:53:40.083271Z","time spent":"390.21955ms","remote":"127.0.0.1:48274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":6918,"request content":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" "} {"level":"info","ts":"2026-02-11T21:53:51.540345Z","caller":"traceutil/trace.go:171","msg":"trace[1653283038] transaction","detail":"{read_only:false; response_revision:455; number_of_response:1; }","duration":"193.656278ms","start":"2026-02-11T21:53:51.346669Z","end":"2026-02-11T21:53:51.540326Z","steps":["trace[1653283038] 'process raft request' (duration: 193.557666ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T21:53:52.695315Z","caller":"traceutil/trace.go:171","msg":"trace[1781921226] transaction","detail":"{read_only:false; response_revision:458; number_of_response:1; }","duration":"119.655405ms","start":"2026-02-11T21:53:52.575641Z","end":"2026-02-11T21:53:52.695297Z","steps":["trace[1781921226] 'process raft request' (duration: 119.537072ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T21:54:00.841086Z","caller":"traceutil/trace.go:171","msg":"trace[899272952] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"245.987498ms","start":"2026-02-11T21:54:00.595082Z","end":"2026-02-11T21:54:00.84107Z","steps":["trace[899272952] 'process raft request' (duration: 245.838635ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T21:54:00.841292Z","caller":"traceutil/trace.go:171","msg":"trace[708189430] linearizableReadLoop","detail":"{readStateIndex:524; appliedIndex:524; }","duration":"235.210719ms","start":"2026-02-11T21:54:00.606063Z","end":"2026-02-11T21:54:00.841274Z","steps":["trace[708189430] 'read index received' (duration: 235.208139ms)","trace[708189430] 'applied index is now lower than readState.Index' (duration: 2.14µs)"],"step_count":2} {"level":"warn","ts":"2026-02-11T21:54:00.841547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.471686ms","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-02-11T21:54:00.841566Z","caller":"traceutil/trace.go:171","msg":"trace[642722168] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:507; }","duration":"235.517377ms","start":"2026-02-11T21:54:00.606043Z","end":"2026-02-11T21:54:00.84156Z","steps":["trace[642722168] 'agreement among raft nodes before linearized reading' (duration: 235.477136ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T21:54:02.071646Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5794897207348021580,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-11T21:54:02.274325Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.181824105s","expected-duration":"1s"} {"level":"info","ts":"2026-02-11T21:54:02.274656Z","caller":"traceutil/trace.go:171","msg":"trace[1341815282] linearizableReadLoop","detail":"{readStateIndex:530; appliedIndex:529; }","duration":"704.013214ms","start":"2026-02-11T21:54:01.570603Z","end":"2026-02-11T21:54:02.274617Z","steps":["trace[1341815282] 'read index received' (duration: 703.82755ms)","trace[1341815282] 'applied index is now lower than readState.Index' (duration: 184.954µs)"],"step_count":2} {"level":"warn","ts":"2026-02-11T21:54:02.274912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"704.266851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-11T21:54:02.274961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"690.698133ms","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-02-11T21:54:02.274988Z","caller":"traceutil/trace.go:171","msg":"trace[1650007183] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:512; }","duration":"704.415275ms","start":"2026-02-11T21:54:01.570555Z","end":"2026-02-11T21:54:02.27497Z","steps":["trace[1650007183] 'agreement among raft nodes before linearized reading' (duration: 704.233881ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T21:54:02.274999Z","caller":"traceutil/trace.go:171","msg":"trace[1932552932] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:512; }","duration":"690.767245ms","start":"2026-02-11T21:54:01.584219Z","end":"2026-02-11T21:54:02.274986Z","steps":["trace[1932552932] 'agreement among raft nodes before linearized reading' (duration: 690.693344ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T21:54:02.275036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T21:54:01.570532Z","time spent":"704.494036ms","remote":"127.0.0.1:48076","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-02-11T21:54:02.275126Z","caller":"traceutil/trace.go:171","msg":"trace[962906592] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"1.182685737s","start":"2026-02-11T21:54:01.092432Z","end":"2026-02-11T21:54:02.275117Z","steps":["trace[962906592] 'process raft request' (duration: 1.182045691s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T21:54:02.275197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T21:54:01.092419Z","time spent":"1.182730227s","remote":"127.0.0.1:48268","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4253,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T21:54:02.275038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T21:54:01.584208Z","time spent":"690.819236ms","remote":"127.0.0.1:48368","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-02-11T21:54:09.044412Z","caller":"traceutil/trace.go:171","msg":"trace[682333230] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"161.22383ms","start":"2026-02-11T21:54:08.883171Z","end":"2026-02-11T21:54:09.044394Z","steps":["trace[682333230] 'process raft request' (duration: 161.086057ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T21:54:10.101689Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.048348135s","expected-duration":"1s"} {"level":"info","ts":"2026-02-11T21:54:10.101927Z","caller":"traceutil/trace.go:171","msg":"trace[1213163557] linearizableReadLoop","detail":"{readStateIndex:572; appliedIndex:571; }","duration":"1.208783203s","start":"2026-02-11T21:54:08.89312Z","end":"2026-02-11T21:54:10.101904Z","steps":["trace[1213163557] 'read index received' (duration: 151.335067ms)","trace[1213163557] 'applied index is now lower than readState.Index' (duration: 1.057447466s)"],"step_count":2} {"level":"info","ts":"2026-02-11T21:54:10.102039Z","caller":"traceutil/trace.go:171","msg":"trace[1587843160] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"1.21719376s","start":"2026-02-11T21:54:08.884839Z","end":"2026-02-11T21:54:10.102033Z","steps":["trace[1587843160] 'process raft request' (duration: 1.216984156s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T21:54:10.102099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T21:54:08.884826Z","time spent":"1.217227662s","remote":"127.0.0.1:48274","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16799,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T21:54:10.102304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.201897763s","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-02-11T21:54:10.102336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.209212383s","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-02-11T21:54:10.10236Z","caller":"traceutil/trace.go:171","msg":"trace[1336608055] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:553; }","duration":"1.209254985s","start":"2026-02-11T21:54:08.893098Z","end":"2026-02-11T21:54:10.102353Z","steps":["trace[1336608055] 'agreement among raft nodes before linearized reading' (duration: 1.209174423s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T21:54:10.102375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T21:54:08.893089Z","time spent":"1.209282775s","remote":"127.0.0.1:48368","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-11T21:54:10.102371Z","caller":"traceutil/trace.go:171","msg":"trace[1924999429] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:553; }","duration":"1.201984195s","start":"2026-02-11T21:54:08.900367Z","end":"2026-02-11T21:54:10.102351Z","steps":["trace[1924999429] 'agreement among raft nodes before linearized reading' (duration: 1.20176922s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T21:54:10.102413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T21:54:08.90036Z","time spent":"1.202042196s","remote":"127.0.0.1:48368","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-02-11T21:54:10.102487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"534.054072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T21:54:10.102502Z","caller":"traceutil/trace.go:171","msg":"trace[835035320] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:553; }","duration":"534.093583ms","start":"2026-02-11T21:54:09.568405Z","end":"2026-02-11T21:54:10.102498Z","steps":["trace[835035320] 'agreement among raft nodes before linearized reading' (duration: 534.068722ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T21:54:10.102514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T21:54:09.568379Z","time spent":"534.131933ms","remote":"127.0.0.1:48076","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-02-11T21:54:11.51722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"724.625476ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T21:54:11.517297Z","caller":"traceutil/trace.go:171","msg":"trace[107551540] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"1.403770921s","start":"2026-02-11T21:54:10.113501Z","end":"2026-02-11T21:54:11.517271Z","steps":["trace[107551540] 'process raft request' (duration: 679.049863ms)","trace[107551540] 'compare' (duration: 724.266948ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T21:54:11.517334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T21:54:10.113488Z","time spent":"1.403831405s","remote":"127.0.0.1:48368","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-02-11T21:54:11.941592Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.149039891s","expected-duration":"1s"} {"level":"info","ts":"2026-02-11T21:54:11.955096Z","caller":"traceutil/trace.go:171","msg":"trace[353552416] linearizableReadLoop","detail":"{readStateIndex:574; appliedIndex:572; }","duration":"1.637739775s","start":"2026-02-11T21:54:10.317339Z","end":"2026-02-11T21:54:11.955078Z","steps":["trace[353552416] 'read index received' (duration: 475.222275ms)","trace[353552416] 'applied index is now lower than readState.Index' (duration: 1.16251671s)"],"step_count":2} {"level":"info","ts":"2026-02-11T21:54:11.95518Z","caller":"traceutil/trace.go:171","msg":"trace[1366896237] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"1.841552643s","start":"2026-02-11T21:54:10.113618Z","end":"2026-02-11T21:54:11.95517Z","steps":["trace[1366896237] 'process raft request' (duration: 1.828358841s)","trace[1366896237] 'compare' (duration: 12.996687ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T21:54:11.955265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T21:54:10.113611Z","time spent":"1.841586443s","remote":"127.0.0.1:48368","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-02-11T21:54:11.955411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.638057132s","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-02-11T21:54:11.955462Z","caller":"traceutil/trace.go:171","msg":"trace[251225966] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:555; }","duration":"1.638139623s","start":"2026-02-11T21:54:10.317308Z","end":"2026-02-11T21:54:11.955447Z","steps":["trace[251225966] 'agreement among raft nodes before linearized reading' (duration: 1.63801203s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T21:54:11.955492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T21:54:10.317271Z","time spent":"1.638213515s","remote":"127.0.0.1:48368","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-11T21:54:11.983974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.102741576s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.53\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-02-11T21:54:11.984053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.35177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T21:54:11.984104Z","caller":"traceutil/trace.go:171","msg":"trace[565226691] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:556; }","duration":"416.448032ms","start":"2026-02-11T21:54:11.567644Z","end":"2026-02-11T21:54:11.984092Z","steps":["trace[565226691] 'agreement among raft nodes before linearized reading' (duration: 416.34948ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T21:54:11.984126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T21:54:11.567629Z","time spent":"416.493173ms","remote":"127.0.0.1:48076","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-02-11T21:54:11.984245Z","caller":"traceutil/trace.go:171","msg":"trace[1409668682] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"902.776402ms","start":"2026-02-11T21:54:11.081462Z","end":"2026-02-11T21:54:11.984238Z","steps":["trace[1409668682] 'process raft request' (duration: 902.208949ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T21:54:11.984299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T21:54:11.081437Z","time spent":"902.820112ms","remote":"127.0.0.1:48368","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T21:54:11.984516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"936.36902ms","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-02-11T21:54:11.984534Z","caller":"traceutil/trace.go:171","msg":"trace[2051381917] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:556; }","duration":"936.420561ms","start":"2026-02-11T21:54:11.048105Z","end":"2026-02-11T21:54:11.984526Z","steps":["trace[2051381917] 'agreement among raft nodes before linearized reading' (duration: 936.338379ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T21:54:11.984547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T21:54:11.048085Z","time spent":"936.457982ms","remote":"127.0.0.1:48368","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-02-11T21:54:11.984062Z","caller":"traceutil/trace.go:171","msg":"trace[392634794] range","detail":"{range_begin:/registry/masterleases/199.204.45.53; range_end:; response_count:1; response_revision:556; }","duration":"1.102878199s","start":"2026-02-11T21:54:10.881166Z","end":"2026-02-11T21:54:11.984044Z","steps":["trace[392634794] 'agreement among raft nodes before linearized reading' (duration: 1.102618224s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T21:54:11.9879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T21:54:10.881151Z","time spent":"1.106670166s","remote":"127.0.0.1:48116","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.53\" "} {"level":"warn","ts":"2026-02-11T21:54:13.244292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.161482331s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T21:54:13.244382Z","caller":"traceutil/trace.go:171","msg":"trace[82635162] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"1.256678703s","start":"2026-02-11T21:54:11.987692Z","end":"2026-02-11T21:54:13.244371Z","steps":["trace[82635162] 'process raft request' (duration: 95.063929ms)","trace[82635162] 'compare' (duration: 1.161424249s)"],"step_count":2} {"level":"warn","ts":"2026-02-11T21:54:13.244416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T21:54:11.987676Z","time spent":"1.256724264s","remote":"127.0.0.1:48368","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T21:54:13.244524Z","caller":"traceutil/trace.go:171","msg":"trace[2043725798] linearizableReadLoop","detail":"{readStateIndex:578; appliedIndex:576; }","duration":"675.338114ms","start":"2026-02-11T21:54:12.56918Z","end":"2026-02-11T21:54:13.244518Z","steps":["trace[2043725798] 'read index received' (duration: 49.301µs)","trace[2043725798] 'applied index is now lower than readState.Index' (duration: 675.288223ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T21:54:13.244555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"675.372985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T21:54:13.244566Z","caller":"traceutil/trace.go:171","msg":"trace[1738157460] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:558; }","duration":"675.409765ms","start":"2026-02-11T21:54:12.569152Z","end":"2026-02-11T21:54:13.244562Z","steps":["trace[1738157460] 'agreement among raft nodes before linearized reading' (duration: 675.384004ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T21:54:13.244578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T21:54:12.569139Z","time spent":"675.437366ms","remote":"127.0.0.1:48076","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-02-11T21:54:13.244658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T21:54:11.988917Z","time spent":"1.255740651s","remote":"127.0.0.1:48116","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-11T21:54:14.022562Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5794897207348021680,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-11T21:54:14.523526Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5794897207348021680,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-11T21:54:14.820302Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.573704796s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-11T21:54:15.570502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000158069s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-11T21:54:15.570598Z","caller":"traceutil/trace.go:171","msg":"trace[950548828] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000296322s","start":"2026-02-11T21:54:13.570285Z","end":"2026-02-11T21:54:15.570581Z","steps":["trace[950548828] 'agreement among raft nodes before linearized reading' (duration: 2.0001815s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T21:54:15.570646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T21:54:13.570269Z","time spent":"2.000367303s","remote":"127.0.0.1:48076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-11T21:54:16.309775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"530.196601ms","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-02-11T21:54:16.309853Z","caller":"traceutil/trace.go:171","msg":"trace[1783778860] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:558; }","duration":"530.284243ms","start":"2026-02-11T21:54:15.779558Z","end":"2026-02-11T21:54:16.309842Z","steps":["trace[1783778860] 'range keys from in-memory index tree' (duration: 530.18339ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T21:54:16.310218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.473679667s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T21:54:16.310255Z","caller":"traceutil/trace.go:171","msg":"trace[1377016325] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"3.063714418s","start":"2026-02-11T21:54:13.246534Z","end":"2026-02-11T21:54:16.310249Z","steps":["trace[1377016325] 'process raft request' (duration: 1.589968159s)","trace[1377016325] 'compare' (duration: 1.472941611s)"],"step_count":2} {"level":"warn","ts":"2026-02-11T21:54:16.310284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T21:54:13.24652Z","time spent":"3.063748308s","remote":"127.0.0.1:48116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T21:54:16.412911Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.592515724s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-11T21:54:16.643687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.477735ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T21:54:16.644362Z","caller":"traceutil/trace.go:171","msg":"trace[141029016] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"3.016076501s","start":"2026-02-11T21:54:13.62827Z","end":"2026-02-11T21:54:16.644347Z","steps":["trace[141029016] 'process raft request' (duration: 2.784873129s)","trace[141029016] 'compare' (duration: 230.387014ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T21:54:16.644421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T21:54:13.628251Z","time spent":"3.016146622s","remote":"127.0.0.1:48368","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T21:54:16.644581Z","caller":"traceutil/trace.go:171","msg":"trace[619137487] linearizableReadLoop","detail":"{readStateIndex:580; appliedIndex:578; }","duration":"3.122070436s","start":"2026-02-11T21:54:13.522503Z","end":"2026-02-11T21:54:16.644574Z","steps":["trace[619137487] 'read index received' (duration: 1.313956068s)","trace[619137487] 'applied index is now lower than readState.Index' (duration: 1.808113308s)"],"step_count":2} {"level":"warn","ts":"2026-02-11T21:54:16.679009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.71998727s","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-02-11T21:54:16.679042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.719925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-02-11T21:54:16.679064Z","caller":"traceutil/trace.go:171","msg":"trace[1624256074] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:560; }","duration":"364.759197ms","start":"2026-02-11T21:54:16.314299Z","end":"2026-02-11T21:54:16.679058Z","steps":["trace[1624256074] 'agreement among raft nodes before linearized reading' (duration: 364.719566ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T21:54:16.679084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T21:54:16.31429Z","time spent":"364.788167ms","remote":"127.0.0.1:48260","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":445,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"warn","ts":"2026-02-11T21:54:16.679087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.108115987s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T21:54:16.679106Z","caller":"traceutil/trace.go:171","msg":"trace[1801037979] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:560; }","duration":"1.108159339s","start":"2026-02-11T21:54:15.570939Z","end":"2026-02-11T21:54:16.679098Z","steps":["trace[1801037979] 'agreement among raft nodes before linearized reading' (duration: 1.108135188s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T21:54:16.679123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T21:54:15.570923Z","time spent":"1.108196409s","remote":"127.0.0.1:48090","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-02-11T21:54:16.679192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.156701068s","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-02-11T21:54:16.679207Z","caller":"traceutil/trace.go:171","msg":"trace[435593976] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:560; }","duration":"3.156749088s","start":"2026-02-11T21:54:13.522452Z","end":"2026-02-11T21:54:16.679201Z","steps":["trace[435593976] 'agreement among raft nodes before linearized reading' (duration: 3.122170988s)","trace[435593976] 'range keys from in-memory index tree' (duration: 34.536839ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T21:54:16.67922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T21:54:13.522433Z","time spent":"3.156784359s","remote":"127.0.0.1:48368","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-02-11T21:54:16.679256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.431320407s","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-02-11T21:54:16.679276Z","caller":"traceutil/trace.go:171","msg":"trace[3525462] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:560; }","duration":"1.431378718s","start":"2026-02-11T21:54:15.247892Z","end":"2026-02-11T21:54:16.679271Z","steps":["trace[3525462] 'agreement among raft nodes before linearized reading' (duration: 1.431325167s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T21:54:16.679293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T21:54:15.247871Z","time spent":"1.43141829s","remote":"127.0.0.1:48368","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-02-11T21:54:16.679059Z","caller":"traceutil/trace.go:171","msg":"trace[314055932] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:560; }","duration":"2.720083183s","start":"2026-02-11T21:54:13.958963Z","end":"2026-02-11T21:54:16.679047Z","steps":["trace[314055932] 'agreement among raft nodes before linearized reading' (duration: 2.71994557s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T21:54:16.679369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T21:54:13.958943Z","time spent":"2.720420979s","remote":"127.0.0.1:48368","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-02-11T21:54:16.679404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.644437376s","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-02-11T21:54:16.679419Z","caller":"traceutil/trace.go:171","msg":"trace[1524528779] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:560; }","duration":"2.644453476s","start":"2026-02-11T21:54:14.034961Z","end":"2026-02-11T21:54:16.679414Z","steps":["trace[1524528779] 'agreement among raft nodes before linearized reading' (duration: 2.644409316s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T21:54:16.679431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T21:54:14.034916Z","time spent":"2.644511448s","remote":"127.0.0.1:48368","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-02-11T21:54:18.857939Z","caller":"traceutil/trace.go:171","msg":"trace[502225924] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"123.35023ms","start":"2026-02-11T21:54:18.734574Z","end":"2026-02-11T21:54:18.857924Z","steps":["trace[502225924] 'process raft request' (duration: 122.02023ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T21:54:18.858196Z","caller":"traceutil/trace.go:171","msg":"trace[1783879567] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"112.77876ms","start":"2026-02-11T21:54:18.745412Z","end":"2026-02-11T21:54:18.858191Z","steps":["trace[1783879567] 'process raft request' (duration: 112.75696ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T21:54:19.445838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.4237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-02-11T21:54:19.445921Z","caller":"traceutil/trace.go:171","msg":"trace[2053530221] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:584; }","duration":"102.550093ms","start":"2026-02-11T21:54:19.343353Z","end":"2026-02-11T21:54:19.445903Z","steps":["trace[2053530221] 'range keys from in-memory index tree' (duration: 102.335728ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T21:54:21.366042Z","caller":"traceutil/trace.go:171","msg":"trace[1904306838] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"276.841428ms","start":"2026-02-11T21:54:21.089183Z","end":"2026-02-11T21:54:21.366025Z","steps":["trace[1904306838] 'process raft request' (duration: 276.681604ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T21:54:29.636125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.498536ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-11T21:54:29.636339Z","caller":"traceutil/trace.go:171","msg":"trace[1652465879] transaction","detail":"{read_only:false; response_revision:707; number_of_response:1; }","duration":"325.853728ms","start":"2026-02-11T21:54:29.310475Z","end":"2026-02-11T21:54:29.636328Z","steps":["trace[1652465879] 'process raft request' (duration: 217.116947ms)","trace[1652465879] 'compare' (duration: 108.361664ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T21:54:29.636378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T21:54:29.310446Z","time spent":"325.907909ms","remote":"127.0.0.1:48158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":722,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-11T21:54:37.298511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.874599ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-11T21:54:37.298586Z","caller":"traceutil/trace.go:171","msg":"trace[1548266660] linearizableReadLoop","detail":"{readStateIndex:795; appliedIndex:794; }","duration":"224.413988ms","start":"2026-02-11T21:54:37.074162Z","end":"2026-02-11T21:54:37.298576Z","steps":["trace[1548266660] 'read index received' (duration: 10.363956ms)","trace[1548266660] 'applied index is now lower than readState.Index' (duration: 214.049462ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T21:54:37.298635Z","caller":"traceutil/trace.go:171","msg":"trace[347261173] transaction","detail":"{read_only:false; response_revision:768; number_of_response:1; }","duration":"245.864554ms","start":"2026-02-11T21:54:37.052764Z","end":"2026-02-11T21:54:37.298629Z","steps":["trace[347261173] 'process raft request' (duration: 31.827062ms)","trace[347261173] 'compare' (duration: 213.609263ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T21:54:37.298924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.454929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T21:54:37.298946Z","caller":"traceutil/trace.go:171","msg":"trace[1236124948] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:768; }","duration":"224.811466ms","start":"2026-02-11T21:54:37.074127Z","end":"2026-02-11T21:54:37.298938Z","steps":["trace[1236124948] 'agreement among raft nodes before linearized reading' (duration: 224.47363ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T21:55:25.905661Z","caller":"traceutil/trace.go:171","msg":"trace[1277071675] transaction","detail":"{read_only:false; response_revision:923; number_of_response:1; }","duration":"144.004602ms","start":"2026-02-11T21:55:25.761641Z","end":"2026-02-11T21:55:25.905646Z","steps":["trace[1277071675] 'process raft request' (duration: 92.869756ms)","trace[1277071675] 'compare' (duration: 51.049135ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T21:55:25.905661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.525543ms","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-02-11T21:55:25.905802Z","caller":"traceutil/trace.go:171","msg":"trace[968759235] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:922; }","duration":"126.704065ms","start":"2026-02-11T21:55:25.779079Z","end":"2026-02-11T21:55:25.905783Z","steps":["trace[968759235] 'range keys from in-memory index tree' (duration: 126.506252ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T21:55:40.305525Z","caller":"traceutil/trace.go:171","msg":"trace[1670488447] transaction","detail":"{read_only:false; response_revision:986; number_of_response:1; }","duration":"265.496546ms","start":"2026-02-11T21:55:40.040001Z","end":"2026-02-11T21:55:40.305498Z","steps":["trace[1670488447] 'process raft request' (duration: 265.332204ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T21:55:40.982924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.369541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T21:55:40.982998Z","caller":"traceutil/trace.go:171","msg":"trace[1993706919] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:986; }","duration":"413.488503ms","start":"2026-02-11T21:55:40.569491Z","end":"2026-02-11T21:55:40.982979Z","steps":["trace[1993706919] 'range keys from in-memory index tree' (duration: 413.318471ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T21:55:40.983726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T21:55:40.569477Z","time spent":"414.106571ms","remote":"127.0.0.1:48076","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-02-11T21:55:41.333692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T21:55:40.985518Z","time spent":"348.168251ms","remote":"127.0.0.1:48116","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-11T21:55:41.333713Z","caller":"traceutil/trace.go:171","msg":"trace[1200779552] linearizableReadLoop","detail":"{readStateIndex:1029; appliedIndex:1028; }","duration":"347.755275ms","start":"2026-02-11T21:55:40.985902Z","end":"2026-02-11T21:55:41.333658Z","steps":["trace[1200779552] 'read index received' (duration: 347.468911ms)","trace[1200779552] 'applied index is now lower than readState.Index' (duration: 284.524µs)"],"step_count":2} {"level":"warn","ts":"2026-02-11T21:55:41.333926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.955548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T21:55:41.334576Z","caller":"traceutil/trace.go:171","msg":"trace[1710981049] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:986; }","duration":"348.666098ms","start":"2026-02-11T21:55:40.985876Z","end":"2026-02-11T21:55:41.334542Z","steps":["trace[1710981049] 'agreement among raft nodes before linearized reading' (duration: 347.942537ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T21:55:41.334762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T21:55:40.985844Z","time spent":"348.894082ms","remote":"127.0.0.1:48090","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-02-11T21:55:41.335183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.289072ms","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-02-11T21:55:41.33532Z","caller":"traceutil/trace.go:171","msg":"trace[1852996859] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:986; }","duration":"190.427454ms","start":"2026-02-11T21:55:41.144877Z","end":"2026-02-11T21:55:41.335304Z","steps":["trace[1852996859] 'agreement among raft nodes before linearized reading' (duration: 190.038558ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T21:55:41.335831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.386526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-11T21:55:41.336013Z","caller":"traceutil/trace.go:171","msg":"trace[2062934108] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:986; }","duration":"133.585149ms","start":"2026-02-11T21:55:41.202411Z","end":"2026-02-11T21:55:41.335996Z","steps":["trace[2062934108] 'agreement among raft nodes before linearized reading' (duration: 133.316096ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T21:55:41.715781Z","caller":"traceutil/trace.go:171","msg":"trace[414881996] transaction","detail":"{read_only:false; response_revision:992; number_of_response:1; }","duration":"107.497115ms","start":"2026-02-11T21:55:41.608262Z","end":"2026-02-11T21:55:41.715759Z","steps":["trace[414881996] 'process raft request' (duration: 61.528751ms)","trace[414881996] 'compare' (duration: 45.483236ms)"],"step_count":2}