{"level":"warn","ts":"2026-02-04T19:44:10.453323Z","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-04T19:44:10.453436Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.195: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.195:2380","--initial-cluster=instance=https://162.253.55.195:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.195:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.195: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-04T19:44:10.453504Z","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-04T19:44:10.45352Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.195:2380"]} {"level":"info","ts":"2026-02-04T19:44:10.453545Z","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-04T19:44:10.459282Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.195:2379"]} {"level":"info","ts":"2026-02-04T19:44:10.459364Z","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.195:2380"],"listen-peer-urls":["https://162.253.55.195:2380"],"advertise-client-urls":["https://162.253.55.195:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.195:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.195: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-04T19:44:10.468758Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"9.217749ms"} {"level":"info","ts":"2026-02-04T19:44:10.479842Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b66d12fd59101739","cluster-id":"a64a525934ce8379"} {"level":"info","ts":"2026-02-04T19:44:10.479885Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 switched to configuration voters=()"} {"level":"info","ts":"2026-02-04T19:44:10.479908Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 became follower at term 0"} {"level":"info","ts":"2026-02-04T19:44:10.479917Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b66d12fd59101739 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-04T19:44:10.479924Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 became follower at term 1"} {"level":"info","ts":"2026-02-04T19:44:10.479962Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 switched to configuration voters=(13145183766694598457)"} {"level":"warn","ts":"2026-02-04T19:44:10.501193Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-04T19:44:10.509574Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-04T19:44:10.513695Z","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-04T19:44:10.51828Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b66d12fd59101739","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-04T19:44:10.520827Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b66d12fd59101739","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-04T19:44:10.521057Z","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-04T19:44:10.521093Z","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-04T19:44:10.5211Z","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-04T19:44:10.523021Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 switched to configuration voters=(13145183766694598457)"} {"level":"info","ts":"2026-02-04T19:44:10.523103Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"a64a525934ce8379","local-member-id":"b66d12fd59101739","added-peer-id":"b66d12fd59101739","added-peer-peer-urls":["https://162.253.55.195:2380"]} {"level":"info","ts":"2026-02-04T19:44:10.529238Z","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-04T19:44:10.529439Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.195:2380"} {"level":"info","ts":"2026-02-04T19:44:10.531311Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.195:2380"} {"level":"info","ts":"2026-02-04T19:44:10.532365Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b66d12fd59101739","initial-advertise-peer-urls":["https://162.253.55.195:2380"],"listen-peer-urls":["https://162.253.55.195:2380"],"advertise-client-urls":["https://162.253.55.195:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.195:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-04T19:44:10.532418Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-04T19:44:10.982374Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 is starting a new election at term 1"} {"level":"info","ts":"2026-02-04T19:44:10.982417Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-04T19:44:10.982431Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 received MsgPreVoteResp from b66d12fd59101739 at term 1"} {"level":"info","ts":"2026-02-04T19:44:10.982442Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 became candidate at term 2"} {"level":"info","ts":"2026-02-04T19:44:10.982529Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 received MsgVoteResp from b66d12fd59101739 at term 2"} {"level":"info","ts":"2026-02-04T19:44:10.982558Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 became leader at term 2"} {"level":"info","ts":"2026-02-04T19:44:10.982618Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b66d12fd59101739 elected leader b66d12fd59101739 at term 2"} {"level":"info","ts":"2026-02-04T19:44:10.986926Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-04T19:44:10.990171Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b66d12fd59101739","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.195:2379]}","request-path":"/0/members/b66d12fd59101739/attributes","cluster-id":"a64a525934ce8379","publish-timeout":"7s"} {"level":"info","ts":"2026-02-04T19:44:10.99036Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-04T19:44:10.990735Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"a64a525934ce8379","local-member-id":"b66d12fd59101739","cluster-version":"3.5"} {"level":"info","ts":"2026-02-04T19:44:10.990864Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-04T19:44:10.990942Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-04T19:44:10.990965Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-04T19:44:10.990972Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-04T19:44:10.991053Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-04T19:44:10.994932Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-04T19:44:11.012457Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.195:2379"} {"level":"info","ts":"2026-02-04T19:44:23.487342Z","caller":"traceutil/trace.go:171","msg":"trace[1368180080] transaction","detail":"{read_only:false; response_revision:406; number_of_response:1; }","duration":"111.632127ms","start":"2026-02-04T19:44:23.375678Z","end":"2026-02-04T19:44:23.487311Z","steps":["trace[1368180080] 'process raft request' (duration: 102.398983ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T19:44:26.377957Z","caller":"traceutil/trace.go:171","msg":"trace[326164531] transaction","detail":"{read_only:false; response_revision:420; number_of_response:1; }","duration":"239.962352ms","start":"2026-02-04T19:44:26.137975Z","end":"2026-02-04T19:44:26.377937Z","steps":["trace[326164531] 'process raft request' (duration: 236.250531ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T19:44:29.509243Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1673540441536263109,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-04T19:44:30.011461Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1673540441536263109,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-04T19:44:30.134668Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.125944632s","expected-duration":"1s"} {"level":"info","ts":"2026-02-04T19:44:30.135122Z","caller":"traceutil/trace.go:171","msg":"trace[929990693] transaction","detail":"{read_only:false; response_revision:456; number_of_response:1; }","duration":"1.126486655s","start":"2026-02-04T19:44:29.008614Z","end":"2026-02-04T19:44:30.1351Z","steps":["trace[929990693] 'process raft request' (duration: 1.126317881s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T19:44:30.135668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T19:44:29.008599Z","time spent":"1.126566717s","remote":"127.0.0.1:38804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T19:44:30.143223Z","caller":"traceutil/trace.go:171","msg":"trace[1357845595] linearizableReadLoop","detail":"{readStateIndex:468; appliedIndex:467; }","duration":"1.13407996s","start":"2026-02-04T19:44:29.009134Z","end":"2026-02-04T19:44:30.143214Z","steps":["trace[1357845595] 'read index received' (duration: 1.127012318s)","trace[1357845595] 'applied index is now lower than readState.Index' (duration: 7.067072ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T19:44:30.143306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.134163582s","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-04T19:44:30.143327Z","caller":"traceutil/trace.go:171","msg":"trace[1175549181] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:456; }","duration":"1.134198303s","start":"2026-02-04T19:44:29.009123Z","end":"2026-02-04T19:44:30.143321Z","steps":["trace[1175549181] 'agreement among raft nodes before linearized reading' (duration: 1.134152112s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T19:44:30.143342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T19:44:29.009118Z","time spent":"1.134220943s","remote":"127.0.0.1:38804","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-04T19:44:30.143449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"962.019321ms","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-04T19:44:30.143463Z","caller":"traceutil/trace.go:171","msg":"trace[1283018532] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:456; }","duration":"962.058292ms","start":"2026-02-04T19:44:29.1814Z","end":"2026-02-04T19:44:30.143458Z","steps":["trace[1283018532] 'agreement among raft nodes before linearized reading' (duration: 962.031282ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T19:44:30.143475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T19:44:29.181386Z","time spent":"962.086623ms","remote":"127.0.0.1:38804","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-04T19:44:30.143567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.658867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T19:44:30.143581Z","caller":"traceutil/trace.go:171","msg":"trace[1550585361] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:456; }","duration":"127.691707ms","start":"2026-02-04T19:44:30.015884Z","end":"2026-02-04T19:44:30.143576Z","steps":["trace[1550585361] 'agreement among raft nodes before linearized reading' (duration: 127.671547ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T19:44:30.143667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"857.738842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-04T19:44:30.143679Z","caller":"traceutil/trace.go:171","msg":"trace[1147163586] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:456; }","duration":"857.778553ms","start":"2026-02-04T19:44:29.285896Z","end":"2026-02-04T19:44:30.143675Z","steps":["trace[1147163586] 'agreement among raft nodes before linearized reading' (duration: 857.755093ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T19:44:30.143693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T19:44:29.28588Z","time spent":"857.809524ms","remote":"127.0.0.1:38804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-04T19:44:43.116784Z","caller":"traceutil/trace.go:171","msg":"trace[123944368] linearizableReadLoop","detail":"{readStateIndex:535; appliedIndex:534; }","duration":"176.020934ms","start":"2026-02-04T19:44:42.940739Z","end":"2026-02-04T19:44:43.11676Z","steps":["trace[123944368] 'read index received' (duration: 175.794609ms)","trace[123944368] 'applied index is now lower than readState.Index' (duration: 225.395µs)"],"step_count":2} {"level":"info","ts":"2026-02-04T19:44:43.1169Z","caller":"traceutil/trace.go:171","msg":"trace[2025645747] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"340.013737ms","start":"2026-02-04T19:44:42.776875Z","end":"2026-02-04T19:44:43.116888Z","steps":["trace[2025645747] 'process raft request' (duration: 339.735681ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T19:44:43.117031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T19:44:42.776847Z","time spent":"340.079709ms","remote":"127.0.0.1:38722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4211,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T19:44:43.117138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.714647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T19:44:43.117245Z","caller":"traceutil/trace.go:171","msg":"trace[1418694935] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:521; }","duration":"115.893702ms","start":"2026-02-04T19:44:43.001332Z","end":"2026-02-04T19:44:43.117226Z","steps":["trace[1418694935] 'agreement among raft nodes before linearized reading' (duration: 115.748828ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T19:44:43.117366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.63722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T19:44:43.1174Z","caller":"traceutil/trace.go:171","msg":"trace[706534498] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:521; }","duration":"176.739263ms","start":"2026-02-04T19:44:42.94065Z","end":"2026-02-04T19:44:43.11739Z","steps":["trace[706534498] 'agreement among raft nodes before linearized reading' (duration: 176.684551ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T19:44:51.128999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"665.970823ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-04T19:44:51.129105Z","caller":"traceutil/trace.go:171","msg":"trace[165504472] linearizableReadLoop","detail":"{readStateIndex:556; appliedIndex:555; }","duration":"860.980871ms","start":"2026-02-04T19:44:50.268106Z","end":"2026-02-04T19:44:51.129087Z","steps":["trace[165504472] 'read index received' (duration: 194.579277ms)","trace[165504472] 'applied index is now lower than readState.Index' (duration: 666.400314ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T19:44:51.129333Z","caller":"traceutil/trace.go:171","msg":"trace[1496278941] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"870.202405ms","start":"2026-02-04T19:44:50.259115Z","end":"2026-02-04T19:44:51.129318Z","steps":["trace[1496278941] 'process raft request' (duration: 203.689448ms)","trace[1496278941] 'compare' (duration: 665.8425ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T19:44:51.129396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T19:44:50.259071Z","time spent":"870.292127ms","remote":"127.0.0.1:38734","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T19:44:51.129655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"861.545055ms","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-04T19:44:51.129687Z","caller":"traceutil/trace.go:171","msg":"trace[1928553284] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:540; }","duration":"861.667537ms","start":"2026-02-04T19:44:50.268007Z","end":"2026-02-04T19:44:51.129674Z","steps":["trace[1928553284] 'agreement among raft nodes before linearized reading' (duration: 861.539864ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T19:44:51.129717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T19:44:50.26798Z","time spent":"861.731179ms","remote":"127.0.0.1:38804","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-04T19:44:51.129905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"855.407155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-04T19:44:51.12993Z","caller":"traceutil/trace.go:171","msg":"trace[665262951] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:540; }","duration":"855.473836ms","start":"2026-02-04T19:44:50.274449Z","end":"2026-02-04T19:44:51.129922Z","steps":["trace[665262951] 'agreement among raft nodes before linearized reading' (duration: 855.394384ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T19:44:51.129954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T19:44:50.274429Z","time spent":"855.520318ms","remote":"127.0.0.1:38804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-04T19:44:51.130225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.234118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T19:44:51.130252Z","caller":"traceutil/trace.go:171","msg":"trace[1962205477] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:540; }","duration":"127.300359ms","start":"2026-02-04T19:44:51.002944Z","end":"2026-02-04T19:44:51.130244Z","steps":["trace[1962205477] 'agreement among raft nodes before linearized reading' (duration: 127.251528ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T19:44:51.130461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"814.98317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-02-04T19:44:51.13049Z","caller":"traceutil/trace.go:171","msg":"trace[441059089] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:540; }","duration":"815.040222ms","start":"2026-02-04T19:44:50.315442Z","end":"2026-02-04T19:44:51.130483Z","steps":["trace[441059089] 'agreement among raft nodes before linearized reading' (duration: 814.96691ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T19:44:51.130515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T19:44:50.315432Z","time spent":"815.077543ms","remote":"127.0.0.1:38674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":375,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-02-04T19:44:51.130691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"839.642381ms","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-04T19:44:51.130717Z","caller":"traceutil/trace.go:171","msg":"trace[1157210482] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:540; }","duration":"839.677373ms","start":"2026-02-04T19:44:50.291032Z","end":"2026-02-04T19:44:51.130709Z","steps":["trace[1157210482] 'agreement among raft nodes before linearized reading' (duration: 839.600491ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T19:44:51.13074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T19:44:50.291026Z","time spent":"839.708583ms","remote":"127.0.0.1:38804","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-04T19:44:51.130887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"841.511237ms","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-04T19:44:51.130911Z","caller":"traceutil/trace.go:171","msg":"trace[1526045754] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:540; }","duration":"841.568108ms","start":"2026-02-04T19:44:50.289336Z","end":"2026-02-04T19:44:51.130904Z","steps":["trace[1526045754] 'agreement among raft nodes before linearized reading' (duration: 841.499137ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T19:44:51.13094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T19:44:50.289322Z","time spent":"841.611529ms","remote":"127.0.0.1:38804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-04T19:44:54.857211Z","caller":"traceutil/trace.go:171","msg":"trace[1622345425] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"339.68979ms","start":"2026-02-04T19:44:54.517504Z","end":"2026-02-04T19:44:54.857194Z","steps":["trace[1622345425] 'process raft request' (duration: 339.551786ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T19:44:54.857349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T19:44:54.517492Z","time spent":"339.780023ms","remote":"127.0.0.1:39038","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":932,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-04T19:44:54.861016Z","caller":"traceutil/trace.go:171","msg":"trace[1417074814] linearizableReadLoop","detail":"{readStateIndex:585; appliedIndex:583; }","duration":"336.482321ms","start":"2026-02-04T19:44:54.524523Z","end":"2026-02-04T19:44:54.861006Z","steps":["trace[1417074814] 'read index received' (duration: 332.599307ms)","trace[1417074814] 'applied index is now lower than readState.Index' (duration: 3.882144ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T19:44:54.861158Z","caller":"traceutil/trace.go:171","msg":"trace[1855254707] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"343.507903ms","start":"2026-02-04T19:44:54.517638Z","end":"2026-02-04T19:44:54.861146Z","steps":["trace[1855254707] 'process raft request' (duration: 343.327589ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T19:44:54.861203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T19:44:54.517622Z","time spent":"343.550514ms","remote":"127.0.0.1:38626","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5562,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T19:44:54.861299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.769639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T19:44:54.861337Z","caller":"traceutil/trace.go:171","msg":"trace[1884643789] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:0; response_revision:568; }","duration":"336.83366ms","start":"2026-02-04T19:44:54.524496Z","end":"2026-02-04T19:44:54.86133Z","steps":["trace[1884643789] 'agreement among raft nodes before linearized reading' (duration: 336.778609ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T19:44:54.861353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T19:44:54.524487Z","time spent":"336.861921ms","remote":"127.0.0.1:38626","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":0,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" "} {"level":"warn","ts":"2026-02-04T19:44:54.861427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.520993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T19:44:54.861441Z","caller":"traceutil/trace.go:171","msg":"trace[688728878] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:0; response_revision:568; }","duration":"336.533843ms","start":"2026-02-04T19:44:54.524902Z","end":"2026-02-04T19:44:54.861436Z","steps":["trace[688728878] 'agreement among raft nodes before linearized reading' (duration: 336.512082ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T19:44:54.861454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T19:44:54.524888Z","time spent":"336.563384ms","remote":"127.0.0.1:38626","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":0,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" "} {"level":"warn","ts":"2026-02-04T19:44:54.861558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.71702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T19:44:54.861573Z","caller":"traceutil/trace.go:171","msg":"trace[1754690585] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:568; }","duration":"187.777451ms","start":"2026-02-04T19:44:54.673791Z","end":"2026-02-04T19:44:54.861569Z","steps":["trace[1754690585] 'agreement among raft nodes before linearized reading' (duration: 187.753421ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T19:44:54.861639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.441172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T19:44:54.861653Z","caller":"traceutil/trace.go:171","msg":"trace[1721312111] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:0; response_revision:568; }","duration":"327.465192ms","start":"2026-02-04T19:44:54.534183Z","end":"2026-02-04T19:44:54.861648Z","steps":["trace[1721312111] 'agreement among raft nodes before linearized reading' (duration: 327.444692ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T19:44:54.861665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T19:44:54.534176Z","time spent":"327.486262ms","remote":"127.0.0.1:38626","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":0,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" "} {"level":"warn","ts":"2026-02-04T19:44:54.861721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.241056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T19:44:54.861737Z","caller":"traceutil/trace.go:171","msg":"trace[139316796] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:0; response_revision:568; }","duration":"329.264146ms","start":"2026-02-04T19:44:54.532467Z","end":"2026-02-04T19:44:54.861731Z","steps":["trace[139316796] 'agreement among raft nodes before linearized reading' (duration: 329.241836ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T19:44:54.86175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T19:44:54.532461Z","time spent":"329.285096ms","remote":"127.0.0.1:38626","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" "} {"level":"warn","ts":"2026-02-04T19:44:54.864175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.95449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T19:44:54.864195Z","caller":"traceutil/trace.go:171","msg":"trace[921235264] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:0; response_revision:568; }","duration":"334.006902ms","start":"2026-02-04T19:44:54.530183Z","end":"2026-02-04T19:44:54.864189Z","steps":["trace[921235264] 'agreement among raft nodes before linearized reading' (duration: 333.966851ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T19:44:54.864213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T19:44:54.530157Z","time spent":"334.049252ms","remote":"127.0.0.1:38626","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" "} {"level":"warn","ts":"2026-02-04T19:44:54.864285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.142645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T19:44:54.864297Z","caller":"traceutil/trace.go:171","msg":"trace[471504777] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:0; response_revision:568; }","duration":"334.166765ms","start":"2026-02-04T19:44:54.530126Z","end":"2026-02-04T19:44:54.864293Z","steps":["trace[471504777] 'agreement among raft nodes before linearized reading' (duration: 334.144025ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T19:44:54.86431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T19:44:54.530119Z","time spent":"334.187436ms","remote":"127.0.0.1:38626","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":0,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" "} {"level":"info","ts":"2026-02-04T19:44:57.597545Z","caller":"traceutil/trace.go:171","msg":"trace[1092963990] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"148.928106ms","start":"2026-02-04T19:44:57.44831Z","end":"2026-02-04T19:44:57.597238Z","steps":["trace[1092963990] 'process raft request' (duration: 106.970234ms)","trace[1092963990] 'compare' (duration: 41.832149ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T19:44:57.600081Z","caller":"traceutil/trace.go:171","msg":"trace[892717303] linearizableReadLoop","detail":"{readStateIndex:642; appliedIndex:639; }","duration":"128.627051ms","start":"2026-02-04T19:44:57.47143Z","end":"2026-02-04T19:44:57.600057Z","steps":["trace[892717303] 'read index received' (duration: 83.933443ms)","trace[892717303] 'applied index is now lower than readState.Index' (duration: 44.692878ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T19:44:57.60027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.817556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:1 size:5177"} {"level":"info","ts":"2026-02-04T19:44:57.600496Z","caller":"traceutil/trace.go:171","msg":"trace[529805000] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:1; response_revision:625; }","duration":"129.075232ms","start":"2026-02-04T19:44:57.471394Z","end":"2026-02-04T19:44:57.600469Z","steps":["trace[529805000] 'agreement among raft nodes before linearized reading' (duration: 128.755165ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T19:44:57.600715Z","caller":"traceutil/trace.go:171","msg":"trace[1607795640] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"144.60173ms","start":"2026-02-04T19:44:57.456102Z","end":"2026-02-04T19:44:57.600704Z","steps":["trace[1607795640] 'process raft request' (duration: 143.78059ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T19:44:57.601137Z","caller":"traceutil/trace.go:171","msg":"trace[61313546] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"151.550279ms","start":"2026-02-04T19:44:57.449575Z","end":"2026-02-04T19:44:57.601126Z","steps":["trace[61313546] 'process raft request' (duration: 150.104724ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T19:45:07.274997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"563.96964ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-04T19:45:07.275082Z","caller":"traceutil/trace.go:171","msg":"trace[473327474] linearizableReadLoop","detail":"{readStateIndex:739; appliedIndex:738; }","duration":"269.052ms","start":"2026-02-04T19:45:07.006018Z","end":"2026-02-04T19:45:07.27507Z","steps":["trace[473327474] 'read index received' (duration: 37.521µs)","trace[473327474] 'applied index is now lower than readState.Index' (duration: 269.013649ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T19:45:07.275138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.115722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T19:45:07.275174Z","caller":"traceutil/trace.go:171","msg":"trace[1113177095] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:719; }","duration":"269.203264ms","start":"2026-02-04T19:45:07.005966Z","end":"2026-02-04T19:45:07.275169Z","steps":["trace[1113177095] 'agreement among raft nodes before linearized reading' (duration: 269.133302ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T19:45:07.275373Z","caller":"traceutil/trace.go:171","msg":"trace[138953711] transaction","detail":"{read_only:false; response_revision:719; number_of_response:1; }","duration":"597.481755ms","start":"2026-02-04T19:45:06.677881Z","end":"2026-02-04T19:45:07.275363Z","steps":["trace[138953711] 'process raft request' (duration: 33.076845ms)","trace[138953711] 'compare' (duration: 563.824376ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T19:45:07.275407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T19:45:06.677858Z","time spent":"597.530667ms","remote":"127.0.0.1:38804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T19:45:07.781075Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1673540441536263628,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-04T19:45:07.787625Z","caller":"traceutil/trace.go:171","msg":"trace[130135700] linearizableReadLoop","detail":"{readStateIndex:740; appliedIndex:739; }","duration":"507.547356ms","start":"2026-02-04T19:45:07.280057Z","end":"2026-02-04T19:45:07.787604Z","steps":["trace[130135700] 'read index received' (duration: 507.341081ms)","trace[130135700] 'applied index is now lower than readState.Index' (duration: 204.725µs)"],"step_count":2} {"level":"info","ts":"2026-02-04T19:45:07.787735Z","caller":"traceutil/trace.go:171","msg":"trace[667252088] transaction","detail":"{read_only:false; response_revision:720; number_of_response:1; }","duration":"539.124845ms","start":"2026-02-04T19:45:07.248598Z","end":"2026-02-04T19:45:07.787723Z","steps":["trace[667252088] 'process raft request' (duration: 538.807317ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T19:45:07.787874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T19:45:07.248573Z","time spent":"539.201427ms","remote":"127.0.0.1:38804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T19:45:07.787987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.403596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-04T19:45:07.788077Z","caller":"traceutil/trace.go:171","msg":"trace[1484404178] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:720; }","duration":"471.54327ms","start":"2026-02-04T19:45:07.316512Z","end":"2026-02-04T19:45:07.788055Z","steps":["trace[1484404178] 'agreement among raft nodes before linearized reading' (duration: 471.322304ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T19:45:07.788112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T19:45:07.316492Z","time spent":"471.613961ms","remote":"127.0.0.1:38804","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-04T19:45:07.788259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.971199ms","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-04T19:45:07.788275Z","caller":"traceutil/trace.go:171","msg":"trace[323665430] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:720; }","duration":"108.05394ms","start":"2026-02-04T19:45:07.680215Z","end":"2026-02-04T19:45:07.788269Z","steps":["trace[323665430] 'agreement among raft nodes before linearized reading' (duration: 108.002299ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T19:45:07.788292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.619401ms","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-04T19:45:07.788327Z","caller":"traceutil/trace.go:171","msg":"trace[1946041352] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:720; }","duration":"105.709803ms","start":"2026-02-04T19:45:07.682609Z","end":"2026-02-04T19:45:07.788318Z","steps":["trace[1946041352] 'agreement among raft nodes before linearized reading' (duration: 105.56451ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T19:45:07.788367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.045093ms","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-04T19:45:07.788389Z","caller":"traceutil/trace.go:171","msg":"trace[871054136] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:720; }","duration":"355.108065ms","start":"2026-02-04T19:45:07.433274Z","end":"2026-02-04T19:45:07.788382Z","steps":["trace[871054136] 'agreement among raft nodes before linearized reading' (duration: 355.050383ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T19:45:07.788409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T19:45:07.433252Z","time spent":"355.152766ms","remote":"127.0.0.1:38804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-04T19:45:07.788412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"508.362765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T19:45:07.788434Z","caller":"traceutil/trace.go:171","msg":"trace[1100821757] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:720; }","duration":"508.419947ms","start":"2026-02-04T19:45:07.280008Z","end":"2026-02-04T19:45:07.788428Z","steps":["trace[1100821757] 'agreement among raft nodes before linearized reading' (duration: 508.385886ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T19:45:07.788455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T19:45:07.279997Z","time spent":"508.450518ms","remote":"127.0.0.1:38596","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-04T19:45:08.319022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.706776ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-04T19:45:08.319137Z","caller":"traceutil/trace.go:171","msg":"trace[702854874] transaction","detail":"{read_only:false; response_revision:721; number_of_response:1; }","duration":"525.227797ms","start":"2026-02-04T19:45:07.79389Z","end":"2026-02-04T19:45:08.319117Z","steps":["trace[702854874] 'process raft request' (duration: 361.309556ms)","trace[702854874] 'compare' (duration: 163.491011ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T19:45:08.319205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T19:45:07.793868Z","time spent":"525.304909ms","remote":"127.0.0.1:38804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T19:45:08.328002Z","caller":"traceutil/trace.go:171","msg":"trace[1063694796] transaction","detail":"{read_only:false; response_revision:722; number_of_response:1; }","duration":"533.342844ms","start":"2026-02-04T19:45:07.79463Z","end":"2026-02-04T19:45:08.327973Z","steps":["trace[1063694796] 'process raft request' (duration: 532.929104ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T19:45:08.327899Z","caller":"traceutil/trace.go:171","msg":"trace[395317258] transaction","detail":"{read_only:false; response_revision:724; number_of_response:1; }","duration":"531.478549ms","start":"2026-02-04T19:45:07.7964Z","end":"2026-02-04T19:45:08.327879Z","steps":["trace[395317258] 'process raft request' (duration: 531.304755ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T19:45:08.32816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T19:45:07.796391Z","time spent":"531.726275ms","remote":"127.0.0.1:38804","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-02-04T19:45:08.328173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T19:45:07.794607Z","time spent":"533.470898ms","remote":"127.0.0.1:38804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T19:45:08.328295Z","caller":"traceutil/trace.go:171","msg":"trace[727726046] transaction","detail":"{read_only:false; response_revision:723; number_of_response:1; }","duration":"532.33492ms","start":"2026-02-04T19:45:07.795951Z","end":"2026-02-04T19:45:08.328286Z","steps":["trace[727726046] 'process raft request' (duration: 531.712265ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T19:45:08.32833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T19:45:07.795942Z","time spent":"532.36817ms","remote":"127.0.0.1:38804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T19:45:12.555438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.894166ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-04T19:45:12.555645Z","caller":"traceutil/trace.go:171","msg":"trace[547012476] transaction","detail":"{read_only:false; response_revision:736; number_of_response:1; }","duration":"175.918592ms","start":"2026-02-04T19:45:12.379707Z","end":"2026-02-04T19:45:12.555626Z","steps":["trace[547012476] 'process raft request' (duration: 175.884882ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T19:45:12.555898Z","caller":"traceutil/trace.go:171","msg":"trace[1386193302] transaction","detail":"{read_only:false; response_revision:734; number_of_response:1; }","duration":"177.135193ms","start":"2026-02-04T19:45:12.378754Z","end":"2026-02-04T19:45:12.555889Z","steps":["trace[1386193302] 'process raft request' (duration: 75.692783ms)","trace[1386193302] 'compare' (duration: 100.781724ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T19:45:12.555986Z","caller":"traceutil/trace.go:171","msg":"trace[676577233] transaction","detail":"{read_only:false; response_revision:735; number_of_response:1; }","duration":"176.63227ms","start":"2026-02-04T19:45:12.379347Z","end":"2026-02-04T19:45:12.555979Z","steps":["trace[676577233] 'process raft request' (duration: 176.21388ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T19:45:29.480937Z","caller":"traceutil/trace.go:171","msg":"trace[1421314703] transaction","detail":"{read_only:false; response_revision:789; number_of_response:1; }","duration":"109.59452ms","start":"2026-02-04T19:45:29.37131Z","end":"2026-02-04T19:45:29.480905Z","steps":["trace[1421314703] 'process raft request' (duration: 109.441096ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T19:45:32.65287Z","caller":"traceutil/trace.go:171","msg":"trace[997111236] transaction","detail":"{read_only:false; response_revision:795; number_of_response:1; }","duration":"185.914169ms","start":"2026-02-04T19:45:32.466938Z","end":"2026-02-04T19:45:32.652852Z","steps":["trace[997111236] 'process raft request' (duration: 185.487169ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T19:45:33.264098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.531338ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T19:45:33.264233Z","caller":"traceutil/trace.go:171","msg":"trace[2083644093] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:796; }","duration":"243.686661ms","start":"2026-02-04T19:45:33.020526Z","end":"2026-02-04T19:45:33.264212Z","steps":["trace[2083644093] 'range keys from in-memory index tree' (duration: 243.516337ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T19:45:33.267566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.674997ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-04T19:45:33.267629Z","caller":"traceutil/trace.go:171","msg":"trace[2123977139] transaction","detail":"{read_only:false; response_revision:797; number_of_response:1; }","duration":"574.412537ms","start":"2026-02-04T19:45:32.693205Z","end":"2026-02-04T19:45:33.267617Z","steps":["trace[2123977139] 'process raft request' (duration: 277.616077ms)","trace[2123977139] 'compare' (duration: 293.093489ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T19:45:33.267671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T19:45:32.69319Z","time spent":"574.460128ms","remote":"127.0.0.1:38804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T19:45:33.271064Z","caller":"traceutil/trace.go:171","msg":"trace[63739078] linearizableReadLoop","detail":"{readStateIndex:824; appliedIndex:822; }","duration":"329.588888ms","start":"2026-02-04T19:45:32.941457Z","end":"2026-02-04T19:45:33.271046Z","steps":["trace[63739078] 'read index received' (duration: 29.374773ms)","trace[63739078] 'applied index is now lower than readState.Index' (duration: 300.213235ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T19:45:33.271212Z","caller":"traceutil/trace.go:171","msg":"trace[255769763] transaction","detail":"{read_only:false; response_revision:798; number_of_response:1; }","duration":"577.019512ms","start":"2026-02-04T19:45:32.694185Z","end":"2026-02-04T19:45:33.271205Z","steps":["trace[255769763] 'process raft request' (duration: 576.739925ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T19:45:33.271294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T19:45:32.694176Z","time spent":"577.052872ms","remote":"127.0.0.1:38804","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-02-04T19:45:33.271419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.960337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T19:45:33.271438Z","caller":"traceutil/trace.go:171","msg":"trace[181733278] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:798; }","duration":"330.011718ms","start":"2026-02-04T19:45:32.94142Z","end":"2026-02-04T19:45:33.271432Z","steps":["trace[181733278] 'agreement among raft nodes before linearized reading' (duration: 329.979467ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T19:45:33.271455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T19:45:32.941393Z","time spent":"330.05707ms","remote":"127.0.0.1:38584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-04T19:45:33.271547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.457761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T19:45:33.27156Z","caller":"traceutil/trace.go:171","msg":"trace[1629449589] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:798; }","duration":"270.502932ms","start":"2026-02-04T19:45:33.001052Z","end":"2026-02-04T19:45:33.271555Z","steps":["trace[1629449589] 'agreement among raft nodes before linearized reading' (duration: 270.482472ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T19:45:33.798494Z","caller":"traceutil/trace.go:171","msg":"trace[484109912] linearizableReadLoop","detail":"{readStateIndex:825; appliedIndex:824; }","duration":"153.638834ms","start":"2026-02-04T19:45:33.644837Z","end":"2026-02-04T19:45:33.798476Z","steps":["trace[484109912] 'read index received' (duration: 153.450059ms)","trace[484109912] 'applied index is now lower than readState.Index' (duration: 188.255µs)"],"step_count":2} {"level":"warn","ts":"2026-02-04T19:45:33.799143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.740926ms","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-02-04T19:45:33.799162Z","caller":"traceutil/trace.go:171","msg":"trace[1008467405] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:799; }","duration":"154.362392ms","start":"2026-02-04T19:45:33.644792Z","end":"2026-02-04T19:45:33.799154Z","steps":["trace[1008467405] 'agreement among raft nodes before linearized reading' (duration: 153.758747ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T19:45:33.799351Z","caller":"traceutil/trace.go:171","msg":"trace[1109538068] transaction","detail":"{read_only:false; response_revision:799; number_of_response:1; }","duration":"239.98766ms","start":"2026-02-04T19:45:33.559357Z","end":"2026-02-04T19:45:33.799344Z","steps":["trace[1109538068] 'process raft request' (duration: 238.997066ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T19:45:36.887073Z","caller":"traceutil/trace.go:171","msg":"trace[1457542034] linearizableReadLoop","detail":"{readStateIndex:834; appliedIndex:833; }","duration":"187.19766ms","start":"2026-02-04T19:45:36.699852Z","end":"2026-02-04T19:45:36.88705Z","steps":["trace[1457542034] 'read index received' (duration: 186.939924ms)","trace[1457542034] 'applied index is now lower than readState.Index' (duration: 256.736µs)"],"step_count":2} {"level":"warn","ts":"2026-02-04T19:45:36.887312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.417286ms","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-04T19:45:36.887343Z","caller":"traceutil/trace.go:171","msg":"trace[1157063528] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:807; }","duration":"187.516718ms","start":"2026-02-04T19:45:36.699817Z","end":"2026-02-04T19:45:36.887334Z","steps":["trace[1157063528] 'agreement among raft nodes before linearized reading' (duration: 187.326554ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T19:45:36.887862Z","caller":"traceutil/trace.go:171","msg":"trace[1142373032] transaction","detail":"{read_only:false; response_revision:807; number_of_response:1; }","duration":"218.931922ms","start":"2026-02-04T19:45:36.668911Z","end":"2026-02-04T19:45:36.887842Z","steps":["trace[1142373032] 'process raft request' (duration: 217.964758ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T19:45:37.976303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"724.993206ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-04T19:45:37.978064Z","caller":"traceutil/trace.go:171","msg":"trace[1973195704] transaction","detail":"{read_only:false; response_revision:808; number_of_response:1; }","duration":"1.085189008s","start":"2026-02-04T19:45:36.892853Z","end":"2026-02-04T19:45:37.978042Z","steps":["trace[1973195704] 'process raft request' (duration: 358.384857ms)","trace[1973195704] 'compare' (duration: 724.574256ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T19:45:37.978209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T19:45:36.892836Z","time spent":"1.08530873s","remote":"127.0.0.1:38804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T19:45:37.978538Z","caller":"traceutil/trace.go:171","msg":"trace[49717637] linearizableReadLoop","detail":"{readStateIndex:835; appliedIndex:834; }","duration":"973.146629ms","start":"2026-02-04T19:45:37.003221Z","end":"2026-02-04T19:45:37.976367Z","steps":["trace[49717637] 'read index received' (duration: 247.940517ms)","trace[49717637] 'applied index is now lower than readState.Index' (duration: 725.205161ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T19:45:37.978615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"975.388284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T19:45:37.979388Z","caller":"traceutil/trace.go:171","msg":"trace[391656708] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:808; }","duration":"976.166653ms","start":"2026-02-04T19:45:37.003209Z","end":"2026-02-04T19:45:37.979376Z","steps":["trace[391656708] 'agreement among raft nodes before linearized reading' (duration: 975.357273ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T19:45:37.979442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T19:45:37.003142Z","time spent":"976.290316ms","remote":"127.0.0.1:38584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-04T19:45:37.979675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"689.451491ms","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-04T19:45:37.97978Z","caller":"traceutil/trace.go:171","msg":"trace[1573386963] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:808; }","duration":"689.597934ms","start":"2026-02-04T19:45:37.290174Z","end":"2026-02-04T19:45:37.979772Z","steps":["trace[1573386963] 'agreement among raft nodes before linearized reading' (duration: 689.41543ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T19:45:37.97982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T19:45:37.290149Z","time spent":"689.663376ms","remote":"127.0.0.1:38804","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-04T19:45:37.980192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.199097ms","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-04T19:45:37.980246Z","caller":"traceutil/trace.go:171","msg":"trace[1531793706] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:808; }","duration":"143.30194ms","start":"2026-02-04T19:45:37.836937Z","end":"2026-02-04T19:45:37.980239Z","steps":["trace[1531793706] 'agreement among raft nodes before linearized reading' (duration: 143.199767ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T19:45:37.980426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.067842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-04T19:45:37.980474Z","caller":"traceutil/trace.go:171","msg":"trace[513140506] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:808; }","duration":"229.159684ms","start":"2026-02-04T19:45:37.751308Z","end":"2026-02-04T19:45:37.980468Z","steps":["trace[513140506] 'agreement among raft nodes before linearized reading' (duration: 229.084903ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T19:45:37.980692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"690.108517ms","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-04T19:45:37.980742Z","caller":"traceutil/trace.go:171","msg":"trace[433381970] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:808; }","duration":"690.206219ms","start":"2026-02-04T19:45:37.290528Z","end":"2026-02-04T19:45:37.980735Z","steps":["trace[433381970] 'agreement among raft nodes before linearized reading' (duration: 690.117777ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T19:45:37.98082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T19:45:37.290504Z","time spent":"690.305472ms","remote":"127.0.0.1:38804","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-02-04T19:45:49.227861Z","caller":"traceutil/trace.go:171","msg":"trace[1675803843] transaction","detail":"{read_only:false; response_revision:892; number_of_response:1; }","duration":"236.471274ms","start":"2026-02-04T19:45:48.991371Z","end":"2026-02-04T19:45:49.227842Z","steps":["trace[1675803843] 'process raft request' (duration: 236.350161ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T19:45:49.228336Z","caller":"traceutil/trace.go:171","msg":"trace[1846090376] linearizableReadLoop","detail":"{readStateIndex:923; appliedIndex:923; }","duration":"229.161714ms","start":"2026-02-04T19:45:48.999164Z","end":"2026-02-04T19:45:49.228325Z","steps":["trace[1846090376] 'read index received' (duration: 229.151144ms)","trace[1846090376] 'applied index is now lower than readState.Index' (duration: 9.56µs)"],"step_count":2} {"level":"warn","ts":"2026-02-04T19:45:49.228417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.239466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T19:45:49.228445Z","caller":"traceutil/trace.go:171","msg":"trace[933772810] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:892; }","duration":"229.291408ms","start":"2026-02-04T19:45:48.999145Z","end":"2026-02-04T19:45:49.228436Z","steps":["trace[933772810] 'agreement among raft nodes before linearized reading' (duration: 229.221206ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T19:45:50.648566Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1673540441536264077,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-04T19:45:51.429704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"566.207905ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-04T19:45:51.429798Z","caller":"traceutil/trace.go:171","msg":"trace[21366013] linearizableReadLoop","detail":"{readStateIndex:926; appliedIndex:925; }","duration":"1.282112547s","start":"2026-02-04T19:45:50.147669Z","end":"2026-02-04T19:45:51.429781Z","steps":["trace[21366013] 'read index received' (duration: 715.685836ms)","trace[21366013] 'applied index is now lower than readState.Index' (duration: 566.425921ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T19:45:51.429942Z","caller":"traceutil/trace.go:171","msg":"trace[928118481] transaction","detail":"{read_only:false; response_revision:894; number_of_response:1; }","duration":"1.310767532s","start":"2026-02-04T19:45:50.119167Z","end":"2026-02-04T19:45:51.429934Z","steps":["trace[928118481] 'process raft request' (duration: 744.252579ms)","trace[928118481] 'compare' (duration: 566.042111ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T19:45:51.429978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T19:45:50.119119Z","time spent":"1.310839305s","remote":"127.0.0.1:38804","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-02-04T19:45:51.430165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.282497197s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-04T19:45:51.430192Z","caller":"traceutil/trace.go:171","msg":"trace[1913904221] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:894; }","duration":"1.282522967s","start":"2026-02-04T19:45:50.147662Z","end":"2026-02-04T19:45:51.430185Z","steps":["trace[1913904221] 'agreement among raft nodes before linearized reading' (duration: 1.282425985s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T19:45:51.430207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T19:45:50.147617Z","time spent":"1.282586649s","remote":"127.0.0.1:38804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-04T19:45:51.430295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.282525767s","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-04T19:45:51.430307Z","caller":"traceutil/trace.go:171","msg":"trace[1970337552] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:894; }","duration":"1.282581378s","start":"2026-02-04T19:45:50.147722Z","end":"2026-02-04T19:45:51.430303Z","steps":["trace[1970337552] 'agreement among raft nodes before linearized reading' (duration: 1.282542407s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T19:45:51.430324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T19:45:50.147688Z","time spent":"1.28263353s","remote":"127.0.0.1:38804","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-02-04T19:45:51.432198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.939015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-04T19:45:51.432218Z","caller":"traceutil/trace.go:171","msg":"trace[210108432] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:894; }","duration":"200.002496ms","start":"2026-02-04T19:45:51.23221Z","end":"2026-02-04T19:45:51.432213Z","steps":["trace[210108432] 'agreement among raft nodes before linearized reading' (duration: 199.935004ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T19:45:51.432299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.024301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T19:45:51.432314Z","caller":"traceutil/trace.go:171","msg":"trace[897008459] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:894; }","duration":"430.072512ms","start":"2026-02-04T19:45:51.002234Z","end":"2026-02-04T19:45:51.432307Z","steps":["trace[897008459] 'agreement among raft nodes before linearized reading' (duration: 430.046432ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T19:45:51.432341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T19:45:51.002217Z","time spent":"430.120873ms","remote":"127.0.0.1:38584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-04T19:45:52.087042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.468164ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-04T19:45:52.087142Z","caller":"traceutil/trace.go:171","msg":"trace[1094260240] linearizableReadLoop","detail":"{readStateIndex:927; appliedIndex:926; }","duration":"638.911756ms","start":"2026-02-04T19:45:51.448213Z","end":"2026-02-04T19:45:52.087125Z","steps":["trace[1094260240] 'read index received' (duration: 350.271437ms)","trace[1094260240] 'applied index is now lower than readState.Index' (duration: 288.639589ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T19:45:52.087227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"639.010588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T19:45:52.087241Z","caller":"traceutil/trace.go:171","msg":"trace[177967387] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:895; }","duration":"639.039529ms","start":"2026-02-04T19:45:51.448195Z","end":"2026-02-04T19:45:52.087235Z","steps":["trace[177967387] 'agreement among raft nodes before linearized reading' (duration: 638.966627ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T19:45:52.087264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T19:45:51.448189Z","time spent":"639.069959ms","remote":"127.0.0.1:38596","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-04T19:45:52.087513Z","caller":"traceutil/trace.go:171","msg":"trace[137071780] transaction","detail":"{read_only:false; response_revision:895; number_of_response:1; }","duration":"639.469049ms","start":"2026-02-04T19:45:51.448034Z","end":"2026-02-04T19:45:52.087503Z","steps":["trace[137071780] 'process raft request' (duration: 350.439621ms)","trace[137071780] 'compare' (duration: 288.26871ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T19:45:52.087548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T19:45:51.448015Z","time spent":"639.514391ms","remote":"127.0.0.1:38804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T19:45:52.313335Z","caller":"traceutil/trace.go:171","msg":"trace[332957750] linearizableReadLoop","detail":"{readStateIndex:929; appliedIndex:927; }","duration":"210.800932ms","start":"2026-02-04T19:45:52.102509Z","end":"2026-02-04T19:45:52.31331Z","steps":["trace[332957750] 'read index received' (duration: 144.571531ms)","trace[332957750] 'applied index is now lower than readState.Index' (duration: 66.227951ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T19:45:52.31348Z","caller":"traceutil/trace.go:171","msg":"trace[1963884744] transaction","detail":"{read_only:false; response_revision:897; number_of_response:1; }","duration":"860.133515ms","start":"2026-02-04T19:45:51.453333Z","end":"2026-02-04T19:45:52.313466Z","steps":["trace[1963884744] 'process raft request' (duration: 859.922179ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T19:45:52.313472Z","caller":"traceutil/trace.go:171","msg":"trace[278449259] transaction","detail":"{read_only:false; response_revision:896; number_of_response:1; }","duration":"865.129057ms","start":"2026-02-04T19:45:51.448287Z","end":"2026-02-04T19:45:52.313416Z","steps":["trace[278449259] 'process raft request' (duration: 798.818384ms)","trace[278449259] 'compare' (duration: 65.965895ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T19:45:52.313601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T19:45:51.453324Z","time spent":"860.186556ms","remote":"127.0.0.1:38804","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-02-04T19:45:52.313661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T19:45:51.448282Z","time spent":"865.271311ms","remote":"127.0.0.1:38804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T19:45:52.314048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.101031ms","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-04T19:45:52.314079Z","caller":"traceutil/trace.go:171","msg":"trace[2029551127] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:897; }","duration":"194.151482ms","start":"2026-02-04T19:45:52.119918Z","end":"2026-02-04T19:45:52.314069Z","steps":["trace[2029551127] 'agreement among raft nodes before linearized reading' (duration: 194.017748ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T19:45:52.314194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.692283ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T19:45:52.314242Z","caller":"traceutil/trace.go:171","msg":"trace[722499662] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:897; }","duration":"211.763155ms","start":"2026-02-04T19:45:52.102463Z","end":"2026-02-04T19:45:52.314226Z","steps":["trace[722499662] 'agreement among raft nodes before linearized reading' (duration: 211.694934ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T19:45:53.650029Z","caller":"traceutil/trace.go:171","msg":"trace[1903362765] transaction","detail":"{read_only:false; response_revision:899; number_of_response:1; }","duration":"186.898394ms","start":"2026-02-04T19:45:53.463101Z","end":"2026-02-04T19:45:53.649999Z","steps":["trace[1903362765] 'process raft request' (duration: 186.717019ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T19:45:55.942675Z","caller":"traceutil/trace.go:171","msg":"trace[1986774312] transaction","detail":"{read_only:false; response_revision:906; number_of_response:1; }","duration":"282.796105ms","start":"2026-02-04T19:45:55.659854Z","end":"2026-02-04T19:45:55.94265Z","steps":["trace[1986774312] 'process raft request' (duration: 282.670712ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T19:46:02.500268Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1673540441536264158,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-04T19:46:02.570733Z","caller":"traceutil/trace.go:171","msg":"trace[1262132153] linearizableReadLoop","detail":"{readStateIndex:956; appliedIndex:955; }","duration":"570.624314ms","start":"2026-02-04T19:46:02.000074Z","end":"2026-02-04T19:46:02.570698Z","steps":["trace[1262132153] 'read index received' (duration: 570.380678ms)","trace[1262132153] 'applied index is now lower than readState.Index' (duration: 242.856µs)"],"step_count":2} {"level":"warn","ts":"2026-02-04T19:46:02.571227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.217463ms","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-04T19:46:02.571259Z","caller":"traceutil/trace.go:171","msg":"trace[293748744] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:922; }","duration":"170.284954ms","start":"2026-02-04T19:46:02.400967Z","end":"2026-02-04T19:46:02.571252Z","steps":["trace[293748744] 'agreement among raft nodes before linearized reading' (duration: 170.168572ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T19:46:02.571359Z","caller":"traceutil/trace.go:171","msg":"trace[1488635080] transaction","detail":"{read_only:false; response_revision:922; number_of_response:1; }","duration":"582.371223ms","start":"2026-02-04T19:46:01.988981Z","end":"2026-02-04T19:46:02.571353Z","steps":["trace[1488635080] 'process raft request' (duration: 581.554693ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T19:46:02.571425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T19:46:01.988965Z","time spent":"582.412285ms","remote":"127.0.0.1:38804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T19:46:02.571486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"571.412793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T19:46:02.571502Z","caller":"traceutil/trace.go:171","msg":"trace[1253006031] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:922; }","duration":"571.444824ms","start":"2026-02-04T19:46:02.000053Z","end":"2026-02-04T19:46:02.571498Z","steps":["trace[1253006031] 'agreement among raft nodes before linearized reading' (duration: 571.419834ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T19:46:02.571519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T19:46:02.000045Z","time spent":"571.470275ms","remote":"127.0.0.1:38584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-04T19:46:02.571745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.083393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-04T19:46:02.571767Z","caller":"traceutil/trace.go:171","msg":"trace[357015324] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:922; }","duration":"199.149815ms","start":"2026-02-04T19:46:02.372612Z","end":"2026-02-04T19:46:02.571762Z","steps":["trace[357015324] 'agreement among raft nodes before linearized reading' (duration: 199.096904ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T19:46:02.571845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.048692ms","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-04T19:46:02.571862Z","caller":"traceutil/trace.go:171","msg":"trace[1566608735] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:922; }","duration":"186.101623ms","start":"2026-02-04T19:46:02.385755Z","end":"2026-02-04T19:46:02.571857Z","steps":["trace[1566608735] 'agreement among raft nodes before linearized reading' (duration: 186.052552ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T19:46:02.571932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.582242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-04T19:46:02.571948Z","caller":"traceutil/trace.go:171","msg":"trace[1581384819] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:922; }","duration":"436.598823ms","start":"2026-02-04T19:46:02.135345Z","end":"2026-02-04T19:46:02.571944Z","steps":["trace[1581384819] 'agreement among raft nodes before linearized reading' (duration: 436.554832ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T19:46:02.571966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T19:46:02.135282Z","time spent":"436.679825ms","remote":"127.0.0.1:38804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-04T19:46:02.572487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"534.558166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-04T19:46:02.572519Z","caller":"traceutil/trace.go:171","msg":"trace[1062982933] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:922; }","duration":"536.024272ms","start":"2026-02-04T19:46:02.036486Z","end":"2026-02-04T19:46:02.572511Z","steps":["trace[1062982933] 'agreement among raft nodes before linearized reading' (duration: 534.574657ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T19:46:02.572554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T19:46:02.036461Z","time spent":"536.086043ms","remote":"127.0.0.1:38722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":31,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true "} {"level":"info","ts":"2026-02-04T19:46:02.839697Z","caller":"traceutil/trace.go:171","msg":"trace[34724482] transaction","detail":"{read_only:false; response_revision:926; number_of_response:1; }","duration":"256.071007ms","start":"2026-02-04T19:46:02.58361Z","end":"2026-02-04T19:46:02.839681Z","steps":["trace[34724482] 'process raft request' (duration: 256.048306ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T19:46:02.839938Z","caller":"traceutil/trace.go:171","msg":"trace[1093098822] transaction","detail":"{read_only:false; response_revision:923; number_of_response:1; }","duration":"262.951007ms","start":"2026-02-04T19:46:02.576981Z","end":"2026-02-04T19:46:02.839932Z","steps":["trace[1093098822] 'process raft request' (duration: 260.734472ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T19:46:02.840014Z","caller":"traceutil/trace.go:171","msg":"trace[652387499] transaction","detail":"{read_only:false; response_revision:924; number_of_response:1; }","duration":"256.558359ms","start":"2026-02-04T19:46:02.583449Z","end":"2026-02-04T19:46:02.840007Z","steps":["trace[652387499] 'process raft request' (duration: 256.165389ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T19:46:02.840076Z","caller":"traceutil/trace.go:171","msg":"trace[223831981] transaction","detail":"{read_only:false; response_revision:925; number_of_response:1; }","duration":"256.554789ms","start":"2026-02-04T19:46:02.583514Z","end":"2026-02-04T19:46:02.840069Z","steps":["trace[223831981] 'process raft request' (duration: 256.125439ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T19:46:03.356685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.660032ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T19:46:03.35677Z","caller":"traceutil/trace.go:171","msg":"trace[218910495] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:926; }","duration":"336.757174ms","start":"2026-02-04T19:46:03.019999Z","end":"2026-02-04T19:46:03.356756Z","steps":["trace[218910495] 'range keys from in-memory index tree' (duration: 336.646951ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T19:46:04.646458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.630596ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-04T19:46:04.646668Z","caller":"traceutil/trace.go:171","msg":"trace[802424925] transaction","detail":"{read_only:false; response_revision:927; number_of_response:1; }","duration":"204.392124ms","start":"2026-02-04T19:46:04.442214Z","end":"2026-02-04T19:46:04.646606Z","steps":["trace[802424925] 'process raft request' (duration: 28.541163ms)","trace[802424925] 'compare' (duration: 175.486972ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T19:46:07.36099Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1673540441536264192,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-04T19:46:07.8614Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1673540441536264192,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-04T19:46:07.907336Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.231862679s","expected-duration":"1s"} {"level":"info","ts":"2026-02-04T19:46:07.907699Z","caller":"traceutil/trace.go:171","msg":"trace[915301628] linearizableReadLoop","detail":"{readStateIndex:969; appliedIndex:968; }","duration":"1.047608361s","start":"2026-02-04T19:46:06.860055Z","end":"2026-02-04T19:46:07.907663Z","steps":["trace[915301628] 'read index received' (duration: 1.047385806s)","trace[915301628] 'applied index is now lower than readState.Index' (duration: 221.435µs)"],"step_count":2} {"level":"info","ts":"2026-02-04T19:46:07.907834Z","caller":"traceutil/trace.go:171","msg":"trace[1409668158] transaction","detail":"{read_only:false; response_revision:934; number_of_response:1; }","duration":"1.232408362s","start":"2026-02-04T19:46:06.675418Z","end":"2026-02-04T19:46:07.907826Z","steps":["trace[1409668158] 'process raft request' (duration: 1.232060064s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T19:46:07.907944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T19:46:06.675407Z","time spent":"1.232445734s","remote":"127.0.0.1:38804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T19:46:07.907975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.044245728s","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-04T19:46:07.908011Z","caller":"traceutil/trace.go:171","msg":"trace[307486682] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:934; }","duration":"1.044301009s","start":"2026-02-04T19:46:06.863694Z","end":"2026-02-04T19:46:07.907995Z","steps":["trace[307486682] 'agreement among raft nodes before linearized reading' (duration: 1.044181986s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T19:46:07.908038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T19:46:06.863689Z","time spent":"1.04434397s","remote":"127.0.0.1:38804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-04T19:46:07.908143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.048089403s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-04T19:46:07.908156Z","caller":"traceutil/trace.go:171","msg":"trace[427516398] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:934; }","duration":"1.048126204s","start":"2026-02-04T19:46:06.860025Z","end":"2026-02-04T19:46:07.908151Z","steps":["trace[427516398] 'agreement among raft nodes before linearized reading' (duration: 1.048086733s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T19:46:07.90817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T19:46:06.860009Z","time spent":"1.048158075s","remote":"127.0.0.1:38804","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-04T19:46:07.908302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.029728701s","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-04T19:46:07.908329Z","caller":"traceutil/trace.go:171","msg":"trace[581562270] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:934; }","duration":"1.029765231s","start":"2026-02-04T19:46:06.878559Z","end":"2026-02-04T19:46:07.908324Z","steps":["trace[581562270] 'agreement among raft nodes before linearized reading' (duration: 1.02970759s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T19:46:07.908342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T19:46:06.878553Z","time spent":"1.029785152s","remote":"127.0.0.1:38804","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-04T19:46:07.908439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.042835303s","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-04T19:46:07.908468Z","caller":"traceutil/trace.go:171","msg":"trace[1434621661] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:934; }","duration":"1.042872985s","start":"2026-02-04T19:46:06.865587Z","end":"2026-02-04T19:46:07.90846Z","steps":["trace[1434621661] 'agreement among raft nodes before linearized reading' (duration: 1.042745962s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T19:46:07.908489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T19:46:06.865582Z","time spent":"1.042903215s","remote":"127.0.0.1:38804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-04T19:46:08.378494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.597623ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-04T19:46:08.378748Z","caller":"traceutil/trace.go:171","msg":"trace[844857661] linearizableReadLoop","detail":"{readStateIndex:973; appliedIndex:969; }","duration":"377.517738ms","start":"2026-02-04T19:46:08.001212Z","end":"2026-02-04T19:46:08.37873Z","steps":["trace[844857661] 'read index received' (duration: 61.69155ms)","trace[844857661] 'applied index is now lower than readState.Index' (duration: 315.825168ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T19:46:08.37903Z","caller":"traceutil/trace.go:171","msg":"trace[1354473261] transaction","detail":"{read_only:false; response_revision:935; number_of_response:1; }","duration":"465.826993ms","start":"2026-02-04T19:46:07.91319Z","end":"2026-02-04T19:46:08.379017Z","steps":["trace[1354473261] 'process raft request' (duration: 149.605935ms)","trace[1354473261] 'compare' (duration: 315.445229ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T19:46:08.379105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T19:46:07.91317Z","time spent":"465.898175ms","remote":"127.0.0.1:38804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T19:46:08.380566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.360803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T19:46:08.380599Z","caller":"traceutil/trace.go:171","msg":"trace[1765578764] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:938; }","duration":"379.427665ms","start":"2026-02-04T19:46:08.001163Z","end":"2026-02-04T19:46:08.380591Z","steps":["trace[1765578764] 'agreement among raft nodes before linearized reading' (duration: 379.370664ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T19:46:08.380627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T19:46:08.001145Z","time spent":"379.476907ms","remote":"127.0.0.1:38584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-04T19:46:08.380841Z","caller":"traceutil/trace.go:171","msg":"trace[1834711852] transaction","detail":"{read_only:false; response_revision:936; number_of_response:1; }","duration":"467.528765ms","start":"2026-02-04T19:46:07.913304Z","end":"2026-02-04T19:46:08.380832Z","steps":["trace[1834711852] 'process raft request' (duration: 465.28421ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T19:46:08.380893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T19:46:07.913299Z","time spent":"467.568276ms","remote":"127.0.0.1:38804","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-02-04T19:46:08.381064Z","caller":"traceutil/trace.go:171","msg":"trace[2121928102] transaction","detail":"{read_only:false; response_revision:937; number_of_response:1; }","duration":"465.910114ms","start":"2026-02-04T19:46:07.915146Z","end":"2026-02-04T19:46:08.381056Z","steps":["trace[2121928102] 'process raft request' (duration: 463.515796ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T19:46:08.381113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T19:46:07.915137Z","time spent":"465.952226ms","remote":"127.0.0.1:38804","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-02-04T19:46:08.381256Z","caller":"traceutil/trace.go:171","msg":"trace[1027432174] transaction","detail":"{read_only:false; response_revision:938; number_of_response:1; }","duration":"465.191857ms","start":"2026-02-04T19:46:07.916055Z","end":"2026-02-04T19:46:08.381247Z","steps":["trace[1027432174] 'process raft request' (duration: 462.640504ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T19:46:08.381299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T19:46:07.916047Z","time spent":"465.232488ms","remote":"127.0.0.1:38804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T19:46:08.382279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.147475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T19:46:08.38231Z","caller":"traceutil/trace.go:171","msg":"trace[979582561] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:938; }","duration":"251.228667ms","start":"2026-02-04T19:46:08.131075Z","end":"2026-02-04T19:46:08.382304Z","steps":["trace[979582561] 'agreement among raft nodes before linearized reading' (duration: 251.182396ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T19:46:10.102795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.348409ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-04T19:46:10.102933Z","caller":"traceutil/trace.go:171","msg":"trace[369457596] linearizableReadLoop","detail":"{readStateIndex:975; appliedIndex:974; }","duration":"179.526702ms","start":"2026-02-04T19:46:09.923372Z","end":"2026-02-04T19:46:10.102898Z","steps":["trace[369457596] 'read index received' (duration: 25.701µs)","trace[369457596] 'applied index is now lower than readState.Index' (duration: 179.49895ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T19:46:10.103506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.124756ms","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-04T19:46:10.103542Z","caller":"traceutil/trace.go:171","msg":"trace[903201752] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:939; }","duration":"180.217238ms","start":"2026-02-04T19:46:09.923313Z","end":"2026-02-04T19:46:10.10353Z","steps":["trace[903201752] 'agreement among raft nodes before linearized reading' (duration: 179.996513ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T19:46:10.614307Z","caller":"traceutil/trace.go:171","msg":"trace[863841636] transaction","detail":"{read_only:false; response_revision:941; number_of_response:1; }","duration":"213.635332ms","start":"2026-02-04T19:46:10.400647Z","end":"2026-02-04T19:46:10.614283Z","steps":["trace[863841636] 'process raft request' (duration: 213.476468ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T19:46:10.945303Z","caller":"traceutil/trace.go:171","msg":"trace[1010354808] linearizableReadLoop","detail":"{readStateIndex:978; appliedIndex:977; }","duration":"543.154717ms","start":"2026-02-04T19:46:10.402114Z","end":"2026-02-04T19:46:10.945269Z","steps":["trace[1010354808] 'read index received' (duration: 212.423922ms)","trace[1010354808] 'applied index is now lower than readState.Index' (duration: 330.729315ms)"],"step_count":2} {"level":"info","ts":"2026-02-04T19:46:10.945621Z","caller":"traceutil/trace.go:171","msg":"trace[789841706] transaction","detail":"{read_only:false; response_revision:942; number_of_response:1; }","duration":"544.660004ms","start":"2026-02-04T19:46:10.400944Z","end":"2026-02-04T19:46:10.945604Z","steps":["trace[789841706] 'process raft request' (duration: 448.719992ms)","trace[789841706] 'compare' (duration: 95.066171ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T19:46:10.94575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T19:46:10.400936Z","time spent":"544.718475ms","remote":"127.0.0.1:38804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T19:46:10.946107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"543.982997ms","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-04T19:46:10.946168Z","caller":"traceutil/trace.go:171","msg":"trace[99262797] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:942; }","duration":"544.066989ms","start":"2026-02-04T19:46:10.402089Z","end":"2026-02-04T19:46:10.946156Z","steps":["trace[99262797] 'agreement among raft nodes before linearized reading' (duration: 543.890764ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T19:46:10.946199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T19:46:10.402078Z","time spent":"544.114371ms","remote":"127.0.0.1:38804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-04T19:46:10.946664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"543.447114ms","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-04T19:46:10.94673Z","caller":"traceutil/trace.go:171","msg":"trace[905630799] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:942; }","duration":"543.536116ms","start":"2026-02-04T19:46:10.403184Z","end":"2026-02-04T19:46:10.94672Z","steps":["trace[905630799] 'agreement among raft nodes before linearized reading' (duration: 543.404332ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T19:46:10.946761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T19:46:10.403173Z","time spent":"543.580977ms","remote":"127.0.0.1:38804","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-04T19:46:11.44093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.335291ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-04T19:46:11.441102Z","caller":"traceutil/trace.go:171","msg":"trace[1245434989] linearizableReadLoop","detail":"{readStateIndex:980; appliedIndex:978; }","duration":"329.778712ms","start":"2026-02-04T19:46:11.111305Z","end":"2026-02-04T19:46:11.441083Z","steps":["trace[1245434989] 'read index received' (duration: 323.809585ms)","trace[1245434989] 'applied index is now lower than readState.Index' (duration: 5.968127ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T19:46:11.441205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.894525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-04T19:46:11.441226Z","caller":"traceutil/trace.go:171","msg":"trace[1971839588] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:944; }","duration":"329.942096ms","start":"2026-02-04T19:46:11.111275Z","end":"2026-02-04T19:46:11.441217Z","steps":["trace[1971839588] 'agreement among raft nodes before linearized reading' (duration: 329.854574ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T19:46:11.441255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T19:46:11.111254Z","time spent":"329.993787ms","remote":"127.0.0.1:38738","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":4,"response size":31,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true "} {"level":"info","ts":"2026-02-04T19:46:11.441532Z","caller":"traceutil/trace.go:171","msg":"trace[82587637] transaction","detail":"{read_only:false; response_revision:943; number_of_response:1; }","duration":"484.969843ms","start":"2026-02-04T19:46:10.956551Z","end":"2026-02-04T19:46:11.441521Z","steps":["trace[82587637] 'process raft request' (duration: 139.917785ms)","trace[82587637] 'compare' (duration: 344.201927ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T19:46:11.441574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T19:46:10.956528Z","time spent":"485.022555ms","remote":"127.0.0.1:38804","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-02-04T19:46:11.441663Z","caller":"traceutil/trace.go:171","msg":"trace[350045494] transaction","detail":"{read_only:false; response_revision:944; number_of_response:1; }","duration":"484.973704ms","start":"2026-02-04T19:46:10.956681Z","end":"2026-02-04T19:46:11.441655Z","steps":["trace[350045494] 'process raft request' (duration: 484.364718ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T19:46:11.4417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T19:46:10.956674Z","time spent":"485.002014ms","remote":"127.0.0.1:38804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T19:46:12.88955Z","caller":"traceutil/trace.go:171","msg":"trace[180046220] transaction","detail":"{read_only:false; response_revision:946; number_of_response:1; }","duration":"268.921063ms","start":"2026-02-04T19:46:12.620603Z","end":"2026-02-04T19:46:12.889524Z","steps":["trace[180046220] 'process raft request' (duration: 268.725058ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T19:46:21.035608Z","caller":"traceutil/trace.go:171","msg":"trace[1693872192] transaction","detail":"{read_only:false; response_revision:1004; number_of_response:1; }","duration":"111.488556ms","start":"2026-02-04T19:46:20.924094Z","end":"2026-02-04T19:46:21.035583Z","steps":["trace[1693872192] 'process raft request' (duration: 111.27815ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T19:46:21.205715Z","caller":"traceutil/trace.go:171","msg":"trace[190317049] transaction","detail":"{read_only:false; response_revision:1005; number_of_response:1; }","duration":"162.466931ms","start":"2026-02-04T19:46:21.043196Z","end":"2026-02-04T19:46:21.205663Z","steps":["trace[190317049] 'process raft request' (duration: 146.252692ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T19:46:24.586764Z","caller":"traceutil/trace.go:171","msg":"trace[709164824] transaction","detail":"{read_only:false; response_revision:1021; number_of_response:1; }","duration":"200.946898ms","start":"2026-02-04T19:46:24.385782Z","end":"2026-02-04T19:46:24.586729Z","steps":["trace[709164824] 'process raft request' (duration: 200.682301ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T19:46:24.589703Z","caller":"traceutil/trace.go:171","msg":"trace[1018930207] linearizableReadLoop","detail":"{readStateIndex:1060; appliedIndex:1059; }","duration":"177.150506ms","start":"2026-02-04T19:46:24.41252Z","end":"2026-02-04T19:46:24.589671Z","steps":["trace[1018930207] 'read index received' (duration: 174.972144ms)","trace[1018930207] 'applied index is now lower than readState.Index' (duration: 2.177412ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T19:46:24.590094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.404002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.195\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-04T19:46:24.590175Z","caller":"traceutil/trace.go:171","msg":"trace[1676738247] range","detail":"{range_begin:/registry/masterleases/162.253.55.195; range_end:; response_count:1; response_revision:1022; }","duration":"177.674298ms","start":"2026-02-04T19:46:24.412487Z","end":"2026-02-04T19:46:24.590161Z","steps":["trace[1676738247] 'agreement among raft nodes before linearized reading' (duration: 177.273528ms)"],"step_count":1} {"level":"info","ts":"2026-02-04T19:46:24.590906Z","caller":"traceutil/trace.go:171","msg":"trace[650655569] transaction","detail":"{read_only:false; response_revision:1022; number_of_response:1; }","duration":"203.743894ms","start":"2026-02-04T19:46:24.387146Z","end":"2026-02-04T19:46:24.59089Z","steps":["trace[650655569] 'process raft request' (duration: 202.447693ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T19:46:29.567616Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1673540441536264442,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-04T19:46:30.071443Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1673540441536264442,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-04T19:46:30.212322Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.20321888s","expected-duration":"1s"} {"level":"info","ts":"2026-02-04T19:46:30.213968Z","caller":"traceutil/trace.go:171","msg":"trace[866851785] transaction","detail":"{read_only:false; response_revision:1057; number_of_response:1; }","duration":"1.20490633s","start":"2026-02-04T19:46:29.009038Z","end":"2026-02-04T19:46:30.213945Z","steps":["trace[866851785] 'process raft request' (duration: 1.204295876s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T19:46:30.21432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T19:46:29.009027Z","time spent":"1.205170337s","remote":"127.0.0.1:38804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-04T19:46:30.467637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.823958ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-04T19:46:30.469464Z","caller":"traceutil/trace.go:171","msg":"trace[477492707] transaction","detail":"{read_only:false; response_revision:1058; number_of_response:1; }","duration":"768.078737ms","start":"2026-02-04T19:46:29.701351Z","end":"2026-02-04T19:46:30.469429Z","steps":["trace[477492707] 'process raft request' (duration: 617.280561ms)","trace[477492707] 'compare' (duration: 148.698025ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T19:46:30.469569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T19:46:29.70133Z","time spent":"768.190929ms","remote":"127.0.0.1:38804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-04T19:46:30.531845Z","caller":"traceutil/trace.go:171","msg":"trace[1505494399] linearizableReadLoop","detail":"{readStateIndex:1098; appliedIndex:1096; }","duration":"1.46500955s","start":"2026-02-04T19:46:29.066801Z","end":"2026-02-04T19:46:30.53181Z","steps":["trace[1505494399] 'read index received' (duration: 1.151376021s)","trace[1505494399] 'applied index is now lower than readState.Index' (duration: 313.631629ms)"],"step_count":2} {"level":"warn","ts":"2026-02-04T19:46:30.532193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.465361859s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-04T19:46:30.532231Z","caller":"traceutil/trace.go:171","msg":"trace[2092892776] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1058; }","duration":"1.465488812s","start":"2026-02-04T19:46:29.06673Z","end":"2026-02-04T19:46:30.532219Z","steps":["trace[2092892776] 'agreement among raft nodes before linearized reading' (duration: 1.465267527s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T19:46:30.532271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T19:46:29.066704Z","time spent":"1.465557574s","remote":"127.0.0.1:38804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-04T19:46:30.532304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.456672751s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T19:46:30.532392Z","caller":"traceutil/trace.go:171","msg":"trace[480074054] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1058; }","duration":"1.456849026s","start":"2026-02-04T19:46:29.075519Z","end":"2026-02-04T19:46:30.532368Z","steps":["trace[480074054] 'agreement among raft nodes before linearized reading' (duration: 1.456653441s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T19:46:30.53244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T19:46:29.075508Z","time spent":"1.456923217s","remote":"127.0.0.1:38584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-04T19:46:30.533069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"939.327611ms","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:510"} {"level":"info","ts":"2026-02-04T19:46:30.533175Z","caller":"traceutil/trace.go:171","msg":"trace[1103987643] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1058; }","duration":"939.467865ms","start":"2026-02-04T19:46:29.593668Z","end":"2026-02-04T19:46:30.533136Z","steps":["trace[1103987643] 'agreement among raft nodes before linearized reading' (duration: 939.200469ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T19:46:30.533244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T19:46:29.593644Z","time spent":"939.589517ms","remote":"127.0.0.1:38804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":534,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-04T19:46:30.533413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"977.932654ms","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-04T19:46:30.533459Z","caller":"traceutil/trace.go:171","msg":"trace[1246507869] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1058; }","duration":"977.981585ms","start":"2026-02-04T19:46:29.555465Z","end":"2026-02-04T19:46:30.533447Z","steps":["trace[1246507869] 'agreement among raft nodes before linearized reading' (duration: 977.819762ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T19:46:30.533491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T19:46:29.555445Z","time spent":"978.038927ms","remote":"127.0.0.1:38804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-04T19:46:30.533519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.480008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T19:46:30.533595Z","caller":"traceutil/trace.go:171","msg":"trace[674050550] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:1058; }","duration":"408.600981ms","start":"2026-02-04T19:46:30.124982Z","end":"2026-02-04T19:46:30.533583Z","steps":["trace[674050550] 'agreement among raft nodes before linearized reading' (duration: 408.455397ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T19:46:30.533633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T19:46:30.12496Z","time spent":"408.662982ms","remote":"127.0.0.1:36408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true "} {"level":"warn","ts":"2026-02-04T19:46:30.533997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"531.224532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-04T19:46:30.534051Z","caller":"traceutil/trace.go:171","msg":"trace[142426908] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1058; }","duration":"531.323455ms","start":"2026-02-04T19:46:30.002705Z","end":"2026-02-04T19:46:30.534028Z","steps":["trace[142426908] 'agreement among raft nodes before linearized reading' (duration: 531.185202ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T19:46:30.534081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T19:46:30.002681Z","time spent":"531.393677ms","remote":"127.0.0.1:38596","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-04T19:46:30.535382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"980.523306ms","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-04T19:46:30.535432Z","caller":"traceutil/trace.go:171","msg":"trace[46447321] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1058; }","duration":"980.615849ms","start":"2026-02-04T19:46:29.554808Z","end":"2026-02-04T19:46:30.535424Z","steps":["trace[46447321] 'agreement among raft nodes before linearized reading' (duration: 978.814436ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T19:46:30.535455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T19:46:29.554785Z","time spent":"980.665719ms","remote":"127.0.0.1:38804","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-04T19:46:30.535655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.298055739s","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-04T19:46:30.535676Z","caller":"traceutil/trace.go:171","msg":"trace[340188216] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1058; }","duration":"1.29809748s","start":"2026-02-04T19:46:29.237571Z","end":"2026-02-04T19:46:30.535668Z","steps":["trace[340188216] 'agreement among raft nodes before linearized reading' (duration: 1.298031648s)"],"step_count":1} {"level":"warn","ts":"2026-02-04T19:46:30.535694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T19:46:29.237559Z","time spent":"1.298130741s","remote":"127.0.0.1:38804","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-04T19:46:30.535707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"687.199562ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:527"} {"level":"info","ts":"2026-02-04T19:46:30.535794Z","caller":"traceutil/trace.go:171","msg":"trace[1033857114] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1058; }","duration":"687.377366ms","start":"2026-02-04T19:46:29.848404Z","end":"2026-02-04T19:46:30.535781Z","steps":["trace[1033857114] 'agreement among raft nodes before linearized reading' (duration: 685.955932ms)"],"step_count":1} {"level":"warn","ts":"2026-02-04T19:46:30.535866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-04T19:46:29.848387Z","time spent":"687.447089ms","remote":"127.0.0.1:38804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":551,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "}