{"level":"warn","ts":"2026-01-28T01:09:50.661107Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-01-28T01:09:50.661193Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.84: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://162.253.55.84:2380","--initial-cluster=instance=https://162.253.55.84:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.84:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.84: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-01-28T01:09:50.661265Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-01-28T01:09:50.661274Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.84:2380"]} {"level":"info","ts":"2026-01-28T01:09:50.661289Z","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-01-28T01:09:50.661959Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.84:2379"]} {"level":"info","ts":"2026-01-28T01:09:50.662049Z","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://162.253.55.84:2380"],"listen-peer-urls":["https://162.253.55.84:2380"],"advertise-client-urls":["https://162.253.55.84:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.84:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.84: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-01-28T01:09:50.668406Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.124101ms"} {"level":"info","ts":"2026-01-28T01:09:50.676901Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"ca4c710a8d5349d7","cluster-id":"8e28a51debf1c8ab"} {"level":"info","ts":"2026-01-28T01:09:50.676948Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca4c710a8d5349d7 switched to configuration voters=()"} {"level":"info","ts":"2026-01-28T01:09:50.676975Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca4c710a8d5349d7 became follower at term 0"} {"level":"info","ts":"2026-01-28T01:09:50.676985Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft ca4c710a8d5349d7 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-01-28T01:09:50.676991Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca4c710a8d5349d7 became follower at term 1"} {"level":"info","ts":"2026-01-28T01:09:50.677019Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca4c710a8d5349d7 switched to configuration voters=(14577150384026110423)"} {"level":"warn","ts":"2026-01-28T01:09:50.687525Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-01-28T01:09:50.705927Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-01-28T01:09:50.708162Z","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-01-28T01:09:50.71263Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"ca4c710a8d5349d7","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-01-28T01:09:50.715987Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"ca4c710a8d5349d7","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-01-28T01:09:50.716872Z","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-01-28T01:09:50.716935Z","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-01-28T01:09:50.716968Z","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-01-28T01:09:50.717141Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca4c710a8d5349d7 switched to configuration voters=(14577150384026110423)"} {"level":"info","ts":"2026-01-28T01:09:50.717249Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"8e28a51debf1c8ab","local-member-id":"ca4c710a8d5349d7","added-peer-id":"ca4c710a8d5349d7","added-peer-peer-urls":["https://162.253.55.84:2380"]} {"level":"info","ts":"2026-01-28T01:09:50.717407Z","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-01-28T01:09:50.717649Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"ca4c710a8d5349d7","initial-advertise-peer-urls":["https://162.253.55.84:2380"],"listen-peer-urls":["https://162.253.55.84:2380"],"advertise-client-urls":["https://162.253.55.84:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.84:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-01-28T01:09:50.717505Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.84:2380"} {"level":"info","ts":"2026-01-28T01:09:50.719591Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.84:2380"} {"level":"info","ts":"2026-01-28T01:09:50.719542Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-01-28T01:09:51.281337Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca4c710a8d5349d7 is starting a new election at term 1"} {"level":"info","ts":"2026-01-28T01:09:51.281432Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca4c710a8d5349d7 became pre-candidate at term 1"} {"level":"info","ts":"2026-01-28T01:09:51.281463Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca4c710a8d5349d7 received MsgPreVoteResp from ca4c710a8d5349d7 at term 1"} {"level":"info","ts":"2026-01-28T01:09:51.281494Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca4c710a8d5349d7 became candidate at term 2"} {"level":"info","ts":"2026-01-28T01:09:51.281517Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca4c710a8d5349d7 received MsgVoteResp from ca4c710a8d5349d7 at term 2"} {"level":"info","ts":"2026-01-28T01:09:51.281536Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca4c710a8d5349d7 became leader at term 2"} {"level":"info","ts":"2026-01-28T01:09:51.281557Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: ca4c710a8d5349d7 elected leader ca4c710a8d5349d7 at term 2"} {"level":"info","ts":"2026-01-28T01:09:51.283285Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-01-28T01:09:51.285689Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"ca4c710a8d5349d7","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.84:2379]}","request-path":"/0/members/ca4c710a8d5349d7/attributes","cluster-id":"8e28a51debf1c8ab","publish-timeout":"7s"} {"level":"info","ts":"2026-01-28T01:09:51.285723Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-01-28T01:09:51.286074Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-01-28T01:09:51.286292Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"8e28a51debf1c8ab","local-member-id":"ca4c710a8d5349d7","cluster-version":"3.5"} {"level":"info","ts":"2026-01-28T01:09:51.286366Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-01-28T01:09:51.2864Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-01-28T01:09:51.286429Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-01-28T01:09:51.286445Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-01-28T01:09:51.28763Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.84:2379"} {"level":"info","ts":"2026-01-28T01:09:51.292899Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-01-28T01:10:13.354337Z","caller":"traceutil/trace.go:171","msg":"trace[1123245968] transaction","detail":"{read_only:false; response_revision:464; number_of_response:1; }","duration":"119.981829ms","start":"2026-01-28T01:10:13.234327Z","end":"2026-01-28T01:10:13.354308Z","steps":["trace[1123245968] 'process raft request' (duration: 116.77139ms)"],"step_count":1} {"level":"info","ts":"2026-01-28T01:10:22.439205Z","caller":"traceutil/trace.go:171","msg":"trace[341445074] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"354.458377ms","start":"2026-01-28T01:10:22.084731Z","end":"2026-01-28T01:10:22.43919Z","steps":["trace[341445074] 'process raft request' (duration: 354.350914ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:10:22.439549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:10:22.084722Z","time spent":"354.531259ms","remote":"127.0.0.1:60408","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-01-28T01:10:24.577562Z","caller":"traceutil/trace.go:171","msg":"trace[227875484] linearizableReadLoop","detail":"{readStateIndex:542; appliedIndex:541; }","duration":"106.560329ms","start":"2026-01-28T01:10:24.470962Z","end":"2026-01-28T01:10:24.577522Z","steps":["trace[227875484] 'read index received' (duration: 106.314632ms)","trace[227875484] 'applied index is now lower than readState.Index' (duration: 244.807µs)"],"step_count":2} {"level":"warn","ts":"2026-01-28T01:10:24.577759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.750525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.84\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-01-28T01:10:24.577848Z","caller":"traceutil/trace.go:171","msg":"trace[1514174922] range","detail":"{range_begin:/registry/masterleases/162.253.55.84; range_end:; response_count:1; response_revision:526; }","duration":"106.896078ms","start":"2026-01-28T01:10:24.470938Z","end":"2026-01-28T01:10:24.577834Z","steps":["trace[1514174922] 'agreement among raft nodes before linearized reading' (duration: 106.707423ms)"],"step_count":1} {"level":"info","ts":"2026-01-28T01:10:24.57886Z","caller":"traceutil/trace.go:171","msg":"trace[1339027565] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"132.973077ms","start":"2026-01-28T01:10:24.445872Z","end":"2026-01-28T01:10:24.578845Z","steps":["trace[1339027565] 'process raft request' (duration: 131.481306ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:10:27.858005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.91372ms","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-01-28T01:10:27.858108Z","caller":"traceutil/trace.go:171","msg":"trace[650740524] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:536; }","duration":"152.033804ms","start":"2026-01-28T01:10:27.70606Z","end":"2026-01-28T01:10:27.858094Z","steps":["trace[650740524] 'range keys from in-memory index tree' (duration: 151.90083ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:10:27.858155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"532.322602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-rxcrf\" ","response":"range_response_count:1 size:16652"} {"level":"warn","ts":"2026-01-28T01:10:27.858476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.299825ms","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-01-28T01:10:27.858496Z","caller":"traceutil/trace.go:171","msg":"trace[1944081699] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:536; }","duration":"452.354607ms","start":"2026-01-28T01:10:27.406135Z","end":"2026-01-28T01:10:27.85849Z","steps":["trace[1944081699] 'range keys from in-memory index tree' (duration: 452.174361ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:10:27.858517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:10:27.406118Z","time spent":"452.393537ms","remote":"127.0.0.1:60408","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-01-28T01:10:27.858652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.281727ms","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-01-28T01:10:27.858669Z","caller":"traceutil/trace.go:171","msg":"trace[1906955987] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:536; }","duration":"426.320988ms","start":"2026-01-28T01:10:27.432343Z","end":"2026-01-28T01:10:27.858664Z","steps":["trace[1906955987] 'range keys from in-memory index tree' (duration: 426.184734ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:10:27.858695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:10:27.432332Z","time spent":"426.358909ms","remote":"127.0.0.1:60408","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-01-28T01:10:27.858195Z","caller":"traceutil/trace.go:171","msg":"trace[138643675] range","detail":"{range_begin:/registry/pods/kube-system/cilium-rxcrf; range_end:; response_count:1; response_revision:536; }","duration":"532.392033ms","start":"2026-01-28T01:10:27.325793Z","end":"2026-01-28T01:10:27.858185Z","steps":["trace[138643675] 'range keys from in-memory index tree' (duration: 532.115457ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:10:27.858753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:10:27.32578Z","time spent":"532.96231ms","remote":"127.0.0.1:60298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16676,"request content":"key:\"/registry/pods/kube-system/cilium-rxcrf\" "} {"level":"warn","ts":"2026-01-28T01:10:28.164151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.774605ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-28T01:10:28.164324Z","caller":"traceutil/trace.go:171","msg":"trace[2007957873] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"279.822688ms","start":"2026-01-28T01:10:27.884485Z","end":"2026-01-28T01:10:28.164308Z","steps":["trace[2007957873] 'process raft request' (duration: 279.752476ms)"],"step_count":1} {"level":"info","ts":"2026-01-28T01:10:28.164345Z","caller":"traceutil/trace.go:171","msg":"trace[242580890] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"298.067921ms","start":"2026-01-28T01:10:27.866258Z","end":"2026-01-28T01:10:28.164326Z","steps":["trace[242580890] 'process raft request' (duration: 95.905145ms)","trace[242580890] 'compare' (duration: 201.687863ms)"],"step_count":2} {"level":"warn","ts":"2026-01-28T01:10:28.927316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.328901ms","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-01-28T01:10:28.927406Z","caller":"traceutil/trace.go:171","msg":"trace[1575975143] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:539; }","duration":"338.445545ms","start":"2026-01-28T01:10:28.588942Z","end":"2026-01-28T01:10:28.927388Z","steps":["trace[1575975143] 'range keys from in-memory index tree' (duration: 338.078495ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:10:28.92745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:10:28.588886Z","time spent":"338.553708ms","remote":"127.0.0.1:60408","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-01-28T01:10:39.000163Z","caller":"traceutil/trace.go:171","msg":"trace[943550730] transaction","detail":"{read_only:false; response_revision:659; number_of_response:1; }","duration":"142.693785ms","start":"2026-01-28T01:10:38.857455Z","end":"2026-01-28T01:10:39.000149Z","steps":["trace[943550730] 'process raft request' (duration: 142.294554ms)"],"step_count":1} {"level":"info","ts":"2026-01-28T01:10:47.385268Z","caller":"traceutil/trace.go:171","msg":"trace[908429869] transaction","detail":"{read_only:false; response_revision:727; number_of_response:1; }","duration":"125.762488ms","start":"2026-01-28T01:10:47.259486Z","end":"2026-01-28T01:10:47.385249Z","steps":["trace[908429869] 'process raft request' (duration: 125.628155ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:10:48.717891Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5320893017808034549,"retry-timeout":"500ms"} {"level":"info","ts":"2026-01-28T01:10:48.729482Z","caller":"traceutil/trace.go:171","msg":"trace[1490147890] linearizableReadLoop","detail":"{readStateIndex:753; appliedIndex:752; }","duration":"512.383022ms","start":"2026-01-28T01:10:48.217078Z","end":"2026-01-28T01:10:48.729461Z","steps":["trace[1490147890] 'read index received' (duration: 512.212767ms)","trace[1490147890] 'applied index is now lower than readState.Index' (duration: 169.405µs)"],"step_count":2} {"level":"info","ts":"2026-01-28T01:10:48.729781Z","caller":"traceutil/trace.go:171","msg":"trace[1396370035] transaction","detail":"{read_only:false; response_revision:730; number_of_response:1; }","duration":"696.923032ms","start":"2026-01-28T01:10:48.032848Z","end":"2026-01-28T01:10:48.729771Z","steps":["trace[1396370035] 'process raft request' (duration: 696.49109ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:10:48.729884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:10:48.032837Z","time spent":"696.971973ms","remote":"127.0.0.1:60408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-28T01:10:48.730068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"512.988289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-28T01:10:48.730099Z","caller":"traceutil/trace.go:171","msg":"trace[2010100544] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:730; }","duration":"513.01946ms","start":"2026-01-28T01:10:48.21707Z","end":"2026-01-28T01:10:48.730089Z","steps":["trace[2010100544] 'agreement among raft nodes before linearized reading' (duration: 512.969419ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:10:48.730124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:10:48.217012Z","time spent":"513.106622ms","remote":"127.0.0.1:60114","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-01-28T01:10:48.730358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"470.314752ms","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-01-28T01:10:48.730387Z","caller":"traceutil/trace.go:171","msg":"trace[1982950253] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:730; }","duration":"470.367773ms","start":"2026-01-28T01:10:48.260011Z","end":"2026-01-28T01:10:48.730379Z","steps":["trace[1982950253] 'agreement among raft nodes before linearized reading' (duration: 470.225299ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:10:48.730411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:10:48.26Z","time spent":"470.406255ms","remote":"127.0.0.1:60408","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-01-28T01:10:49.267957Z","caller":"traceutil/trace.go:171","msg":"trace[54257765] transaction","detail":"{read_only:false; response_revision:732; number_of_response:1; }","duration":"200.464178ms","start":"2026-01-28T01:10:49.067474Z","end":"2026-01-28T01:10:49.267938Z","steps":["trace[54257765] 'process raft request' (duration: 200.343824ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:10:50.395962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"752.860555ms","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-01-28T01:10:50.396059Z","caller":"traceutil/trace.go:171","msg":"trace[1297666389] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:732; }","duration":"752.974818ms","start":"2026-01-28T01:10:49.643064Z","end":"2026-01-28T01:10:50.396039Z","steps":["trace[1297666389] 'range keys from in-memory index tree' (duration: 752.69724ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:10:50.396103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:10:49.643014Z","time spent":"753.078651ms","remote":"127.0.0.1:60408","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-01-28T01:10:50.3965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.007995192s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-01-28T01:10:50.396568Z","caller":"traceutil/trace.go:171","msg":"trace[1345994967] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:732; }","duration":"1.008099365s","start":"2026-01-28T01:10:49.388452Z","end":"2026-01-28T01:10:50.396551Z","steps":["trace[1345994967] 'range keys from in-memory index tree' (duration: 1.007852458s)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:10:50.396607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:10:49.388437Z","time spent":"1.008160946s","remote":"127.0.0.1:60408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-01-28T01:10:50.715031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.876693ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-01-28T01:10:50.715243Z","caller":"traceutil/trace.go:171","msg":"trace[127603372] transaction","detail":"{read_only:false; response_revision:734; number_of_response:1; }","duration":"313.024934ms","start":"2026-01-28T01:10:50.402203Z","end":"2026-01-28T01:10:50.715228Z","steps":["trace[127603372] 'process raft request' (duration: 312.990453ms)"],"step_count":1} {"level":"info","ts":"2026-01-28T01:10:50.715279Z","caller":"traceutil/trace.go:171","msg":"trace[439647401] linearizableReadLoop","detail":"{readStateIndex:756; appliedIndex:755; }","duration":"500.291799ms","start":"2026-01-28T01:10:50.214962Z","end":"2026-01-28T01:10:50.715254Z","steps":["trace[439647401] 'read index received' (duration: 267.293653ms)","trace[439647401] 'applied index is now lower than readState.Index' (duration: 232.996406ms)"],"step_count":2} {"level":"warn","ts":"2026-01-28T01:10:50.715314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:10:50.402196Z","time spent":"313.073035ms","remote":"127.0.0.1:60408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-28T01:10:50.715396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"500.410532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-28T01:10:50.715421Z","caller":"traceutil/trace.go:171","msg":"trace[1729331018] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:734; }","duration":"500.479814ms","start":"2026-01-28T01:10:50.214933Z","end":"2026-01-28T01:10:50.715413Z","steps":["trace[1729331018] 'agreement among raft nodes before linearized reading' (duration: 500.388841ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:10:50.715442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:10:50.214918Z","time spent":"500.519505ms","remote":"127.0.0.1:60114","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-01-28T01:10:50.715524Z","caller":"traceutil/trace.go:171","msg":"trace[757663640] transaction","detail":"{read_only:false; response_revision:733; number_of_response:1; }","duration":"314.225287ms","start":"2026-01-28T01:10:50.401291Z","end":"2026-01-28T01:10:50.715517Z","steps":["trace[757663640] 'process raft request' (duration: 313.819475ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:10:50.715562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:10:50.401272Z","time spent":"314.269918ms","remote":"127.0.0.1:60408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-28T01:10:57.264758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.412638ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-28T01:10:57.264835Z","caller":"traceutil/trace.go:171","msg":"trace[1011951211] linearizableReadLoop","detail":"{readStateIndex:782; appliedIndex:781; }","duration":"495.99067ms","start":"2026-01-28T01:10:56.768826Z","end":"2026-01-28T01:10:57.264816Z","steps":["trace[1011951211] 'read index received' (duration: 328.4774ms)","trace[1011951211] 'applied index is now lower than readState.Index' (duration: 167.51268ms)"],"step_count":2} {"level":"warn","ts":"2026-01-28T01:10:57.264974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.475021ms","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-01-28T01:10:57.264988Z","caller":"traceutil/trace.go:171","msg":"trace[1544208380] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:756; }","duration":"435.529392ms","start":"2026-01-28T01:10:56.829454Z","end":"2026-01-28T01:10:57.264984Z","steps":["trace[1544208380] 'agreement among raft nodes before linearized reading' (duration: 435.429169ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:10:57.265005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:10:56.829445Z","time spent":"435.556363ms","remote":"127.0.0.1:60408","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-01-28T01:10:57.265038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.196446ms","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-01-28T01:10:57.265099Z","caller":"traceutil/trace.go:171","msg":"trace[463450198] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:756; }","duration":"496.296249ms","start":"2026-01-28T01:10:56.76879Z","end":"2026-01-28T01:10:57.265087Z","steps":["trace[463450198] 'agreement among raft nodes before linearized reading' (duration: 496.090273ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:10:57.265129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:10:56.768776Z","time spent":"496.34641ms","remote":"127.0.0.1:60408","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-01-28T01:10:57.265192Z","caller":"traceutil/trace.go:171","msg":"trace[1543853411] transaction","detail":"{read_only:false; response_revision:756; number_of_response:1; }","duration":"500.154184ms","start":"2026-01-28T01:10:56.76503Z","end":"2026-01-28T01:10:57.265185Z","steps":["trace[1543853411] 'process raft request' (duration: 332.264563ms)","trace[1543853411] 'compare' (duration: 167.12875ms)"],"step_count":2} {"level":"warn","ts":"2026-01-28T01:10:57.265223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:10:56.76502Z","time spent":"500.188015ms","remote":"127.0.0.1:60408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-28T01:10:57.553943Z","caller":"traceutil/trace.go:171","msg":"trace[1378037709] transaction","detail":"{read_only:false; response_revision:757; number_of_response:1; }","duration":"284.212679ms","start":"2026-01-28T01:10:57.269714Z","end":"2026-01-28T01:10:57.553927Z","steps":["trace[1378037709] 'process raft request' (duration: 284.130027ms)"],"step_count":1} {"level":"info","ts":"2026-01-28T01:10:57.558911Z","caller":"traceutil/trace.go:171","msg":"trace[1786580628] transaction","detail":"{read_only:false; response_revision:758; number_of_response:1; }","duration":"288.950239ms","start":"2026-01-28T01:10:57.269948Z","end":"2026-01-28T01:10:57.558898Z","steps":["trace[1786580628] 'process raft request' (duration: 288.789905ms)"],"step_count":1} {"level":"info","ts":"2026-01-28T01:10:57.558901Z","caller":"traceutil/trace.go:171","msg":"trace[932065986] linearizableReadLoop","detail":"{readStateIndex:785; appliedIndex:783; }","duration":"240.661108ms","start":"2026-01-28T01:10:57.318221Z","end":"2026-01-28T01:10:57.558882Z","steps":["trace[932065986] 'read index received' (duration: 235.966368ms)","trace[932065986] 'applied index is now lower than readState.Index' (duration: 4.69406ms)"],"step_count":2} {"level":"info","ts":"2026-01-28T01:10:57.559082Z","caller":"traceutil/trace.go:171","msg":"trace[1047764500] transaction","detail":"{read_only:false; response_revision:759; number_of_response:1; }","duration":"284.701622ms","start":"2026-01-28T01:10:57.274372Z","end":"2026-01-28T01:10:57.559074Z","steps":["trace[1047764500] 'process raft request' (duration: 284.481166ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:10:57.559197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.102477ms","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-01-28T01:10:57.559223Z","caller":"traceutil/trace.go:171","msg":"trace[333912695] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:759; }","duration":"234.148688ms","start":"2026-01-28T01:10:57.325067Z","end":"2026-01-28T01:10:57.559215Z","steps":["trace[333912695] 'agreement among raft nodes before linearized reading' (duration: 234.052216ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:10:57.559313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.0884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-28T01:10:57.55933Z","caller":"traceutil/trace.go:171","msg":"trace[1047790281] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:759; }","duration":"241.12845ms","start":"2026-01-28T01:10:57.318196Z","end":"2026-01-28T01:10:57.559325Z","steps":["trace[1047790281] 'agreement among raft nodes before linearized reading' (duration: 241.098779ms)"],"step_count":1} {"level":"info","ts":"2026-01-28T01:11:07.78735Z","caller":"traceutil/trace.go:171","msg":"trace[395241739] transaction","detail":"{read_only:false; response_revision:787; number_of_response:1; }","duration":"182.421981ms","start":"2026-01-28T01:11:07.604904Z","end":"2026-01-28T01:11:07.787326Z","steps":["trace[395241739] 'process raft request' (duration: 182.106852ms)"],"step_count":1} {"level":"info","ts":"2026-01-28T01:11:07.787573Z","caller":"traceutil/trace.go:171","msg":"trace[1999829385] transaction","detail":"{read_only:false; response_revision:788; number_of_response:1; }","duration":"181.576918ms","start":"2026-01-28T01:11:07.605989Z","end":"2026-01-28T01:11:07.787566Z","steps":["trace[1999829385] 'process raft request' (duration: 181.118306ms)"],"step_count":1} {"level":"info","ts":"2026-01-28T01:11:08.906532Z","caller":"traceutil/trace.go:171","msg":"trace[1854521520] transaction","detail":"{read_only:false; response_revision:790; number_of_response:1; }","duration":"111.528254ms","start":"2026-01-28T01:11:08.794659Z","end":"2026-01-28T01:11:08.906188Z","steps":["trace[1854521520] 'process raft request' (duration: 111.40707ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:11:11.661587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.509705533s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-01-28T01:11:11.661703Z","caller":"traceutil/trace.go:171","msg":"trace[912362007] linearizableReadLoop","detail":"{readStateIndex:821; appliedIndex:820; }","duration":"1.87022206s","start":"2026-01-28T01:11:09.791443Z","end":"2026-01-28T01:11:11.661665Z","steps":["trace[912362007] 'read index received' (duration: 360.373963ms)","trace[912362007] 'applied index is now lower than readState.Index' (duration: 1.509846607s)"],"step_count":2} {"level":"warn","ts":"2026-01-28T01:11:11.663165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.871708889s","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-01-28T01:11:11.663213Z","caller":"traceutil/trace.go:171","msg":"trace[541089161] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:792; }","duration":"1.871780072s","start":"2026-01-28T01:11:09.791421Z","end":"2026-01-28T01:11:11.663201Z","steps":["trace[541089161] 'agreement among raft nodes before linearized reading' (duration: 1.871625388s)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:11:11.663247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:11:09.791412Z","time spent":"1.871827013s","remote":"127.0.0.1:60408","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-01-28T01:11:11.663465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.871986648s","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-01-28T01:11:11.663553Z","caller":"traceutil/trace.go:171","msg":"trace[516704598] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:792; }","duration":"1.87207992s","start":"2026-01-28T01:11:09.791462Z","end":"2026-01-28T01:11:11.663542Z","steps":["trace[516704598] 'agreement among raft nodes before linearized reading' (duration: 1.871934916s)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:11:11.664873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:11:09.791459Z","time spent":"1.873404265s","remote":"127.0.0.1:60408","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-01-28T01:11:11.665129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.477206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-01-28T01:11:11.665159Z","caller":"traceutil/trace.go:171","msg":"trace[255193894] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:792; }","duration":"344.546098ms","start":"2026-01-28T01:11:11.320607Z","end":"2026-01-28T01:11:11.665153Z","steps":["trace[255193894] 'agreement among raft nodes before linearized reading' (duration: 344.470656ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:11:11.665179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:11:11.320588Z","time spent":"344.58578ms","remote":"127.0.0.1:60408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-01-28T01:11:11.665279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"754.070025ms","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-01-28T01:11:11.6653Z","caller":"traceutil/trace.go:171","msg":"trace[753664787] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:792; }","duration":"754.121337ms","start":"2026-01-28T01:11:10.911174Z","end":"2026-01-28T01:11:11.665295Z","steps":["trace[753664787] 'agreement among raft nodes before linearized reading' (duration: 754.070806ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:11:11.665318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:11:10.911157Z","time spent":"754.156778ms","remote":"127.0.0.1:60408","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-01-28T01:11:11.665398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.447090738s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-28T01:11:11.665419Z","caller":"traceutil/trace.go:171","msg":"trace[1184602894] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:792; }","duration":"1.447138209s","start":"2026-01-28T01:11:10.218276Z","end":"2026-01-28T01:11:11.665414Z","steps":["trace[1184602894] 'agreement among raft nodes before linearized reading' (duration: 1.447108238s)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:11:11.665438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:11:10.218263Z","time spent":"1.44717094s","remote":"127.0.0.1:60114","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-01-28T01:11:12.012344Z","caller":"traceutil/trace.go:171","msg":"trace[699724435] transaction","detail":"{read_only:false; response_revision:793; number_of_response:1; }","duration":"341.476837ms","start":"2026-01-28T01:11:11.67084Z","end":"2026-01-28T01:11:12.012316Z","steps":["trace[699724435] 'process raft request' (duration: 341.23341ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:11:12.012593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:11:11.670823Z","time spent":"341.680902ms","remote":"127.0.0.1:60408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-28T01:11:12.028636Z","caller":"traceutil/trace.go:171","msg":"trace[316502708] linearizableReadLoop","detail":"{readStateIndex:823; appliedIndex:822; }","duration":"356.651383ms","start":"2026-01-28T01:11:11.671967Z","end":"2026-01-28T01:11:12.028619Z","steps":["trace[316502708] 'read index received' (duration: 340.903121ms)","trace[316502708] 'applied index is now lower than readState.Index' (duration: 15.747512ms)"],"step_count":2} {"level":"info","ts":"2026-01-28T01:11:12.029025Z","caller":"traceutil/trace.go:171","msg":"trace[1532018788] transaction","detail":"{read_only:false; response_revision:794; number_of_response:1; }","duration":"357.202198ms","start":"2026-01-28T01:11:11.671812Z","end":"2026-01-28T01:11:12.029014Z","steps":["trace[1532018788] 'process raft request' (duration: 356.630912ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:11:12.033717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:11:11.671803Z","time spent":"361.78282ms","remote":"127.0.0.1:60408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-28T01:11:12.033888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.644885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-01-28T01:11:12.033921Z","caller":"traceutil/trace.go:171","msg":"trace[1029135561] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:796; }","duration":"170.678806ms","start":"2026-01-28T01:11:11.863234Z","end":"2026-01-28T01:11:12.033913Z","steps":["trace[1029135561] 'agreement among raft nodes before linearized reading' (duration: 170.525192ms)"],"step_count":1} {"level":"info","ts":"2026-01-28T01:11:12.029201Z","caller":"traceutil/trace.go:171","msg":"trace[447052853] transaction","detail":"{read_only:false; response_revision:795; number_of_response:1; }","duration":"353.21699ms","start":"2026-01-28T01:11:11.675978Z","end":"2026-01-28T01:11:12.029195Z","steps":["trace[447052853] 'process raft request' (duration: 352.575843ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:11:12.033742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.913592ms","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-01-28T01:11:12.034519Z","caller":"traceutil/trace.go:171","msg":"trace[769289709] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:796; }","duration":"242.693253ms","start":"2026-01-28T01:11:11.791817Z","end":"2026-01-28T01:11:12.034511Z","steps":["trace[769289709] 'agreement among raft nodes before linearized reading' (duration: 241.83298ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:11:12.029274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.29697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-28T01:11:12.03474Z","caller":"traceutil/trace.go:171","msg":"trace[586861737] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:796; }","duration":"362.789306ms","start":"2026-01-28T01:11:11.671943Z","end":"2026-01-28T01:11:12.034733Z","steps":["trace[586861737] 'agreement among raft nodes before linearized reading' (duration: 357.30482ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:11:12.034893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:11:11.671938Z","time spent":"362.91893ms","remote":"127.0.0.1:60122","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-01-28T01:11:12.035012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:11:11.675972Z","time spent":"358.476032ms","remote":"127.0.0.1:60408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-28T01:11:12.029231Z","caller":"traceutil/trace.go:171","msg":"trace[1361717366] transaction","detail":"{read_only:false; response_revision:796; number_of_response:1; }","duration":"352.358648ms","start":"2026-01-28T01:11:11.676868Z","end":"2026-01-28T01:11:12.029227Z","steps":["trace[1361717366] 'process raft request' (duration: 351.721801ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:11:12.035809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:11:11.676862Z","time spent":"358.42972ms","remote":"127.0.0.1:60408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-28T01:11:12.146312Z","caller":"traceutil/trace.go:171","msg":"trace[349064441] linearizableReadLoop","detail":"{readStateIndex:826; appliedIndex:825; }","duration":"100.17946ms","start":"2026-01-28T01:11:12.046118Z","end":"2026-01-28T01:11:12.146297Z","steps":["trace[349064441] 'read index received' (duration: 75.732446ms)","trace[349064441] 'applied index is now lower than readState.Index' (duration: 24.446274ms)"],"step_count":2} {"level":"warn","ts":"2026-01-28T01:11:12.146539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.271733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-28T01:11:12.146591Z","caller":"traceutil/trace.go:171","msg":"trace[565984282] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:797; }","duration":"100.487589ms","start":"2026-01-28T01:11:12.046093Z","end":"2026-01-28T01:11:12.146581Z","steps":["trace[565984282] 'agreement among raft nodes before linearized reading' (duration: 100.262593ms)"],"step_count":1} {"level":"info","ts":"2026-01-28T01:11:12.146939Z","caller":"traceutil/trace.go:171","msg":"trace[1098193659] transaction","detail":"{read_only:false; response_revision:797; number_of_response:1; }","duration":"101.880606ms","start":"2026-01-28T01:11:12.045035Z","end":"2026-01-28T01:11:12.146916Z","steps":["trace[1098193659] 'process raft request' (duration: 76.475596ms)","trace[1098193659] 'compare' (duration: 24.64197ms)"],"step_count":2} {"level":"warn","ts":"2026-01-28T01:11:25.716694Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5320893017808034933,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-28T01:11:25.7728Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.296821185s","expected-duration":"1s"} {"level":"warn","ts":"2026-01-28T01:11:25.7735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:11:24.47589Z","time spent":"1.297606136s","remote":"127.0.0.1:60148","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-01-28T01:11:25.809981Z","caller":"traceutil/trace.go:171","msg":"trace[751770204] linearizableReadLoop","detail":"{readStateIndex:917; appliedIndex:916; }","duration":"593.93702ms","start":"2026-01-28T01:11:25.21603Z","end":"2026-01-28T01:11:25.809967Z","steps":["trace[751770204] 'read index received' (duration: 557.818763ms)","trace[751770204] 'applied index is now lower than readState.Index' (duration: 36.117697ms)"],"step_count":2} {"level":"warn","ts":"2026-01-28T01:11:25.8101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"594.056703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-28T01:11:25.810121Z","caller":"traceutil/trace.go:171","msg":"trace[1551363284] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:883; }","duration":"594.111365ms","start":"2026-01-28T01:11:25.216004Z","end":"2026-01-28T01:11:25.810115Z","steps":["trace[1551363284] 'agreement among raft nodes before linearized reading' (duration: 594.002132ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:11:25.810136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:11:25.215992Z","time spent":"594.140135ms","remote":"127.0.0.1:60114","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-01-28T01:11:25.8103Z","caller":"traceutil/trace.go:171","msg":"trace[1092718518] transaction","detail":"{read_only:false; response_revision:883; number_of_response:1; }","duration":"195.055198ms","start":"2026-01-28T01:11:25.615239Z","end":"2026-01-28T01:11:25.810294Z","steps":["trace[1092718518] 'process raft request' (duration: 194.618967ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:11:26.594792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.93972ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-28T01:11:26.595102Z","caller":"traceutil/trace.go:171","msg":"trace[1646003277] linearizableReadLoop","detail":"{readStateIndex:918; appliedIndex:917; }","duration":"783.024889ms","start":"2026-01-28T01:11:25.812053Z","end":"2026-01-28T01:11:26.595078Z","steps":["trace[1646003277] 'read index received' (duration: 305.505543ms)","trace[1646003277] 'applied index is now lower than readState.Index' (duration: 477.517826ms)"],"step_count":2} {"level":"info","ts":"2026-01-28T01:11:26.59519Z","caller":"traceutil/trace.go:171","msg":"trace[2083443403] transaction","detail":"{read_only:false; response_revision:884; number_of_response:1; }","duration":"819.744221ms","start":"2026-01-28T01:11:25.77543Z","end":"2026-01-28T01:11:26.595174Z","steps":["trace[2083443403] 'process raft request' (duration: 342.098862ms)","trace[2083443403] 'compare' (duration: 476.790616ms)"],"step_count":2} {"level":"warn","ts":"2026-01-28T01:11:26.59524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:11:25.775408Z","time spent":"819.804943ms","remote":"127.0.0.1:60148","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-01-28T01:11:26.59679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"784.733175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-28T01:11:26.596824Z","caller":"traceutil/trace.go:171","msg":"trace[466789965] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:884; }","duration":"784.766466ms","start":"2026-01-28T01:11:25.812047Z","end":"2026-01-28T01:11:26.596813Z","steps":["trace[466789965] 'agreement among raft nodes before linearized reading' (duration: 783.472602ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:11:26.596853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:11:25.81201Z","time spent":"784.832268ms","remote":"127.0.0.1:60122","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-01-28T01:11:26.599924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"531.782777ms","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-01-28T01:11:26.599967Z","caller":"traceutil/trace.go:171","msg":"trace[1585999302] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:884; }","duration":"531.843839ms","start":"2026-01-28T01:11:26.068107Z","end":"2026-01-28T01:11:26.599951Z","steps":["trace[1585999302] 'agreement among raft nodes before linearized reading' (duration: 531.683655ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:11:26.599998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:11:26.068094Z","time spent":"531.89765ms","remote":"127.0.0.1:60408","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-01-28T01:11:26.600629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.284926ms","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-01-28T01:11:26.600665Z","caller":"traceutil/trace.go:171","msg":"trace[506157850] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:884; }","duration":"404.329518ms","start":"2026-01-28T01:11:26.196321Z","end":"2026-01-28T01:11:26.600651Z","steps":["trace[506157850] 'agreement among raft nodes before linearized reading' (duration: 404.195494ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:11:26.600684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:11:26.196313Z","time spent":"404.366259ms","remote":"127.0.0.1:60408","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-01-28T01:11:26.60111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.378197ms","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-01-28T01:11:26.601146Z","caller":"traceutil/trace.go:171","msg":"trace[1438459505] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:884; }","duration":"463.419509ms","start":"2026-01-28T01:11:26.137713Z","end":"2026-01-28T01:11:26.601133Z","steps":["trace[1438459505] 'agreement among raft nodes before linearized reading' (duration: 463.195553ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:11:26.601165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:11:26.137709Z","time spent":"463.45174ms","remote":"127.0.0.1:60408","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-01-28T01:11:26.601672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.346593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-01-28T01:11:26.601721Z","caller":"traceutil/trace.go:171","msg":"trace[1263641242] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:884; }","duration":"467.385215ms","start":"2026-01-28T01:11:26.134306Z","end":"2026-01-28T01:11:26.601691Z","steps":["trace[1263641242] 'agreement among raft nodes before linearized reading' (duration: 467.103797ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:11:26.601746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:11:26.134301Z","time spent":"467.438236ms","remote":"127.0.0.1:60408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-01-28T01:11:26.602069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"473.081007ms","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-01-28T01:11:26.602114Z","caller":"traceutil/trace.go:171","msg":"trace[73777912] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:884; }","duration":"473.157589ms","start":"2026-01-28T01:11:26.128947Z","end":"2026-01-28T01:11:26.602105Z","steps":["trace[73777912] 'agreement among raft nodes before linearized reading' (duration: 472.914632ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:11:26.602136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:11:26.128932Z","time spent":"473.19653ms","remote":"127.0.0.1:60408","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-01-28T01:11:28.657296Z","caller":"traceutil/trace.go:171","msg":"trace[894083376] transaction","detail":"{read_only:false; response_revision:890; number_of_response:1; }","duration":"135.372752ms","start":"2026-01-28T01:11:28.521908Z","end":"2026-01-28T01:11:28.657281Z","steps":["trace[894083376] 'process raft request' (duration: 135.106635ms)"],"step_count":1} {"level":"info","ts":"2026-01-28T01:11:28.776859Z","caller":"traceutil/trace.go:171","msg":"trace[1812567047] transaction","detail":"{read_only:false; response_revision:895; number_of_response:1; }","duration":"104.341142ms","start":"2026-01-28T01:11:28.672505Z","end":"2026-01-28T01:11:28.776846Z","steps":["trace[1812567047] 'process raft request' (duration: 104.317021ms)"],"step_count":1} {"level":"info","ts":"2026-01-28T01:11:28.777308Z","caller":"traceutil/trace.go:171","msg":"trace[1686542031] transaction","detail":"{read_only:false; response_revision:892; number_of_response:1; }","duration":"110.888717ms","start":"2026-01-28T01:11:28.666412Z","end":"2026-01-28T01:11:28.777301Z","steps":["trace[1686542031] 'process raft request' (duration: 110.326762ms)"],"step_count":1} {"level":"info","ts":"2026-01-28T01:11:28.777479Z","caller":"traceutil/trace.go:171","msg":"trace[86522319] transaction","detail":"{read_only:false; response_revision:893; number_of_response:1; }","duration":"108.892433ms","start":"2026-01-28T01:11:28.66858Z","end":"2026-01-28T01:11:28.777473Z","steps":["trace[86522319] 'process raft request' (duration: 108.205345ms)"],"step_count":1} {"level":"info","ts":"2026-01-28T01:11:28.777601Z","caller":"traceutil/trace.go:171","msg":"trace[187172935] transaction","detail":"{read_only:false; response_revision:894; number_of_response:1; }","duration":"108.02011ms","start":"2026-01-28T01:11:28.669575Z","end":"2026-01-28T01:11:28.777595Z","steps":["trace[187172935] 'process raft request' (duration: 107.230099ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:11:30.294983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.117912ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-01-28T01:11:30.295047Z","caller":"traceutil/trace.go:171","msg":"trace[532401112] linearizableReadLoop","detail":"{readStateIndex:930; appliedIndex:929; }","duration":"284.665476ms","start":"2026-01-28T01:11:30.010371Z","end":"2026-01-28T01:11:30.295037Z","steps":["trace[532401112] 'read index received' (duration: 27.82µs)","trace[532401112] 'applied index is now lower than readState.Index' (duration: 284.636816ms)"],"step_count":2} {"level":"warn","ts":"2026-01-28T01:11:30.295236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.853692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-01-28T01:11:30.295252Z","caller":"traceutil/trace.go:171","msg":"trace[1351770649] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:895; }","duration":"284.921983ms","start":"2026-01-28T01:11:30.010324Z","end":"2026-01-28T01:11:30.295246Z","steps":["trace[1351770649] 'agreement among raft nodes before linearized reading' (duration: 284.861072ms)"],"step_count":1} {"level":"info","ts":"2026-01-28T01:11:31.265567Z","caller":"traceutil/trace.go:171","msg":"trace[66495611] linearizableReadLoop","detail":"{readStateIndex:931; appliedIndex:930; }","duration":"484.176334ms","start":"2026-01-28T01:11:30.781376Z","end":"2026-01-28T01:11:31.265552Z","steps":["trace[66495611] 'read index received' (duration: 484.03585ms)","trace[66495611] 'applied index is now lower than readState.Index' (duration: 138.114µs)"],"step_count":2} {"level":"info","ts":"2026-01-28T01:11:31.265734Z","caller":"traceutil/trace.go:171","msg":"trace[805131989] transaction","detail":"{read_only:false; response_revision:896; number_of_response:1; }","duration":"504.887938ms","start":"2026-01-28T01:11:30.76084Z","end":"2026-01-28T01:11:31.265728Z","steps":["trace[805131989] 'process raft request' (duration: 504.620241ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:11:31.265793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:11:30.76083Z","time spent":"504.918158ms","remote":"127.0.0.1:60408","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-01-28T01:11:31.265926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"484.547494ms","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-01-28T01:11:31.265942Z","caller":"traceutil/trace.go:171","msg":"trace[1135422949] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:896; }","duration":"484.579135ms","start":"2026-01-28T01:11:30.781358Z","end":"2026-01-28T01:11:31.265937Z","steps":["trace[1135422949] 'agreement among raft nodes before linearized reading' (duration: 484.517313ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:11:31.265957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:11:30.781351Z","time spent":"484.601665ms","remote":"127.0.0.1:60408","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-01-28T01:11:31.266047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"484.636106ms","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-01-28T01:11:31.266061Z","caller":"traceutil/trace.go:171","msg":"trace[764740457] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:896; }","duration":"484.648267ms","start":"2026-01-28T01:11:30.781408Z","end":"2026-01-28T01:11:31.266056Z","steps":["trace[764740457] 'agreement among raft nodes before linearized reading' (duration: 484.612476ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:11:31.266073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:11:30.781394Z","time spent":"484.676237ms","remote":"127.0.0.1:60408","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-01-28T01:11:31.266193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.286862ms","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-01-28T01:11:31.266208Z","caller":"traceutil/trace.go:171","msg":"trace[1681462381] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:896; }","duration":"476.301593ms","start":"2026-01-28T01:11:30.789902Z","end":"2026-01-28T01:11:31.266204Z","steps":["trace[1681462381] 'agreement among raft nodes before linearized reading' (duration: 476.243121ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:11:31.26622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:11:30.789888Z","time spent":"476.329114ms","remote":"127.0.0.1:60408","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-01-28T01:11:31.266304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"484.677597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-01-28T01:11:31.266316Z","caller":"traceutil/trace.go:171","msg":"trace[1760286088] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:896; }","duration":"484.697958ms","start":"2026-01-28T01:11:30.781615Z","end":"2026-01-28T01:11:31.266313Z","steps":["trace[1760286088] 'agreement among raft nodes before linearized reading' (duration: 484.663177ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:11:31.266327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:11:30.781611Z","time spent":"484.714008ms","remote":"127.0.0.1:60408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-01-28T01:11:31.541768Z","caller":"traceutil/trace.go:171","msg":"trace[319639528] transaction","detail":"{read_only:false; response_revision:897; number_of_response:1; }","duration":"269.947043ms","start":"2026-01-28T01:11:31.271806Z","end":"2026-01-28T01:11:31.541753Z","steps":["trace[319639528] 'process raft request' (duration: 269.84309ms)"],"step_count":1} {"level":"info","ts":"2026-01-28T01:11:31.561884Z","caller":"traceutil/trace.go:171","msg":"trace[1129203195] transaction","detail":"{read_only:false; response_revision:900; number_of_response:1; }","duration":"287.912583ms","start":"2026-01-28T01:11:31.273949Z","end":"2026-01-28T01:11:31.561862Z","steps":["trace[1129203195] 'process raft request' (duration: 287.879072ms)"],"step_count":1} {"level":"info","ts":"2026-01-28T01:11:31.56224Z","caller":"traceutil/trace.go:171","msg":"trace[1355473958] transaction","detail":"{read_only:false; response_revision:898; number_of_response:1; }","duration":"288.964792ms","start":"2026-01-28T01:11:31.273265Z","end":"2026-01-28T01:11:31.56223Z","steps":["trace[1355473958] 'process raft request' (duration: 288.436607ms)"],"step_count":1} {"level":"info","ts":"2026-01-28T01:11:31.56236Z","caller":"traceutil/trace.go:171","msg":"trace[1180887398] transaction","detail":"{read_only:false; response_revision:899; number_of_response:1; }","duration":"289.039183ms","start":"2026-01-28T01:11:31.273313Z","end":"2026-01-28T01:11:31.562352Z","steps":["trace[1180887398] 'process raft request' (duration: 288.477908ms)"],"step_count":1} {"level":"info","ts":"2026-01-28T01:11:37.955425Z","caller":"traceutil/trace.go:171","msg":"trace[557202915] linearizableReadLoop","detail":"{readStateIndex:950; appliedIndex:949; }","duration":"375.152945ms","start":"2026-01-28T01:11:37.580257Z","end":"2026-01-28T01:11:37.95541Z","steps":["trace[557202915] 'read index received' (duration: 375.048893ms)","trace[557202915] 'applied index is now lower than readState.Index' (duration: 103.172µs)"],"step_count":2} {"level":"info","ts":"2026-01-28T01:11:37.9555Z","caller":"traceutil/trace.go:171","msg":"trace[972931016] transaction","detail":"{read_only:false; response_revision:914; number_of_response:1; }","duration":"398.82496ms","start":"2026-01-28T01:11:37.55667Z","end":"2026-01-28T01:11:37.955495Z","steps":["trace[972931016] 'process raft request' (duration: 398.643035ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:11:37.95556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:11:37.55666Z","time spent":"398.8541ms","remote":"127.0.0.1:60408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-28T01:11:37.955672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.421883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-01-28T01:11:37.955688Z","caller":"traceutil/trace.go:171","msg":"trace[342493956] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:914; }","duration":"375.437034ms","start":"2026-01-28T01:11:37.580245Z","end":"2026-01-28T01:11:37.955682Z","steps":["trace[342493956] 'agreement among raft nodes before linearized reading' (duration: 375.378142ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:11:37.955702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:11:37.580193Z","time spent":"375.505205ms","remote":"127.0.0.1:60408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-01-28T01:11:37.955766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.437719ms","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-01-28T01:11:37.955777Z","caller":"traceutil/trace.go:171","msg":"trace[1696659004] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:914; }","duration":"361.47046ms","start":"2026-01-28T01:11:37.594303Z","end":"2026-01-28T01:11:37.955773Z","steps":["trace[1696659004] 'agreement among raft nodes before linearized reading' (duration: 361.435169ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:11:37.955788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:11:37.594289Z","time spent":"361.49676ms","remote":"127.0.0.1:60408","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-01-28T01:11:37.955902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.131291ms","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-01-28T01:11:37.955949Z","caller":"traceutil/trace.go:171","msg":"trace[906465253] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:914; }","duration":"361.183693ms","start":"2026-01-28T01:11:37.594753Z","end":"2026-01-28T01:11:37.955937Z","steps":["trace[906465253] 'agreement among raft nodes before linearized reading' (duration: 361.056289ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:11:37.955971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:11:37.594738Z","time spent":"361.228833ms","remote":"127.0.0.1:60408","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-01-28T01:11:54.247251Z","caller":"traceutil/trace.go:171","msg":"trace[1532438696] linearizableReadLoop","detail":"{readStateIndex:1039; appliedIndex:1038; }","duration":"114.696119ms","start":"2026-01-28T01:11:54.132538Z","end":"2026-01-28T01:11:54.247234Z","steps":["trace[1532438696] 'read index received' (duration: 114.527614ms)","trace[1532438696] 'applied index is now lower than readState.Index' (duration: 167.595µs)"],"step_count":2} {"level":"info","ts":"2026-01-28T01:11:54.247489Z","caller":"traceutil/trace.go:171","msg":"trace[1814401863] transaction","detail":"{read_only:false; response_revision:1000; number_of_response:1; }","duration":"134.199651ms","start":"2026-01-28T01:11:54.11325Z","end":"2026-01-28T01:11:54.24745Z","steps":["trace[1814401863] 'process raft request' (duration: 133.831511ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:11:54.247613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.476638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-01-28T01:11:54.247691Z","caller":"traceutil/trace.go:171","msg":"trace[1411253280] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1000; }","duration":"106.587342ms","start":"2026-01-28T01:11:54.141085Z","end":"2026-01-28T01:11:54.247672Z","steps":["trace[1411253280] 'agreement among raft nodes before linearized reading' (duration: 106.397306ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:11:54.247944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.409688ms","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-01-28T01:11:54.247981Z","caller":"traceutil/trace.go:171","msg":"trace[518756456] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1000; }","duration":"115.467619ms","start":"2026-01-28T01:11:54.132504Z","end":"2026-01-28T01:11:54.247971Z","steps":["trace[518756456] 'agreement among raft nodes before linearized reading' (duration: 115.363736ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:11:54.552772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.140502ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-28T01:11:54.553027Z","caller":"traceutil/trace.go:171","msg":"trace[1497736530] linearizableReadLoop","detail":"{readStateIndex:1042; appliedIndex:1039; }","duration":"268.73665ms","start":"2026-01-28T01:11:54.284272Z","end":"2026-01-28T01:11:54.553009Z","steps":["trace[1497736530] 'read index received' (duration: 58.278949ms)","trace[1497736530] 'applied index is now lower than readState.Index' (duration: 210.456421ms)"],"step_count":2} {"level":"info","ts":"2026-01-28T01:11:54.553311Z","caller":"traceutil/trace.go:171","msg":"trace[178083716] transaction","detail":"{read_only:false; response_revision:1001; number_of_response:1; }","duration":"299.059072ms","start":"2026-01-28T01:11:54.254239Z","end":"2026-01-28T01:11:54.553298Z","steps":["trace[178083716] 'process raft request' (duration: 88.303913ms)","trace[178083716] 'compare' (duration: 208.684313ms)"],"step_count":2} {"level":"info","ts":"2026-01-28T01:11:54.553478Z","caller":"traceutil/trace.go:171","msg":"trace[857458340] transaction","detail":"{read_only:false; response_revision:1002; number_of_response:1; }","duration":"297.639603ms","start":"2026-01-28T01:11:54.255826Z","end":"2026-01-28T01:11:54.553466Z","steps":["trace[857458340] 'process raft request' (duration: 297.039047ms)"],"step_count":1} {"level":"info","ts":"2026-01-28T01:11:54.5536Z","caller":"traceutil/trace.go:171","msg":"trace[1837913485] transaction","detail":"{read_only:false; response_revision:1003; number_of_response:1; }","duration":"297.718505ms","start":"2026-01-28T01:11:54.25587Z","end":"2026-01-28T01:11:54.553589Z","steps":["trace[1837913485] 'process raft request' (duration: 297.064138ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:11:54.556804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.50522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-01-28T01:11:54.556871Z","caller":"traceutil/trace.go:171","msg":"trace[1138015395] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1003; }","duration":"272.607663ms","start":"2026-01-28T01:11:54.284243Z","end":"2026-01-28T01:11:54.556851Z","steps":["trace[1138015395] 'agreement among raft nodes before linearized reading' (duration: 269.446139ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:11:54.557169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.070029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-01-28T01:11:54.5572Z","caller":"traceutil/trace.go:171","msg":"trace[2038246511] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1003; }","duration":"232.128311ms","start":"2026-01-28T01:11:54.325064Z","end":"2026-01-28T01:11:54.557193Z","steps":["trace[2038246511] 'agreement among raft nodes before linearized reading' (duration: 232.036128ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:11:55.694541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"848.743936ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-28T01:11:55.694626Z","caller":"traceutil/trace.go:171","msg":"trace[109363605] linearizableReadLoop","detail":"{readStateIndex:1045; appliedIndex:1044; }","duration":"236.900097ms","start":"2026-01-28T01:11:55.457714Z","end":"2026-01-28T01:11:55.694614Z","steps":["trace[109363605] 'read index received' (duration: 33.821µs)","trace[109363605] 'applied index is now lower than readState.Index' (duration: 236.865226ms)"],"step_count":2} {"level":"info","ts":"2026-01-28T01:11:55.694682Z","caller":"traceutil/trace.go:171","msg":"trace[313505797] transaction","detail":"{read_only:false; response_revision:1005; number_of_response:1; }","duration":"1.085738407s","start":"2026-01-28T01:11:54.608936Z","end":"2026-01-28T01:11:55.694674Z","steps":["trace[313505797] 'process raft request' (duration: 236.795425ms)","trace[313505797] 'compare' (duration: 848.608293ms)"],"step_count":2} {"level":"warn","ts":"2026-01-28T01:11:55.694717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:11:54.608922Z","time spent":"1.085776558s","remote":"127.0.0.1:60148","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-01-28T01:11:55.694894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.178935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-28T01:11:55.694914Z","caller":"traceutil/trace.go:171","msg":"trace[1883846876] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1005; }","duration":"237.222336ms","start":"2026-01-28T01:11:55.457684Z","end":"2026-01-28T01:11:55.694907Z","steps":["trace[1883846876] 'agreement among raft nodes before linearized reading' (duration: 237.153484ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:11:55.695052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.147699ms","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-01-28T01:11:55.695068Z","caller":"traceutil/trace.go:171","msg":"trace[845134232] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1005; }","duration":"217.181549ms","start":"2026-01-28T01:11:55.477882Z","end":"2026-01-28T01:11:55.695063Z","steps":["trace[845134232] 'agreement among raft nodes before linearized reading' (duration: 217.114728ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:11:56.117558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.961563ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-28T01:11:56.117975Z","caller":"traceutil/trace.go:171","msg":"trace[563384947] transaction","detail":"{read_only:false; response_revision:1006; number_of_response:1; }","duration":"414.5349ms","start":"2026-01-28T01:11:55.703421Z","end":"2026-01-28T01:11:56.117956Z","steps":["trace[563384947] 'process raft request' (duration: 249.080534ms)","trace[563384947] 'compare' (duration: 164.625074ms)"],"step_count":2} {"level":"warn","ts":"2026-01-28T01:11:56.118037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:11:55.70341Z","time spent":"414.596233ms","remote":"127.0.0.1:60408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-28T01:11:56.670475Z","caller":"traceutil/trace.go:171","msg":"trace[1981273963] linearizableReadLoop","detail":"{readStateIndex:1047; appliedIndex:1046; }","duration":"485.139119ms","start":"2026-01-28T01:11:56.185318Z","end":"2026-01-28T01:11:56.670457Z","steps":["trace[1981273963] 'read index received' (duration: 484.982325ms)","trace[1981273963] 'applied index is now lower than readState.Index' (duration: 155.784µs)"],"step_count":2} {"level":"info","ts":"2026-01-28T01:11:56.67092Z","caller":"traceutil/trace.go:171","msg":"trace[718157465] transaction","detail":"{read_only:false; response_revision:1007; number_of_response:1; }","duration":"547.717254ms","start":"2026-01-28T01:11:56.123155Z","end":"2026-01-28T01:11:56.670872Z","steps":["trace[718157465] 'process raft request' (duration: 547.19275ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:11:56.671064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"485.641813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-01-28T01:11:56.671185Z","caller":"traceutil/trace.go:171","msg":"trace[752126188] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:1007; }","duration":"485.88712ms","start":"2026-01-28T01:11:56.185284Z","end":"2026-01-28T01:11:56.671171Z","steps":["trace[752126188] 'agreement among raft nodes before linearized reading' (duration: 485.644913ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:11:56.671327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:11:56.185266Z","time spent":"486.041664ms","remote":"127.0.0.1:60494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":31,"request content":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true "} {"level":"warn","ts":"2026-01-28T01:11:56.671081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:11:56.123134Z","time spent":"547.861608ms","remote":"127.0.0.1:60408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":519,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-28T01:11:56.997759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.70348ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-28T01:11:56.997914Z","caller":"traceutil/trace.go:171","msg":"trace[241729369] linearizableReadLoop","detail":"{readStateIndex:1049; appliedIndex:1047; }","duration":"327.149833ms","start":"2026-01-28T01:11:56.670749Z","end":"2026-01-28T01:11:56.997899Z","steps":["trace[241729369] 'read index received' (duration: 157.190706ms)","trace[241729369] 'applied index is now lower than readState.Index' (duration: 169.958127ms)"],"step_count":2} {"level":"info","ts":"2026-01-28T01:11:56.99812Z","caller":"traceutil/trace.go:171","msg":"trace[2109599663] transaction","detail":"{read_only:false; response_revision:1008; number_of_response:1; }","duration":"747.339575ms","start":"2026-01-28T01:11:56.250767Z","end":"2026-01-28T01:11:56.998107Z","steps":["trace[2109599663] 'process raft request' (duration: 577.218693ms)","trace[2109599663] 'compare' (duration: 169.532416ms)"],"step_count":2} {"level":"warn","ts":"2026-01-28T01:11:56.998178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:11:56.250744Z","time spent":"747.402497ms","remote":"127.0.0.1:60408","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-28T01:11:56.998325Z","caller":"traceutil/trace.go:171","msg":"trace[709350580] transaction","detail":"{read_only:false; response_revision:1009; number_of_response:1; }","duration":"466.612714ms","start":"2026-01-28T01:11:56.531702Z","end":"2026-01-28T01:11:56.998315Z","steps":["trace[709350580] 'process raft request' (duration: 466.134581ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:11:56.998365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:11:56.531687Z","time spent":"466.657685ms","remote":"127.0.0.1:60204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":863,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-01-28T01:11:56.998546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.518767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:473"} {"level":"info","ts":"2026-01-28T01:11:56.998573Z","caller":"traceutil/trace.go:171","msg":"trace[1740413765] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1009; }","duration":"418.566379ms","start":"2026-01-28T01:11:56.579998Z","end":"2026-01-28T01:11:56.998564Z","steps":["trace[1740413765] 'agreement among raft nodes before linearized reading' (duration: 418.466726ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:11:56.998599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:11:56.579986Z","time spent":"418.60652ms","remote":"127.0.0.1:60408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":497,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-01-28T01:11:56.998786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.416553ms","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-01-28T01:11:56.998813Z","caller":"traceutil/trace.go:171","msg":"trace[1759192045] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1009; }","duration":"434.442883ms","start":"2026-01-28T01:11:56.564362Z","end":"2026-01-28T01:11:56.998805Z","steps":["trace[1759192045] 'agreement among raft nodes before linearized reading' (duration: 434.33756ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:11:56.998837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:11:56.564332Z","time spent":"434.499915ms","remote":"127.0.0.1:60408","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-01-28T01:11:56.99899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.034776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-01-28T01:11:56.999017Z","caller":"traceutil/trace.go:171","msg":"trace[218824859] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1009; }","duration":"436.088488ms","start":"2026-01-28T01:11:56.562921Z","end":"2026-01-28T01:11:56.99901Z","steps":["trace[218824859] 'agreement among raft nodes before linearized reading' (duration: 436.015086ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:11:56.999041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:11:56.562909Z","time spent":"436.126358ms","remote":"127.0.0.1:60408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-01-28T01:11:56.99916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"595.655757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-01-28T01:11:57.000161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"735.111997ms","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-01-28T01:11:57.000271Z","caller":"traceutil/trace.go:171","msg":"trace[264113902] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1009; }","duration":"735.284862ms","start":"2026-01-28T01:11:56.264931Z","end":"2026-01-28T01:11:57.000216Z","steps":["trace[264113902] 'agreement among raft nodes before linearized reading' (duration: 734.999254ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:11:57.000326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:11:56.264922Z","time spent":"735.395055ms","remote":"127.0.0.1:60408","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-01-28T01:11:57.000403Z","caller":"traceutil/trace.go:171","msg":"trace[219641010] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:1009; }","duration":"595.711878ms","start":"2026-01-28T01:11:56.403466Z","end":"2026-01-28T01:11:56.999178Z","steps":["trace[219641010] 'agreement among raft nodes before linearized reading' (duration: 595.663547ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:11:57.000473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:11:56.40345Z","time spent":"596.991882ms","remote":"127.0.0.1:60450","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":14,"response size":31,"request content":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true "} {"level":"warn","ts":"2026-01-28T01:11:57.000623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"765.529292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-28T01:11:57.000654Z","caller":"traceutil/trace.go:171","msg":"trace[758105120] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1009; }","duration":"765.585752ms","start":"2026-01-28T01:11:56.235059Z","end":"2026-01-28T01:11:57.000645Z","steps":["trace[758105120] 'agreement among raft nodes before linearized reading' (duration: 765.534711ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:11:57.00068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:11:56.235046Z","time spent":"765.629024ms","remote":"127.0.0.1:60122","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-01-28T01:11:57.00087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.338547ms","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-01-28T01:11:57.000897Z","caller":"traceutil/trace.go:171","msg":"trace[1212384681] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1009; }","duration":"324.390209ms","start":"2026-01-28T01:11:56.676498Z","end":"2026-01-28T01:11:57.000888Z","steps":["trace[1212384681] 'agreement among raft nodes before linearized reading' (duration: 324.307907ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:11:57.000922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-28T01:11:56.676441Z","time spent":"324.475431ms","remote":"127.0.0.1:60408","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-01-28T01:11:57.427203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.901126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-28T01:11:57.427258Z","caller":"traceutil/trace.go:171","msg":"trace[1091663811] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1014; }","duration":"212.990999ms","start":"2026-01-28T01:11:57.214254Z","end":"2026-01-28T01:11:57.427245Z","steps":["trace[1091663811] 'range keys from in-memory index tree' (duration: 212.840315ms)"],"step_count":1} {"level":"warn","ts":"2026-01-28T01:11:57.427517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.817717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-01-28T01:11:57.428821Z","caller":"traceutil/trace.go:171","msg":"trace[68791921] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1014; }","duration":"137.864618ms","start":"2026-01-28T01:11:57.289667Z","end":"2026-01-28T01:11:57.427532Z","steps":["trace[68791921] 'count revisions from in-memory index tree' (duration: 137.774016ms)"],"step_count":1}