{"level":"warn","ts":"2026-02-07T01:34:56.3887Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-07T01:34:56.388828Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.217: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.217:2380","--initial-cluster=instance=https://162.253.55.217:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.217:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.217:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-02-07T01:34:56.394079Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-07T01:34:56.394108Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.217:2380"]} {"level":"info","ts":"2026-02-07T01:34:56.394146Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-02-07T01:34:56.40075Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.217:2379"]} {"level":"info","ts":"2026-02-07T01:34:56.401363Z","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.217:2380"],"listen-peer-urls":["https://162.253.55.217:2380"],"advertise-client-urls":["https://162.253.55.217:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.217:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.217:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-02-07T01:34:56.408299Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.652921ms"} {"level":"info","ts":"2026-02-07T01:34:56.418855Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f1e368e6818bd6f7","cluster-id":"289e52865b7eaeb1"} {"level":"info","ts":"2026-02-07T01:34:56.41908Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 switched to configuration voters=()"} {"level":"info","ts":"2026-02-07T01:34:56.419225Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 became follower at term 0"} {"level":"info","ts":"2026-02-07T01:34:56.419271Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f1e368e6818bd6f7 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-07T01:34:56.419306Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 became follower at term 1"} {"level":"info","ts":"2026-02-07T01:34:56.419352Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 switched to configuration voters=(17429890322079143671)"} {"level":"warn","ts":"2026-02-07T01:34:56.43239Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-07T01:34:56.437211Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-07T01:34:56.441262Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-02-07T01:34:56.447328Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f1e368e6818bd6f7","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-07T01:34:56.448289Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f1e368e6818bd6f7","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-07T01:34:56.448493Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-07T01:34:56.448537Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-07T01:34:56.448555Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-07T01:34:56.449887Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 switched to configuration voters=(17429890322079143671)"} {"level":"info","ts":"2026-02-07T01:34:56.449995Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"289e52865b7eaeb1","local-member-id":"f1e368e6818bd6f7","added-peer-id":"f1e368e6818bd6f7","added-peer-peer-urls":["https://162.253.55.217:2380"]} {"level":"info","ts":"2026-02-07T01:34:56.459748Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-02-07T01:34:56.460064Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f1e368e6818bd6f7","initial-advertise-peer-urls":["https://162.253.55.217:2380"],"listen-peer-urls":["https://162.253.55.217:2380"],"advertise-client-urls":["https://162.253.55.217:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.217:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-07T01:34:56.460096Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-07T01:34:56.46007Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.217:2380"} {"level":"info","ts":"2026-02-07T01:34:56.460143Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.217:2380"} {"level":"info","ts":"2026-02-07T01:34:56.62083Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 is starting a new election at term 1"} {"level":"info","ts":"2026-02-07T01:34:56.620986Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-07T01:34:56.621065Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 received MsgPreVoteResp from f1e368e6818bd6f7 at term 1"} {"level":"info","ts":"2026-02-07T01:34:56.621147Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 became candidate at term 2"} {"level":"info","ts":"2026-02-07T01:34:56.621193Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 received MsgVoteResp from f1e368e6818bd6f7 at term 2"} {"level":"info","ts":"2026-02-07T01:34:56.621281Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e368e6818bd6f7 became leader at term 2"} {"level":"info","ts":"2026-02-07T01:34:56.621429Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f1e368e6818bd6f7 elected leader f1e368e6818bd6f7 at term 2"} {"level":"info","ts":"2026-02-07T01:34:56.623232Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-07T01:34:56.624118Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f1e368e6818bd6f7","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.217:2379]}","request-path":"/0/members/f1e368e6818bd6f7/attributes","cluster-id":"289e52865b7eaeb1","publish-timeout":"7s"} {"level":"info","ts":"2026-02-07T01:34:56.624983Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"289e52865b7eaeb1","local-member-id":"f1e368e6818bd6f7","cluster-version":"3.5"} {"level":"info","ts":"2026-02-07T01:34:56.625079Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-07T01:34:56.625149Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-07T01:34:56.62518Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-07T01:34:56.627012Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.217:2379"} {"level":"info","ts":"2026-02-07T01:34:56.632056Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-07T01:34:56.636153Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-07T01:34:56.644352Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-07T01:34:56.644391Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-07T01:35:18.871702Z","caller":"traceutil/trace.go:171","msg":"trace[661075421] transaction","detail":"{read_only:false; response_revision:421; number_of_response:1; }","duration":"106.027689ms","start":"2026-02-07T01:35:18.765647Z","end":"2026-02-07T01:35:18.871675Z","steps":["trace[661075421] 'process raft request' (duration: 105.855025ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T01:35:25.003311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.2357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-07T01:35:25.003462Z","caller":"traceutil/trace.go:171","msg":"trace[1592191661] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:435; }","duration":"110.480506ms","start":"2026-02-07T01:35:24.892955Z","end":"2026-02-07T01:35:25.003436Z","steps":["trace[1592191661] 'range keys from in-memory index tree' (duration: 110.115998ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T01:35:29.693631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.233199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T01:35:29.693701Z","caller":"traceutil/trace.go:171","msg":"trace[1381405000] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:479; }","duration":"365.408863ms","start":"2026-02-07T01:35:29.328276Z","end":"2026-02-07T01:35:29.693685Z","steps":["trace[1381405000] 'range keys from in-memory index tree' (duration: 365.126237ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T01:35:29.693739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T01:35:29.328259Z","time spent":"365.472354ms","remote":"127.0.0.1:44914","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-07T01:35:29.695536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.724968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-07T01:35:29.695582Z","caller":"traceutil/trace.go:171","msg":"trace[627332663] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:479; }","duration":"310.798429ms","start":"2026-02-07T01:35:29.384772Z","end":"2026-02-07T01:35:29.695571Z","steps":["trace[627332663] 'range keys from in-memory index tree' (duration: 310.636945ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T01:35:29.695628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T01:35:29.384757Z","time spent":"310.863741ms","remote":"127.0.0.1:45152","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-07T01:35:29.691145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.012059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-07T01:35:29.704991Z","caller":"traceutil/trace.go:171","msg":"trace[1431496338] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:479; }","duration":"458.914821ms","start":"2026-02-07T01:35:29.246066Z","end":"2026-02-07T01:35:29.704981Z","steps":["trace[1431496338] 'range keys from in-memory index tree' (duration: 444.523298ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T01:35:29.705079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T01:35:29.246053Z","time spent":"459.015793ms","remote":"127.0.0.1:45152","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-07T01:35:29.7053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.909879ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-07T01:35:29.705862Z","caller":"traceutil/trace.go:171","msg":"trace[1490144229] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"280.695997ms","start":"2026-02-07T01:35:29.425159Z","end":"2026-02-07T01:35:29.705855Z","steps":["trace[1490144229] 'process raft request' (duration: 146.758653ms)","trace[1490144229] 'compare' (duration: 119.994371ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T01:35:31.291358Z","caller":"traceutil/trace.go:171","msg":"trace[1501716329] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"259.668771ms","start":"2026-02-07T01:35:31.031675Z","end":"2026-02-07T01:35:31.291343Z","steps":["trace[1501716329] 'process raft request' (duration: 259.582959ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T01:35:32.886084Z","caller":"traceutil/trace.go:171","msg":"trace[499547061] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"152.930296ms","start":"2026-02-07T01:35:32.733102Z","end":"2026-02-07T01:35:32.886033Z","steps":["trace[499547061] 'process raft request' (duration: 152.785442ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T01:35:33.067555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.940797ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-07T01:35:33.067653Z","caller":"traceutil/trace.go:171","msg":"trace[313380761] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"243.555701ms","start":"2026-02-07T01:35:32.82408Z","end":"2026-02-07T01:35:33.067635Z","steps":["trace[313380761] 'process raft request' (duration: 113.44617ms)","trace[313380761] 'compare' (duration: 129.677872ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T01:35:37.00635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.543814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-07T01:35:37.006444Z","caller":"traceutil/trace.go:171","msg":"trace[1267104784] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:499; }","duration":"108.679766ms","start":"2026-02-07T01:35:36.897741Z","end":"2026-02-07T01:35:37.006421Z","steps":["trace[1267104784] 'range keys from in-memory index tree' (duration: 108.304858ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T01:35:37.359075Z","caller":"traceutil/trace.go:171","msg":"trace[604810447] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"348.543447ms","start":"2026-02-07T01:35:37.010499Z","end":"2026-02-07T01:35:37.359042Z","steps":["trace[604810447] 'process raft request' (duration: 348.259931ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T01:35:37.359207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T01:35:37.01048Z","time spent":"348.66031ms","remote":"127.0.0.1:45152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T01:35:37.510596Z","caller":"traceutil/trace.go:171","msg":"trace[480450917] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"132.264707ms","start":"2026-02-07T01:35:37.378312Z","end":"2026-02-07T01:35:37.510577Z","steps":["trace[480450917] 'process raft request' (duration: 132.140264ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T01:35:50.121463Z","caller":"traceutil/trace.go:171","msg":"trace[229460698] linearizableReadLoop","detail":"{readStateIndex:653; appliedIndex:652; }","duration":"189.649442ms","start":"2026-02-07T01:35:49.931792Z","end":"2026-02-07T01:35:50.121442Z","steps":["trace[229460698] 'read index received' (duration: 170.436786ms)","trace[229460698] 'applied index is now lower than readState.Index' (duration: 19.211586ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T01:35:50.121537Z","caller":"traceutil/trace.go:171","msg":"trace[1452875626] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"194.601699ms","start":"2026-02-07T01:35:49.926907Z","end":"2026-02-07T01:35:50.121509Z","steps":["trace[1452875626] 'process raft request' (duration: 175.371403ms)","trace[1452875626] 'compare' (duration: 18.988001ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T01:35:50.121655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.846176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-07T01:35:50.12168Z","caller":"traceutil/trace.go:171","msg":"trace[2088937812] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:635; }","duration":"189.901317ms","start":"2026-02-07T01:35:49.931771Z","end":"2026-02-07T01:35:50.121673Z","steps":["trace[2088937812] 'agreement among raft nodes before linearized reading' (duration: 189.782924ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T01:35:50.121829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.808074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-07T01:35:50.121856Z","caller":"traceutil/trace.go:171","msg":"trace[1373427775] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:635; }","duration":"111.869396ms","start":"2026-02-07T01:35:50.009979Z","end":"2026-02-07T01:35:50.121848Z","steps":["trace[1373427775] 'agreement among raft nodes before linearized reading' (duration: 111.770964ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T01:35:55.838796Z","caller":"traceutil/trace.go:171","msg":"trace[575815979] linearizableReadLoop","detail":"{readStateIndex:701; appliedIndex:700; }","duration":"324.175018ms","start":"2026-02-07T01:35:55.514596Z","end":"2026-02-07T01:35:55.838771Z","steps":["trace[575815979] 'read index received' (duration: 282.017054ms)","trace[575815979] 'applied index is now lower than readState.Index' (duration: 42.156294ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T01:35:55.839063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.453175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-07T01:35:55.839098Z","caller":"traceutil/trace.go:171","msg":"trace[1798338123] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:680; }","duration":"324.530016ms","start":"2026-02-07T01:35:55.514557Z","end":"2026-02-07T01:35:55.839087Z","steps":["trace[1798338123] 'agreement among raft nodes before linearized reading' (duration: 324.362112ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T01:35:55.839138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T01:35:55.514513Z","time spent":"324.617028ms","remote":"127.0.0.1:45152","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-07T01:35:55.841608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.151177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-07T01:35:55.84165Z","caller":"traceutil/trace.go:171","msg":"trace[1771383453] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:680; }","duration":"312.196778ms","start":"2026-02-07T01:35:55.529439Z","end":"2026-02-07T01:35:55.841636Z","steps":["trace[1771383453] 'agreement among raft nodes before linearized reading' (duration: 311.84084ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T01:35:55.841792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T01:35:55.529367Z","time spent":"312.414833ms","remote":"127.0.0.1:45152","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-07T01:35:58.476078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.850752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T01:35:58.47617Z","caller":"traceutil/trace.go:171","msg":"trace[63127494] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:688; }","duration":"151.970535ms","start":"2026-02-07T01:35:58.32418Z","end":"2026-02-07T01:35:58.47615Z","steps":["trace[63127494] 'range keys from in-memory index tree' (duration: 151.770791ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T01:35:58.476784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.595954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-07T01:35:58.476808Z","caller":"traceutil/trace.go:171","msg":"trace[366915423] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:688; }","duration":"316.638966ms","start":"2026-02-07T01:35:58.160163Z","end":"2026-02-07T01:35:58.476802Z","steps":["trace[366915423] 'range keys from in-memory index tree' (duration: 316.486123ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T01:35:58.476825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T01:35:58.160154Z","time spent":"316.665647ms","remote":"127.0.0.1:45152","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-07T01:35:58.476859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.949586ms","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-02-07T01:35:58.476922Z","caller":"traceutil/trace.go:171","msg":"trace[1038761398] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:688; }","duration":"313.039407ms","start":"2026-02-07T01:35:58.163867Z","end":"2026-02-07T01:35:58.476907Z","steps":["trace[1038761398] 'range keys from in-memory index tree' (duration: 312.723001ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T01:35:58.476952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T01:35:58.163858Z","time spent":"313.088579ms","remote":"127.0.0.1:45152","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-02-07T01:35:58.476967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.044377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-07T01:35:58.477012Z","caller":"traceutil/trace.go:171","msg":"trace[359041245] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:688; }","duration":"318.120168ms","start":"2026-02-07T01:35:58.158862Z","end":"2026-02-07T01:35:58.476982Z","steps":["trace[359041245] 'range keys from in-memory index tree' (duration: 317.930244ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T01:35:58.477027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T01:35:58.158835Z","time spent":"318.187499ms","remote":"127.0.0.1:45152","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-07T01:35:58.646695Z","caller":"traceutil/trace.go:171","msg":"trace[1628817402] transaction","detail":"{read_only:false; response_revision:690; number_of_response:1; }","duration":"163.774731ms","start":"2026-02-07T01:35:58.482865Z","end":"2026-02-07T01:35:58.64664Z","steps":["trace[1628817402] 'process raft request' (duration: 143.634184ms)","trace[1628817402] 'compare' (duration: 19.932792ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T01:35:58.64692Z","caller":"traceutil/trace.go:171","msg":"trace[1787452479] transaction","detail":"{read_only:false; response_revision:691; number_of_response:1; }","duration":"163.480374ms","start":"2026-02-07T01:35:58.483413Z","end":"2026-02-07T01:35:58.646893Z","steps":["trace[1787452479] 'process raft request' (duration: 163.127256ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T01:36:04.342835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.394182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-07T01:36:04.342885Z","caller":"traceutil/trace.go:171","msg":"trace[75568145] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:711; }","duration":"293.451853ms","start":"2026-02-07T01:36:04.049421Z","end":"2026-02-07T01:36:04.342873Z","steps":["trace[75568145] 'range keys from in-memory index tree' (duration: 293.29328ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T01:36:04.87104Z","caller":"traceutil/trace.go:171","msg":"trace[651610419] transaction","detail":"{read_only:false; response_revision:714; number_of_response:1; }","duration":"199.634259ms","start":"2026-02-07T01:36:04.671383Z","end":"2026-02-07T01:36:04.871018Z","steps":["trace[651610419] 'process raft request' (duration: 171.249183ms)","trace[651610419] 'compare' (duration: 28.261633ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T01:36:04.871406Z","caller":"traceutil/trace.go:171","msg":"trace[23290720] transaction","detail":"{read_only:false; response_revision:715; number_of_response:1; }","duration":"198.958854ms","start":"2026-02-07T01:36:04.672439Z","end":"2026-02-07T01:36:04.871398Z","steps":["trace[23290720] 'process raft request' (duration: 198.908473ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T01:36:16.624497Z","caller":"traceutil/trace.go:171","msg":"trace[117882266] transaction","detail":"{read_only:false; response_revision:745; number_of_response:1; }","duration":"177.4258ms","start":"2026-02-07T01:36:16.447046Z","end":"2026-02-07T01:36:16.624472Z","steps":["trace[117882266] 'process raft request' (duration: 177.215876ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T01:36:18.345905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.159034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-07T01:36:18.34654Z","caller":"traceutil/trace.go:171","msg":"trace[660445818] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:749; }","duration":"153.413169ms","start":"2026-02-07T01:36:18.192658Z","end":"2026-02-07T01:36:18.346071Z","steps":["trace[660445818] 'count revisions from in-memory index tree' (duration: 153.064471ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T01:36:18.832027Z","caller":"traceutil/trace.go:171","msg":"trace[1894522182] linearizableReadLoop","detail":"{readStateIndex:776; appliedIndex:775; }","duration":"156.677191ms","start":"2026-02-07T01:36:18.675318Z","end":"2026-02-07T01:36:18.831995Z","steps":["trace[1894522182] 'read index received' (duration: 156.438045ms)","trace[1894522182] 'applied index is now lower than readState.Index' (duration: 238.086µs)"],"step_count":2} {"level":"warn","ts":"2026-02-07T01:36:18.832302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.949108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-07T01:36:18.832353Z","caller":"traceutil/trace.go:171","msg":"trace[959822768] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:750; }","duration":"157.052269ms","start":"2026-02-07T01:36:18.675289Z","end":"2026-02-07T01:36:18.832342Z","steps":["trace[959822768] 'agreement among raft nodes before linearized reading' (duration: 156.817684ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T01:36:18.832703Z","caller":"traceutil/trace.go:171","msg":"trace[1145400743] transaction","detail":"{read_only:false; response_revision:750; number_of_response:1; }","duration":"200.205813ms","start":"2026-02-07T01:36:18.632486Z","end":"2026-02-07T01:36:18.832692Z","steps":["trace[1145400743] 'process raft request' (duration: 199.351604ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T01:36:19.043112Z","caller":"traceutil/trace.go:171","msg":"trace[1556693329] linearizableReadLoop","detail":"{readStateIndex:777; appliedIndex:776; }","duration":"119.217043ms","start":"2026-02-07T01:36:18.923875Z","end":"2026-02-07T01:36:19.043092Z","steps":["trace[1556693329] 'read index received' (duration: 119.06784ms)","trace[1556693329] 'applied index is now lower than readState.Index' (duration: 148.663µs)"],"step_count":2} {"level":"info","ts":"2026-02-07T01:36:19.043222Z","caller":"traceutil/trace.go:171","msg":"trace[1162761947] transaction","detail":"{read_only:false; response_revision:751; number_of_response:1; }","duration":"199.909426ms","start":"2026-02-07T01:36:18.843306Z","end":"2026-02-07T01:36:19.043216Z","steps":["trace[1162761947] 'process raft request' (duration: 199.677211ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T01:36:19.04342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.538951ms","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-02-07T01:36:19.043439Z","caller":"traceutil/trace.go:171","msg":"trace[458061916] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:751; }","duration":"119.622873ms","start":"2026-02-07T01:36:18.923809Z","end":"2026-02-07T01:36:19.043432Z","steps":["trace[458061916] 'agreement among raft nodes before linearized reading' (duration: 119.532991ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T01:36:19.04352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.245977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-07T01:36:19.043531Z","caller":"traceutil/trace.go:171","msg":"trace[97976498] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:751; }","duration":"111.289449ms","start":"2026-02-07T01:36:18.932238Z","end":"2026-02-07T01:36:19.043527Z","steps":["trace[97976498] 'agreement among raft nodes before linearized reading' (duration: 111.253708ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T01:36:19.3283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.657019ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-07T01:36:19.328519Z","caller":"traceutil/trace.go:171","msg":"trace[973168787] transaction","detail":"{read_only:false; response_revision:753; number_of_response:1; }","duration":"280.146109ms","start":"2026-02-07T01:36:19.048352Z","end":"2026-02-07T01:36:19.328499Z","steps":["trace[973168787] 'process raft request' (duration: 280.091018ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T01:36:19.328713Z","caller":"traceutil/trace.go:171","msg":"trace[1615844266] transaction","detail":"{read_only:false; response_revision:752; number_of_response:1; }","duration":"280.750103ms","start":"2026-02-07T01:36:19.047953Z","end":"2026-02-07T01:36:19.328703Z","steps":["trace[1615844266] 'process raft request' (duration: 104.07141ms)","trace[1615844266] 'compare' (duration: 174.29651ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T01:36:24.471214Z","caller":"traceutil/trace.go:171","msg":"trace[1529224802] linearizableReadLoop","detail":"{readStateIndex:794; appliedIndex:793; }","duration":"131.010905ms","start":"2026-02-07T01:36:24.34018Z","end":"2026-02-07T01:36:24.471191Z","steps":["trace[1529224802] 'read index received' (duration: 93.628989ms)","trace[1529224802] 'applied index is now lower than readState.Index' (duration: 37.380926ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T01:36:24.47282Z","caller":"traceutil/trace.go:171","msg":"trace[492504945] transaction","detail":"{read_only:false; response_revision:767; number_of_response:1; }","duration":"330.572423ms","start":"2026-02-07T01:36:24.142231Z","end":"2026-02-07T01:36:24.472803Z","steps":["trace[492504945] 'process raft request' (duration: 291.624382ms)","trace[492504945] 'compare' (duration: 37.167491ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T01:36:24.473529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.775234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T01:36:24.473584Z","caller":"traceutil/trace.go:171","msg":"trace[2068316962] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:767; }","duration":"133.418509ms","start":"2026-02-07T01:36:24.34015Z","end":"2026-02-07T01:36:24.473568Z","steps":["trace[2068316962] 'agreement among raft nodes before linearized reading' (duration: 131.148808ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T01:36:24.473997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T01:36:24.14219Z","time spent":"330.676185ms","remote":"127.0.0.1:45152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T01:36:35.597582Z","caller":"traceutil/trace.go:171","msg":"trace[574603494] transaction","detail":"{read_only:false; response_revision:849; number_of_response:1; }","duration":"158.490032ms","start":"2026-02-07T01:36:35.439075Z","end":"2026-02-07T01:36:35.597565Z","steps":["trace[574603494] 'process raft request' (duration: 158.40031ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T01:36:35.597867Z","caller":"traceutil/trace.go:171","msg":"trace[2120785769] linearizableReadLoop","detail":"{readStateIndex:880; appliedIndex:880; }","duration":"143.605932ms","start":"2026-02-07T01:36:35.454253Z","end":"2026-02-07T01:36:35.597859Z","steps":["trace[2120785769] 'read index received' (duration: 143.601352ms)","trace[2120785769] 'applied index is now lower than readState.Index' (duration: 2.45µs)"],"step_count":2} {"level":"warn","ts":"2026-02-07T01:36:35.597959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.704765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-07T01:36:35.59798Z","caller":"traceutil/trace.go:171","msg":"trace[1424302155] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:849; }","duration":"143.770206ms","start":"2026-02-07T01:36:35.454204Z","end":"2026-02-07T01:36:35.597974Z","steps":["trace[1424302155] 'agreement among raft nodes before linearized reading' (duration: 143.683104ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T01:36:37.469631Z","caller":"traceutil/trace.go:171","msg":"trace[992411563] linearizableReadLoop","detail":"{readStateIndex:884; appliedIndex:883; }","duration":"347.559588ms","start":"2026-02-07T01:36:37.122053Z","end":"2026-02-07T01:36:37.469613Z","steps":["trace[992411563] 'read index received' (duration: 347.445406ms)","trace[992411563] 'applied index is now lower than readState.Index' (duration: 113.212µs)"],"step_count":2} {"level":"info","ts":"2026-02-07T01:36:37.469732Z","caller":"traceutil/trace.go:171","msg":"trace[852109528] transaction","detail":"{read_only:false; response_revision:852; number_of_response:1; }","duration":"559.91589ms","start":"2026-02-07T01:36:36.909811Z","end":"2026-02-07T01:36:37.469727Z","steps":["trace[852109528] 'process raft request' (duration: 559.686475ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T01:36:37.469812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T01:36:36.909802Z","time spent":"559.947141ms","remote":"127.0.0.1:45152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-07T01:36:37.469965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.911026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-07T01:36:37.469982Z","caller":"traceutil/trace.go:171","msg":"trace[1427301988] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:852; }","duration":"347.952308ms","start":"2026-02-07T01:36:37.122024Z","end":"2026-02-07T01:36:37.469977Z","steps":["trace[1427301988] 'agreement among raft nodes before linearized reading' (duration: 347.868976ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T01:36:37.469998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T01:36:37.122009Z","time spent":"347.986418ms","remote":"127.0.0.1:45152","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-07T01:36:37.470051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.596468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T01:36:37.470068Z","caller":"traceutil/trace.go:171","msg":"trace[1451664253] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:852; }","duration":"141.627429ms","start":"2026-02-07T01:36:37.32843Z","end":"2026-02-07T01:36:37.470057Z","steps":["trace[1451664253] 'agreement among raft nodes before linearized reading' (duration: 141.608969ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T01:36:37.470068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.211339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-07T01:36:37.47016Z","caller":"traceutil/trace.go:171","msg":"trace[1029859174] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:852; }","duration":"284.351002ms","start":"2026-02-07T01:36:37.18579Z","end":"2026-02-07T01:36:37.470141Z","steps":["trace[1029859174] 'agreement among raft nodes before linearized reading' (duration: 284.172968ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T01:36:49.916918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.376139ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-07T01:36:49.916987Z","caller":"traceutil/trace.go:171","msg":"trace[1762268054] linearizableReadLoop","detail":"{readStateIndex:920; appliedIndex:919; }","duration":"277.399849ms","start":"2026-02-07T01:36:49.639576Z","end":"2026-02-07T01:36:49.916976Z","steps":["trace[1762268054] 'read index received' (duration: 32.903487ms)","trace[1762268054] 'applied index is now lower than readState.Index' (duration: 244.491822ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T01:36:49.917037Z","caller":"traceutil/trace.go:171","msg":"trace[192524522] transaction","detail":"{read_only:false; response_revision:886; number_of_response:1; }","duration":"296.816938ms","start":"2026-02-07T01:36:49.620214Z","end":"2026-02-07T01:36:49.917031Z","steps":["trace[192524522] 'process raft request' (duration: 52.275785ms)","trace[192524522] 'compare' (duration: 244.205276ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T01:36:49.91719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.298454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-07T01:36:49.917257Z","caller":"traceutil/trace.go:171","msg":"trace[1561604555] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:886; }","duration":"261.388015ms","start":"2026-02-07T01:36:49.655843Z","end":"2026-02-07T01:36:49.917232Z","steps":["trace[1561604555] 'agreement among raft nodes before linearized reading' (duration: 261.230302ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T01:36:49.917263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.689605ms","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-02-07T01:36:49.917282Z","caller":"traceutil/trace.go:171","msg":"trace[927112958] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:886; }","duration":"277.729876ms","start":"2026-02-07T01:36:49.639546Z","end":"2026-02-07T01:36:49.917276Z","steps":["trace[927112958] 'agreement among raft nodes before linearized reading' (duration: 277.646245ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T01:36:52.21105Z","caller":"traceutil/trace.go:171","msg":"trace[208724763] transaction","detail":"{read_only:false; response_revision:895; number_of_response:1; }","duration":"247.851945ms","start":"2026-02-07T01:36:51.963181Z","end":"2026-02-07T01:36:52.211033Z","steps":["trace[208724763] 'process raft request' (duration: 247.820905ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T01:36:52.211255Z","caller":"traceutil/trace.go:171","msg":"trace[217385222] transaction","detail":"{read_only:false; response_revision:894; number_of_response:1; }","duration":"249.353319ms","start":"2026-02-07T01:36:51.961874Z","end":"2026-02-07T01:36:52.211227Z","steps":["trace[217385222] 'process raft request' (duration: 160.399763ms)","trace[217385222] 'compare' (duration: 88.644899ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T01:36:55.873559Z","caller":"traceutil/trace.go:171","msg":"trace[752950630] linearizableReadLoop","detail":"{readStateIndex:946; appliedIndex:945; }","duration":"238.667303ms","start":"2026-02-07T01:36:55.634706Z","end":"2026-02-07T01:36:55.873373Z","steps":["trace[752950630] 'read index received' (duration: 159.348881ms)","trace[752950630] 'applied index is now lower than readState.Index' (duration: 79.316782ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T01:36:55.873789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.066371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-07T01:36:55.87383Z","caller":"traceutil/trace.go:171","msg":"trace[1570095789] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:910; }","duration":"239.161384ms","start":"2026-02-07T01:36:55.634659Z","end":"2026-02-07T01:36:55.87382Z","steps":["trace[1570095789] 'agreement among raft nodes before linearized reading' (duration: 238.98189ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T01:36:56.367088Z","caller":"traceutil/trace.go:171","msg":"trace[1724581345] transaction","detail":"{read_only:false; response_revision:913; number_of_response:1; }","duration":"139.821819ms","start":"2026-02-07T01:36:56.227247Z","end":"2026-02-07T01:36:56.367069Z","steps":["trace[1724581345] 'process raft request' (duration: 139.714626ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T01:36:56.48849Z","caller":"traceutil/trace.go:171","msg":"trace[431166597] linearizableReadLoop","detail":"{readStateIndex:950; appliedIndex:949; }","duration":"162.842497ms","start":"2026-02-07T01:36:56.325624Z","end":"2026-02-07T01:36:56.488466Z","steps":["trace[431166597] 'read index received' (duration: 41.787903ms)","trace[431166597] 'applied index is now lower than readState.Index' (duration: 121.053284ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T01:36:56.48868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.043082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T01:36:56.488728Z","caller":"traceutil/trace.go:171","msg":"trace[1868167579] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:914; }","duration":"163.121494ms","start":"2026-02-07T01:36:56.325596Z","end":"2026-02-07T01:36:56.488717Z","steps":["trace[1868167579] 'agreement among raft nodes before linearized reading' (duration: 163.039502ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T01:36:56.489161Z","caller":"traceutil/trace.go:171","msg":"trace[1477083141] transaction","detail":"{read_only:false; response_revision:914; number_of_response:1; }","duration":"261.135098ms","start":"2026-02-07T01:36:56.228011Z","end":"2026-02-07T01:36:56.489146Z","steps":["trace[1477083141] 'process raft request' (duration: 168.016591ms)","trace[1477083141] 'compare' (duration: 92.287789ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T01:37:09.072981Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15490021197980085148,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-07T01:37:09.481251Z","caller":"traceutil/trace.go:171","msg":"trace[1515745374] linearizableReadLoop","detail":"{readStateIndex:1021; appliedIndex:1020; }","duration":"909.095543ms","start":"2026-02-07T01:37:08.572134Z","end":"2026-02-07T01:37:09.48123Z","steps":["trace[1515745374] 'read index received' (duration: 908.93831ms)","trace[1515745374] 'applied index is now lower than readState.Index' (duration: 156.733µs)"],"step_count":2} {"level":"info","ts":"2026-02-07T01:37:09.48147Z","caller":"traceutil/trace.go:171","msg":"trace[1881438528] transaction","detail":"{read_only:false; response_revision:983; number_of_response:1; }","duration":"922.02122ms","start":"2026-02-07T01:37:08.559443Z","end":"2026-02-07T01:37:09.481464Z","steps":["trace[1881438528] 'process raft request' (duration: 921.684442ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T01:37:09.481565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T01:37:08.559421Z","time spent":"922.07815ms","remote":"127.0.0.1:45152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-07T01:37:09.481683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"909.545283ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T01:37:09.481701Z","caller":"traceutil/trace.go:171","msg":"trace[659012590] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:983; }","duration":"909.582615ms","start":"2026-02-07T01:37:08.572112Z","end":"2026-02-07T01:37:09.481695Z","steps":["trace[659012590] 'agreement among raft nodes before linearized reading' (duration: 909.551114ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T01:37:09.481715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T01:37:08.572104Z","time spent":"909.607525ms","remote":"127.0.0.1:42296","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true "} {"level":"warn","ts":"2026-02-07T01:37:09.481839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"852.293329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-07T01:37:09.481852Z","caller":"traceutil/trace.go:171","msg":"trace[672151302] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:983; }","duration":"852.34888ms","start":"2026-02-07T01:37:08.629499Z","end":"2026-02-07T01:37:09.481848Z","steps":["trace[672151302] 'agreement among raft nodes before linearized reading' (duration: 852.283299ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T01:37:09.481863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T01:37:08.629447Z","time spent":"852.413551ms","remote":"127.0.0.1:45152","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-07T01:37:09.482028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.640145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T01:37:09.48204Z","caller":"traceutil/trace.go:171","msg":"trace[1187126163] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:983; }","duration":"153.700825ms","start":"2026-02-07T01:37:09.328336Z","end":"2026-02-07T01:37:09.482037Z","steps":["trace[1187126163] 'agreement among raft nodes before linearized reading' (duration: 153.663114ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T01:37:12.238097Z","caller":"traceutil/trace.go:171","msg":"trace[1691409452] transaction","detail":"{read_only:false; response_revision:1012; number_of_response:1; }","duration":"291.654713ms","start":"2026-02-07T01:37:11.945517Z","end":"2026-02-07T01:37:12.237171Z","steps":["trace[1691409452] 'process raft request' (duration: 291.04426ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T01:40:33.59818Z","caller":"traceutil/trace.go:171","msg":"trace[1984425305] transaction","detail":"{read_only:false; response_revision:1859; number_of_response:1; }","duration":"116.36813ms","start":"2026-02-07T01:40:33.481782Z","end":"2026-02-07T01:40:33.598151Z","steps":["trace[1984425305] 'process raft request' (duration: 115.638053ms)"],"step_count":1}