{"level":"warn","ts":"2026-03-01T12:53:38.703047Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-01T12:53:38.703196Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.204: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.204:2380","--initial-cluster=instance=https://162.253.55.204:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.204:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.204: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-03-01T12:53:38.703295Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-01T12:53:38.703311Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.204:2380"]} {"level":"info","ts":"2026-03-01T12:53:38.703335Z","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-03-01T12:53:38.705235Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.204:2379"]} {"level":"info","ts":"2026-03-01T12:53:38.705339Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.204:2380"],"listen-peer-urls":["https://162.253.55.204:2380"],"advertise-client-urls":["https://162.253.55.204:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.204:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.204: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-03-01T12:53:38.709781Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.158007ms"} {"level":"info","ts":"2026-03-01T12:53:38.715102Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"a11c20306510c1f7","cluster-id":"97da89d0cbebd2b5"} {"level":"info","ts":"2026-03-01T12:53:38.715176Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 switched to configuration voters=()"} {"level":"info","ts":"2026-03-01T12:53:38.715204Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 became follower at term 0"} {"level":"info","ts":"2026-03-01T12:53:38.71522Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft a11c20306510c1f7 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-01T12:53:38.715234Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 became follower at term 1"} {"level":"info","ts":"2026-03-01T12:53:38.715324Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 switched to configuration voters=(11609189331680412151)"} {"level":"warn","ts":"2026-03-01T12:53:38.720112Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-01T12:53:38.721407Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-01T12:53:38.722357Z","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-03-01T12:53:38.725243Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"a11c20306510c1f7","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-01T12:53:38.725486Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"a11c20306510c1f7","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-01T12:53:38.726301Z","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-03-01T12:53:38.726507Z","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-03-01T12:53:38.726615Z","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-03-01T12:53:38.727026Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 switched to configuration voters=(11609189331680412151)"} {"level":"info","ts":"2026-03-01T12:53:38.727216Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"97da89d0cbebd2b5","local-member-id":"a11c20306510c1f7","added-peer-id":"a11c20306510c1f7","added-peer-peer-urls":["https://162.253.55.204:2380"]} {"level":"info","ts":"2026-03-01T12:53:38.732571Z","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-03-01T12:53:38.732755Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.204:2380"} {"level":"info","ts":"2026-03-01T12:53:38.732809Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.204:2380"} {"level":"info","ts":"2026-03-01T12:53:38.733001Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"a11c20306510c1f7","initial-advertise-peer-urls":["https://162.253.55.204:2380"],"listen-peer-urls":["https://162.253.55.204:2380"],"advertise-client-urls":["https://162.253.55.204:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.204:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-01T12:53:38.73304Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-01T12:53:39.225583Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 is starting a new election at term 1"} {"level":"info","ts":"2026-03-01T12:53:39.226865Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 became pre-candidate at term 1"} {"level":"info","ts":"2026-03-01T12:53:39.226943Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 received MsgPreVoteResp from a11c20306510c1f7 at term 1"} {"level":"info","ts":"2026-03-01T12:53:39.227002Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 became candidate at term 2"} {"level":"info","ts":"2026-03-01T12:53:39.227042Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 received MsgVoteResp from a11c20306510c1f7 at term 2"} {"level":"info","ts":"2026-03-01T12:53:39.227081Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 became leader at term 2"} {"level":"info","ts":"2026-03-01T12:53:39.227131Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: a11c20306510c1f7 elected leader a11c20306510c1f7 at term 2"} {"level":"info","ts":"2026-03-01T12:53:39.228815Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"a11c20306510c1f7","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.204:2379]}","request-path":"/0/members/a11c20306510c1f7/attributes","cluster-id":"97da89d0cbebd2b5","publish-timeout":"7s"} {"level":"info","ts":"2026-03-01T12:53:39.229035Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-01T12:53:39.229126Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-01T12:53:39.229087Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-01T12:53:39.229235Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-01T12:53:39.231162Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"97da89d0cbebd2b5","local-member-id":"a11c20306510c1f7","cluster-version":"3.5"} {"level":"info","ts":"2026-03-01T12:53:39.231338Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-01T12:53:39.231381Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-01T12:53:39.232892Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-01T12:53:39.233545Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.204:2379"} {"level":"info","ts":"2026-03-01T12:53:39.229808Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-01T12:53:49.230779Z","caller":"traceutil/trace.go:171","msg":"trace[767054112] linearizableReadLoop","detail":"{readStateIndex:383; appliedIndex:378; }","duration":"119.241102ms","start":"2026-03-01T12:53:49.111514Z","end":"2026-03-01T12:53:49.230755Z","steps":["trace[767054112] 'read index received' (duration: 23.069438ms)","trace[767054112] 'applied index is now lower than readState.Index' (duration: 96.170634ms)"],"step_count":2} {"level":"info","ts":"2026-03-01T12:53:49.230816Z","caller":"traceutil/trace.go:171","msg":"trace[1369629879] transaction","detail":"{read_only:false; response_revision:373; number_of_response:1; }","duration":"127.00889ms","start":"2026-03-01T12:53:49.103759Z","end":"2026-03-01T12:53:49.230768Z","steps":["trace[1369629879] 'process raft request' (duration: 75.184888ms)","trace[1369629879] 'compare' (duration: 51.509173ms)"],"step_count":2} {"level":"warn","ts":"2026-03-01T12:53:49.230956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.412356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-03-01T12:53:49.231182Z","caller":"traceutil/trace.go:171","msg":"trace[143544690] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:374; }","duration":"119.683443ms","start":"2026-03-01T12:53:49.111485Z","end":"2026-03-01T12:53:49.231168Z","steps":["trace[143544690] 'agreement among raft nodes before linearized reading' (duration: 119.405356ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T12:53:49.231408Z","caller":"traceutil/trace.go:171","msg":"trace[756094458] transaction","detail":"{read_only:false; response_revision:374; number_of_response:1; }","duration":"126.514868ms","start":"2026-03-01T12:53:49.104332Z","end":"2026-03-01T12:53:49.230846Z","steps":["trace[756094458] 'process raft request' (duration: 126.338864ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T12:54:04.112111Z","caller":"traceutil/trace.go:171","msg":"trace[1107596597] transaction","detail":"{read_only:false; response_revision:467; number_of_response:1; }","duration":"205.428442ms","start":"2026-03-01T12:54:03.906657Z","end":"2026-03-01T12:54:04.112086Z","steps":["trace[1107596597] 'process raft request' (duration: 205.281698ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T12:54:08.119514Z","caller":"traceutil/trace.go:171","msg":"trace[1205215745] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"403.522715ms","start":"2026-03-01T12:54:07.715919Z","end":"2026-03-01T12:54:08.119442Z","steps":["trace[1205215745] 'process raft request' (duration: 403.347881ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T12:54:08.120193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T12:54:07.715893Z","time spent":"403.713949ms","remote":"127.0.0.1:56254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4567,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-01T12:54:12.717228Z","caller":"traceutil/trace.go:171","msg":"trace[636324526] linearizableReadLoop","detail":"{readStateIndex:535; appliedIndex:534; }","duration":"456.524771ms","start":"2026-03-01T12:54:12.260676Z","end":"2026-03-01T12:54:12.7172Z","steps":["trace[636324526] 'read index received' (duration: 456.452979ms)","trace[636324526] 'applied index is now lower than readState.Index' (duration: 70.302µs)"],"step_count":2} {"level":"info","ts":"2026-03-01T12:54:12.717329Z","caller":"traceutil/trace.go:171","msg":"trace[1522778468] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"564.882109ms","start":"2026-03-01T12:54:12.152412Z","end":"2026-03-01T12:54:12.717294Z","steps":["trace[1522778468] 'process raft request' (duration: 564.659103ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T12:54:12.717415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.815905ms","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-03-01T12:54:12.717452Z","caller":"traceutil/trace.go:171","msg":"trace[1719063600] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:520; }","duration":"455.888858ms","start":"2026-03-01T12:54:12.261551Z","end":"2026-03-01T12:54:12.71744Z","steps":["trace[1719063600] 'agreement among raft nodes before linearized reading' (duration: 455.805656ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T12:54:12.717483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T12:54:12.261541Z","time spent":"455.934318ms","remote":"127.0.0.1:56372","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-03-01T12:54:12.717457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.741857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.204\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-03-01T12:54:12.717531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.685983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-01T12:54:12.717577Z","caller":"traceutil/trace.go:171","msg":"trace[561851236] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:520; }","duration":"166.758685ms","start":"2026-03-01T12:54:12.550806Z","end":"2026-03-01T12:54:12.717564Z","steps":["trace[561851236] 'agreement among raft nodes before linearized reading' (duration: 166.692014ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T12:54:12.717556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T12:54:12.152387Z","time spent":"565.029353ms","remote":"127.0.0.1:56372","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-03-01T12:54:12.717575Z","caller":"traceutil/trace.go:171","msg":"trace[1334954333] range","detail":"{range_begin:/registry/masterleases/162.253.55.204; range_end:; response_count:1; response_revision:520; }","duration":"456.912001ms","start":"2026-03-01T12:54:12.26063Z","end":"2026-03-01T12:54:12.717542Z","steps":["trace[1334954333] 'agreement among raft nodes before linearized reading' (duration: 456.681796ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T12:54:12.717655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T12:54:12.260606Z","time spent":"457.024685ms","remote":"127.0.0.1:56172","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/162.253.55.204\" "} {"level":"info","ts":"2026-03-01T12:54:14.982776Z","caller":"traceutil/trace.go:171","msg":"trace[1400637966] linearizableReadLoop","detail":"{readStateIndex:541; appliedIndex:540; }","duration":"412.014172ms","start":"2026-03-01T12:54:14.570739Z","end":"2026-03-01T12:54:14.982754Z","steps":["trace[1400637966] 'read index received' (duration: 411.844038ms)","trace[1400637966] 'applied index is now lower than readState.Index' (duration: 169.253µs)"],"step_count":2} {"level":"info","ts":"2026-03-01T12:54:14.983058Z","caller":"traceutil/trace.go:171","msg":"trace[544817512] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"528.356427ms","start":"2026-03-01T12:54:14.454675Z","end":"2026-03-01T12:54:14.983031Z","steps":["trace[544817512] 'process raft request' (duration: 527.966318ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T12:54:14.983105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.266015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-03-01T12:54:14.983092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.33587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-01T12:54:14.983146Z","caller":"traceutil/trace.go:171","msg":"trace[1515987144] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:525; }","duration":"261.316786ms","start":"2026-03-01T12:54:14.721817Z","end":"2026-03-01T12:54:14.983134Z","steps":["trace[1515987144] 'agreement among raft nodes before linearized reading' (duration: 261.238935ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T12:54:14.983166Z","caller":"traceutil/trace.go:171","msg":"trace[930846183] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:525; }","duration":"412.447892ms","start":"2026-03-01T12:54:14.570696Z","end":"2026-03-01T12:54:14.983144Z","steps":["trace[930846183] 'agreement among raft nodes before linearized reading' (duration: 412.303088ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T12:54:14.983234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T12:54:14.57068Z","time spent":"412.544334ms","remote":"127.0.0.1:56138","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-03-01T12:54:14.98325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.541323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-01T12:54:14.983272Z","caller":"traceutil/trace.go:171","msg":"trace[596166280] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:525; }","duration":"142.597825ms","start":"2026-03-01T12:54:14.840667Z","end":"2026-03-01T12:54:14.983265Z","steps":["trace[596166280] 'agreement among raft nodes before linearized reading' (duration: 142.541614ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T12:54:14.983317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.431408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-01T12:54:14.983347Z","caller":"traceutil/trace.go:171","msg":"trace[1817744281] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:525; }","duration":"211.460999ms","start":"2026-03-01T12:54:14.771878Z","end":"2026-03-01T12:54:14.983339Z","steps":["trace[1817744281] 'agreement among raft nodes before linearized reading' (duration: 211.413068ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T12:54:14.983184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T12:54:14.45465Z","time spent":"528.47054ms","remote":"127.0.0.1:56372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-01T12:54:14.98344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.590581ms","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-03-01T12:54:14.983514Z","caller":"traceutil/trace.go:171","msg":"trace[194802157] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:525; }","duration":"241.703304ms","start":"2026-03-01T12:54:14.741787Z","end":"2026-03-01T12:54:14.98349Z","steps":["trace[194802157] 'agreement among raft nodes before linearized reading' (duration: 241.631982ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T12:54:15.177916Z","caller":"traceutil/trace.go:171","msg":"trace[686368411] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"187.851803ms","start":"2026-03-01T12:54:14.990043Z","end":"2026-03-01T12:54:15.177894Z","steps":["trace[686368411] 'process raft request' (duration: 187.812432ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T12:54:15.177956Z","caller":"traceutil/trace.go:171","msg":"trace[293633840] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"190.013584ms","start":"2026-03-01T12:54:14.987897Z","end":"2026-03-01T12:54:15.177911Z","steps":["trace[293633840] 'process raft request' (duration: 137.491114ms)","trace[293633840] 'compare' (duration: 52.265944ms)"],"step_count":2} {"level":"info","ts":"2026-03-01T12:54:15.17813Z","caller":"traceutil/trace.go:171","msg":"trace[719333015] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"190.151517ms","start":"2026-03-01T12:54:14.987968Z","end":"2026-03-01T12:54:15.17812Z","steps":["trace[719333015] 'process raft request' (duration: 189.81349ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T12:54:15.178185Z","caller":"traceutil/trace.go:171","msg":"trace[1140832095] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"190.159448ms","start":"2026-03-01T12:54:14.988008Z","end":"2026-03-01T12:54:15.178167Z","steps":["trace[1140832095] 'process raft request' (duration: 189.81501ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T12:54:16.778725Z","caller":"traceutil/trace.go:171","msg":"trace[785651155] linearizableReadLoop","detail":"{readStateIndex:549; appliedIndex:548; }","duration":"227.792715ms","start":"2026-03-01T12:54:16.550911Z","end":"2026-03-01T12:54:16.778704Z","steps":["trace[785651155] 'read index received' (duration: 227.57994ms)","trace[785651155] 'applied index is now lower than readState.Index' (duration: 211.825µs)"],"step_count":2} {"level":"info","ts":"2026-03-01T12:54:16.7788Z","caller":"traceutil/trace.go:171","msg":"trace[1205707565] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"717.0645ms","start":"2026-03-01T12:54:16.061716Z","end":"2026-03-01T12:54:16.77878Z","steps":["trace[1205707565] 'process raft request' (duration: 716.834885ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T12:54:16.778845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.912968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-01T12:54:16.77888Z","caller":"traceutil/trace.go:171","msg":"trace[902511360] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:533; }","duration":"228ms","start":"2026-03-01T12:54:16.550869Z","end":"2026-03-01T12:54:16.778869Z","steps":["trace[902511360] 'agreement among raft nodes before linearized reading' (duration: 227.928439ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T12:54:16.778919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T12:54:16.061686Z","time spent":"717.159913ms","remote":"127.0.0.1:56266","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-01T12:54:18.051526Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976812220213305216,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-01T12:54:18.551982Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976812220213305216,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-01T12:54:18.563093Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.376440808s","expected-duration":"1s"} {"level":"info","ts":"2026-03-01T12:54:18.563557Z","caller":"traceutil/trace.go:171","msg":"trace[906292150] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"1.376953841s","start":"2026-03-01T12:54:17.186574Z","end":"2026-03-01T12:54:18.563528Z","steps":["trace[906292150] 'process raft request' (duration: 1.376785237s)"],"step_count":1} {"level":"warn","ts":"2026-03-01T12:54:18.563717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T12:54:17.186564Z","time spent":"1.377065003s","remote":"127.0.0.1:56372","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-03-01T12:54:18.610832Z","caller":"traceutil/trace.go:171","msg":"trace[247606116] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"1.423077069s","start":"2026-03-01T12:54:17.187714Z","end":"2026-03-01T12:54:18.610791Z","steps":["trace[247606116] 'process raft request' (duration: 1.422855714s)"],"step_count":1} {"level":"info","ts":"2026-03-01T12:54:18.610881Z","caller":"traceutil/trace.go:171","msg":"trace[489075238] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"1.423007128s","start":"2026-03-01T12:54:17.187835Z","end":"2026-03-01T12:54:18.610842Z","steps":["trace[489075238] 'process raft request' (duration: 1.422808383s)"],"step_count":1} {"level":"warn","ts":"2026-03-01T12:54:18.611013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T12:54:17.187699Z","time spent":"1.423222263s","remote":"127.0.0.1:56372","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-03-01T12:54:18.611064Z","caller":"traceutil/trace.go:171","msg":"trace[1335757734] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"1.422832824s","start":"2026-03-01T12:54:17.188216Z","end":"2026-03-01T12:54:18.611049Z","steps":["trace[1335757734] 'process raft request' (duration: 1.422470595s)"],"step_count":1} {"level":"warn","ts":"2026-03-01T12:54:18.611155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T12:54:17.188199Z","time spent":"1.422911295s","remote":"127.0.0.1:56372","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-03-01T12:54:18.611428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T12:54:17.187828Z","time spent":"1.423199252s","remote":"127.0.0.1:56372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-01T12:54:18.614203Z","caller":"traceutil/trace.go:171","msg":"trace[314859325] linearizableReadLoop","detail":"{readStateIndex:554; appliedIndex:549; }","duration":"1.062967423s","start":"2026-03-01T12:54:17.551217Z","end":"2026-03-01T12:54:18.614185Z","steps":["trace[314859325] 'read index received' (duration: 1.012219164s)","trace[314859325] 'applied index is now lower than readState.Index' (duration: 50.747179ms)"],"step_count":2} {"level":"warn","ts":"2026-03-01T12:54:18.61431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.063088815s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-01T12:54:18.614347Z","caller":"traceutil/trace.go:171","msg":"trace[1211541273] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:537; }","duration":"1.063165247s","start":"2026-03-01T12:54:17.551171Z","end":"2026-03-01T12:54:18.614336Z","steps":["trace[1211541273] 'agreement among raft nodes before linearized reading' (duration: 1.063080145s)"],"step_count":1} {"level":"warn","ts":"2026-03-01T12:54:18.614386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T12:54:17.551151Z","time spent":"1.063227289s","remote":"127.0.0.1:56138","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-03-01T12:54:19.228654Z","caller":"traceutil/trace.go:171","msg":"trace[902198605] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"152.942709ms","start":"2026-03-01T12:54:19.075682Z","end":"2026-03-01T12:54:19.228625Z","steps":["trace[902198605] 'process raft request' (duration: 91.683643ms)","trace[902198605] 'compare' (duration: 61.111273ms)"],"step_count":2} {"level":"warn","ts":"2026-03-01T12:54:20.181716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.339891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-qfgrz\" ","response":"range_response_count:1 size:17247"} {"level":"info","ts":"2026-03-01T12:54:20.181823Z","caller":"traceutil/trace.go:171","msg":"trace[1832914259] range","detail":"{range_begin:/registry/pods/kube-system/cilium-qfgrz; range_end:; response_count:1; response_revision:541; }","duration":"112.499135ms","start":"2026-03-01T12:54:20.069301Z","end":"2026-03-01T12:54:20.1818Z","steps":["trace[1832914259] 'range keys from in-memory index tree' (duration: 112.138506ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T12:54:20.360099Z","caller":"traceutil/trace.go:171","msg":"trace[977586957] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"155.50329ms","start":"2026-03-01T12:54:20.204565Z","end":"2026-03-01T12:54:20.360068Z","steps":["trace[977586957] 'process raft request' (duration: 155.293455ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T12:54:20.759657Z","caller":"traceutil/trace.go:171","msg":"trace[999706206] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"142.012591ms","start":"2026-03-01T12:54:20.617622Z","end":"2026-03-01T12:54:20.759635Z","steps":["trace[999706206] 'process raft request' (duration: 141.883258ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T12:54:20.763816Z","caller":"traceutil/trace.go:171","msg":"trace[1012245786] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"143.414134ms","start":"2026-03-01T12:54:20.62037Z","end":"2026-03-01T12:54:20.763785Z","steps":["trace[1012245786] 'process raft request' (duration: 143.333282ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T12:54:20.76388Z","caller":"traceutil/trace.go:171","msg":"trace[1836329923] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"143.871085ms","start":"2026-03-01T12:54:20.619984Z","end":"2026-03-01T12:54:20.763855Z","steps":["trace[1836329923] 'process raft request' (duration: 143.568558ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T12:54:30.624024Z","caller":"traceutil/trace.go:171","msg":"trace[238261765] transaction","detail":"{read_only:false; response_revision:658; number_of_response:1; }","duration":"564.117508ms","start":"2026-03-01T12:54:30.059879Z","end":"2026-03-01T12:54:30.623997Z","steps":["trace[238261765] 'process raft request' (duration: 563.769229ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T12:54:30.624135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T12:54:30.059863Z","time spent":"564.213621ms","remote":"127.0.0.1:56266","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18698,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-01T12:54:36.502139Z","caller":"traceutil/trace.go:171","msg":"trace[1284474482] transaction","detail":"{read_only:false; response_revision:717; number_of_response:1; }","duration":"276.475068ms","start":"2026-03-01T12:54:36.225641Z","end":"2026-03-01T12:54:36.502116Z","steps":["trace[1284474482] 'process raft request' (duration: 276.083178ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T12:54:37.049886Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976812220213305558,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-01T12:54:37.06974Z","caller":"traceutil/trace.go:171","msg":"trace[1668490377] linearizableReadLoop","detail":"{readStateIndex:739; appliedIndex:738; }","duration":"520.355598ms","start":"2026-03-01T12:54:36.54936Z","end":"2026-03-01T12:54:37.069716Z","steps":["trace[1668490377] 'read index received' (duration: 519.954447ms)","trace[1668490377] 'applied index is now lower than readState.Index' (duration: 399.831µs)"],"step_count":2} {"level":"info","ts":"2026-03-01T12:54:37.069853Z","caller":"traceutil/trace.go:171","msg":"trace[1269983233] transaction","detail":"{read_only:false; response_revision:718; number_of_response:1; }","duration":"836.471524ms","start":"2026-03-01T12:54:36.233367Z","end":"2026-03-01T12:54:37.069839Z","steps":["trace[1269983233] 'process raft request' (duration: 836.047853ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T12:54:37.070171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.870259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-03-01T12:54:37.070169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.883513ms","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-03-01T12:54:37.07022Z","caller":"traceutil/trace.go:171","msg":"trace[1410653046] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:718; }","duration":"416.971502ms","start":"2026-03-01T12:54:36.653231Z","end":"2026-03-01T12:54:37.070202Z","steps":["trace[1410653046] 'agreement among raft nodes before linearized reading' (duration: 416.811038ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T12:54:37.070225Z","caller":"traceutil/trace.go:171","msg":"trace[177856514] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:718; }","duration":"194.965615ms","start":"2026-03-01T12:54:36.875244Z","end":"2026-03-01T12:54:37.07021Z","steps":["trace[177856514] 'agreement among raft nodes before linearized reading' (duration: 194.831992ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T12:54:37.070252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T12:54:36.653209Z","time spent":"417.035513ms","remote":"127.0.0.1:56372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-01T12:54:37.070213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"520.833529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-01T12:54:37.070258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.781842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-03-01T12:54:37.07026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.825552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-01T12:54:37.070327Z","caller":"traceutil/trace.go:171","msg":"trace[1175143388] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:718; }","duration":"520.995514ms","start":"2026-03-01T12:54:36.549298Z","end":"2026-03-01T12:54:37.070293Z","steps":["trace[1175143388] 'agreement among raft nodes before linearized reading' (duration: 520.82773ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T12:54:37.070353Z","caller":"traceutil/trace.go:171","msg":"trace[1216020844] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:718; }","duration":"194.963826ms","start":"2026-03-01T12:54:36.875365Z","end":"2026-03-01T12:54:37.070328Z","steps":["trace[1216020844] 'agreement among raft nodes before linearized reading' (duration: 194.706369ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T12:54:37.070337Z","caller":"traceutil/trace.go:171","msg":"trace[1864916704] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:718; }","duration":"228.910225ms","start":"2026-03-01T12:54:36.841406Z","end":"2026-03-01T12:54:37.070317Z","steps":["trace[1864916704] 'agreement among raft nodes before linearized reading' (duration: 228.68306ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T12:54:37.070389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T12:54:36.549269Z","time spent":"521.111236ms","remote":"127.0.0.1:56138","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-03-01T12:54:37.070518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T12:54:36.233353Z","time spent":"837.029688ms","remote":"127.0.0.1:56266","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4893,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-01T12:54:37.727698Z","caller":"traceutil/trace.go:171","msg":"trace[289790320] transaction","detail":"{read_only:false; response_revision:719; number_of_response:1; }","duration":"650.751215ms","start":"2026-03-01T12:54:37.076908Z","end":"2026-03-01T12:54:37.727659Z","steps":["trace[289790320] 'process raft request' (duration: 612.908121ms)","trace[289790320] 'compare' (duration: 37.758752ms)"],"step_count":2} {"level":"warn","ts":"2026-03-01T12:54:37.727821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T12:54:37.076889Z","time spent":"650.867967ms","remote":"127.0.0.1:56250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":897,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-01T12:54:37.728143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.651997ms","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-03-01T12:54:37.729803Z","caller":"traceutil/trace.go:171","msg":"trace[1101026710] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:718; }","duration":"320.115503ms","start":"2026-03-01T12:54:37.408976Z","end":"2026-03-01T12:54:37.729091Z","steps":["trace[1101026710] 'range keys from in-memory index tree' (duration: 318.591306ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T12:54:37.859627Z","caller":"traceutil/trace.go:171","msg":"trace[1156767743] transaction","detail":"{read_only:false; response_revision:721; number_of_response:1; }","duration":"781.226084ms","start":"2026-03-01T12:54:37.078377Z","end":"2026-03-01T12:54:37.859603Z","steps":["trace[1156767743] 'process raft request' (duration: 781.126062ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T12:54:37.859635Z","caller":"traceutil/trace.go:171","msg":"trace[740228211] transaction","detail":"{read_only:false; response_revision:720; number_of_response:1; }","duration":"781.48197ms","start":"2026-03-01T12:54:37.078108Z","end":"2026-03-01T12:54:37.85959Z","steps":["trace[740228211] 'process raft request' (duration: 776.12617ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T12:54:37.85987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T12:54:37.078368Z","time spent":"781.390639ms","remote":"127.0.0.1:56606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3888,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-01T12:54:37.859963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T12:54:37.078093Z","time spent":"781.782847ms","remote":"127.0.0.1:56376","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1607,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-01T12:54:37.860151Z","caller":"traceutil/trace.go:171","msg":"trace[1728208326] transaction","detail":"{read_only:false; response_revision:722; number_of_response:1; }","duration":"781.416219ms","start":"2026-03-01T12:54:37.078702Z","end":"2026-03-01T12:54:37.860118Z","steps":["trace[1728208326] 'process raft request' (duration: 780.862045ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T12:54:37.860315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T12:54:37.078693Z","time spent":"781.543542ms","remote":"127.0.0.1:56372","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-03-01T12:54:37.860562Z","caller":"traceutil/trace.go:171","msg":"trace[702147164] transaction","detail":"{read_only:false; response_revision:723; number_of_response:1; }","duration":"780.831555ms","start":"2026-03-01T12:54:37.07972Z","end":"2026-03-01T12:54:37.860551Z","steps":["trace[702147164] 'process raft request' (duration: 780.316382ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T12:54:37.86069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T12:54:37.07969Z","time spent":"780.906007ms","remote":"127.0.0.1:56372","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-03-01T12:54:37.860742Z","caller":"traceutil/trace.go:171","msg":"trace[1733893769] transaction","detail":"{read_only:false; response_revision:724; number_of_response:1; }","duration":"780.842486ms","start":"2026-03-01T12:54:37.079874Z","end":"2026-03-01T12:54:37.860717Z","steps":["trace[1733893769] 'process raft request' (duration: 780.561949ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T12:54:37.860863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T12:54:37.079852Z","time spent":"780.949608ms","remote":"127.0.0.1:56372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-01T12:54:37.860969Z","caller":"traceutil/trace.go:171","msg":"trace[973682606] transaction","detail":"{read_only:false; response_revision:725; number_of_response:1; }","duration":"781.026289ms","start":"2026-03-01T12:54:37.079929Z","end":"2026-03-01T12:54:37.860956Z","steps":["trace[973682606] 'process raft request' (duration: 780.584609ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T12:54:37.862521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T12:54:37.079894Z","time spent":"782.554157ms","remote":"127.0.0.1:56372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-01T12:54:37.86351Z","caller":"traceutil/trace.go:171","msg":"trace[1098504863] linearizableReadLoop","detail":"{readStateIndex:746; appliedIndex:739; }","duration":"657.07836ms","start":"2026-03-01T12:54:37.206421Z","end":"2026-03-01T12:54:37.863499Z","steps":["trace[1098504863] 'read index received' (duration: 483.405255ms)","trace[1098504863] 'applied index is now lower than readState.Index' (duration: 173.672315ms)"],"step_count":2} {"level":"warn","ts":"2026-03-01T12:54:37.863682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.415676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-01T12:54:37.863735Z","caller":"traceutil/trace.go:171","msg":"trace[661670911] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:725; }","duration":"312.482878ms","start":"2026-03-01T12:54:37.551237Z","end":"2026-03-01T12:54:37.86372Z","steps":["trace[661670911] 'agreement among raft nodes before linearized reading' (duration: 312.408016ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T12:54:37.863761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T12:54:37.551224Z","time spent":"312.532539ms","remote":"127.0.0.1:56138","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-03-01T12:54:37.863794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"657.371048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-03-01T12:54:37.863831Z","caller":"traceutil/trace.go:171","msg":"trace[271941460] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:725; }","duration":"657.45019ms","start":"2026-03-01T12:54:37.206372Z","end":"2026-03-01T12:54:37.863822Z","steps":["trace[271941460] 'agreement among raft nodes before linearized reading' (duration: 657.191993ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T12:54:37.863857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T12:54:37.20635Z","time spent":"657.50147ms","remote":"127.0.0.1:56372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-03-01T12:54:40.008591Z","caller":"traceutil/trace.go:171","msg":"trace[38142563] transaction","detail":"{read_only:false; response_revision:728; number_of_response:1; }","duration":"140.050802ms","start":"2026-03-01T12:54:39.868516Z","end":"2026-03-01T12:54:40.008567Z","steps":["trace[38142563] 'process raft request' (duration: 139.897459ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T12:54:40.021886Z","caller":"traceutil/trace.go:171","msg":"trace[232875227] transaction","detail":"{read_only:false; response_revision:729; number_of_response:1; }","duration":"150.035567ms","start":"2026-03-01T12:54:39.871825Z","end":"2026-03-01T12:54:40.02186Z","steps":["trace[232875227] 'process raft request' (duration: 149.899644ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T12:54:40.022085Z","caller":"traceutil/trace.go:171","msg":"trace[71281416] transaction","detail":"{read_only:false; response_revision:730; number_of_response:1; }","duration":"150.119659ms","start":"2026-03-01T12:54:39.871948Z","end":"2026-03-01T12:54:40.022068Z","steps":["trace[71281416] 'process raft request' (duration: 149.860393ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T12:54:40.02221Z","caller":"traceutil/trace.go:171","msg":"trace[724274030] linearizableReadLoop","detail":"{readStateIndex:751; appliedIndex:749; }","duration":"149.941464ms","start":"2026-03-01T12:54:39.871895Z","end":"2026-03-01T12:54:40.021836Z","steps":["trace[724274030] 'read index received' (duration: 136.442125ms)","trace[724274030] 'applied index is now lower than readState.Index' (duration: 13.498669ms)"],"step_count":2} {"level":"warn","ts":"2026-03-01T12:54:40.022334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.426527ms","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-03-01T12:54:40.022359Z","caller":"traceutil/trace.go:171","msg":"trace[1638024262] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:730; }","duration":"150.489929ms","start":"2026-03-01T12:54:39.871863Z","end":"2026-03-01T12:54:40.022353Z","steps":["trace[1638024262] 'agreement among raft nodes before linearized reading' (duration: 150.386785ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T12:54:40.022558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.633463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-03-01T12:54:40.022586Z","caller":"traceutil/trace.go:171","msg":"trace[483591589] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:730; }","duration":"148.690424ms","start":"2026-03-01T12:54:39.873887Z","end":"2026-03-01T12:54:40.022578Z","steps":["trace[483591589] 'agreement among raft nodes before linearized reading' (duration: 148.630793ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T12:55:00.480925Z","caller":"traceutil/trace.go:171","msg":"trace[1095045090] transaction","detail":"{read_only:false; response_revision:794; number_of_response:1; }","duration":"230.919624ms","start":"2026-03-01T12:55:00.249984Z","end":"2026-03-01T12:55:00.480904Z","steps":["trace[1095045090] 'process raft request' (duration: 230.884673ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T12:55:00.480951Z","caller":"traceutil/trace.go:171","msg":"trace[1722295953] transaction","detail":"{read_only:false; response_revision:792; number_of_response:1; }","duration":"231.352585ms","start":"2026-03-01T12:55:00.249557Z","end":"2026-03-01T12:55:00.480909Z","steps":["trace[1722295953] 'process raft request' (duration: 231.145749ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T12:55:00.480951Z","caller":"traceutil/trace.go:171","msg":"trace[922978050] transaction","detail":"{read_only:false; response_revision:793; number_of_response:1; }","duration":"231.329354ms","start":"2026-03-01T12:55:00.249599Z","end":"2026-03-01T12:55:00.480928Z","steps":["trace[922978050] 'process raft request' (duration: 231.231711ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T12:55:02.135349Z","caller":"traceutil/trace.go:171","msg":"trace[1076943806] transaction","detail":"{read_only:false; response_revision:795; number_of_response:1; }","duration":"183.415212ms","start":"2026-03-01T12:55:01.951911Z","end":"2026-03-01T12:55:02.135326Z","steps":["trace[1076943806] 'process raft request' (duration: 183.247828ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T12:55:02.737334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.225251ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-01T12:55:02.737577Z","caller":"traceutil/trace.go:171","msg":"trace[1354678539] transaction","detail":"{read_only:false; response_revision:797; number_of_response:1; }","duration":"403.955773ms","start":"2026-03-01T12:55:02.333602Z","end":"2026-03-01T12:55:02.737557Z","steps":["trace[1354678539] 'process raft request' (duration: 403.84811ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T12:55:02.737631Z","caller":"traceutil/trace.go:171","msg":"trace[825125057] transaction","detail":"{read_only:false; response_revision:796; number_of_response:1; }","duration":"404.070076ms","start":"2026-03-01T12:55:02.333539Z","end":"2026-03-01T12:55:02.737609Z","steps":["trace[825125057] 'process raft request' (duration: 116.302122ms)","trace[825125057] 'compare' (duration: 287.059447ms)"],"step_count":2} {"level":"warn","ts":"2026-03-01T12:55:02.737659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T12:55:02.333574Z","time spent":"404.051755ms","remote":"127.0.0.1:56372","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-03-01T12:55:02.737731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T12:55:02.33352Z","time spent":"404.168798ms","remote":"127.0.0.1:56372","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-03-01T12:55:02.985205Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976812220213305772,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-01T12:55:03.485561Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976812220213305772,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-01T12:55:03.509495Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.059741462s","expected-duration":"1s"} {"level":"info","ts":"2026-03-01T12:55:03.59409Z","caller":"traceutil/trace.go:171","msg":"trace[976887621] linearizableReadLoop","detail":"{readStateIndex:825; appliedIndex:824; }","duration":"1.109922247s","start":"2026-03-01T12:55:02.484143Z","end":"2026-03-01T12:55:03.594065Z","steps":["trace[976887621] 'read index received' (duration: 1.025445762s)","trace[976887621] 'applied index is now lower than readState.Index' (duration: 84.475245ms)"],"step_count":2} {"level":"info","ts":"2026-03-01T12:55:03.59412Z","caller":"traceutil/trace.go:171","msg":"trace[2071626661] transaction","detail":"{read_only:false; response_revision:798; number_of_response:1; }","duration":"1.177989841s","start":"2026-03-01T12:55:02.416096Z","end":"2026-03-01T12:55:03.594086Z","steps":["trace[2071626661] 'process raft request' (duration: 1.093547787s)","trace[2071626661] 'compare' (duration: 84.161818ms)"],"step_count":2} {"level":"warn","ts":"2026-03-01T12:55:03.594285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T12:55:02.416078Z","time spent":"1.178114585s","remote":"127.0.0.1:56172","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-01T12:55:03.594379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.110072041s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-03-01T12:55:03.594388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.110225685s","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-03-01T12:55:03.594426Z","caller":"traceutil/trace.go:171","msg":"trace[711776107] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:798; }","duration":"1.110190744s","start":"2026-03-01T12:55:02.484221Z","end":"2026-03-01T12:55:03.594412Z","steps":["trace[711776107] 'agreement among raft nodes before linearized reading' (duration: 1.11000511s)"],"step_count":1} {"level":"info","ts":"2026-03-01T12:55:03.594459Z","caller":"traceutil/trace.go:171","msg":"trace[1484185369] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:798; }","duration":"1.110336117s","start":"2026-03-01T12:55:02.484104Z","end":"2026-03-01T12:55:03.59444Z","steps":["trace[1484185369] 'agreement among raft nodes before linearized reading' (duration: 1.110082601s)"],"step_count":1} {"level":"warn","ts":"2026-03-01T12:55:03.594504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T12:55:02.484086Z","time spent":"1.110407909s","remote":"127.0.0.1:56372","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-03-01T12:55:03.594517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.401145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-01T12:55:03.594544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.108633456s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"warn","ts":"2026-03-01T12:55:03.594513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.044892748s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-01T12:55:03.594569Z","caller":"traceutil/trace.go:171","msg":"trace[690835022] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:798; }","duration":"143.524288ms","start":"2026-03-01T12:55:03.45103Z","end":"2026-03-01T12:55:03.594554Z","steps":["trace[690835022] 'agreement among raft nodes before linearized reading' (duration: 143.417525ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T12:55:03.594598Z","caller":"traceutil/trace.go:171","msg":"trace[939662869] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:798; }","duration":"1.04498304s","start":"2026-03-01T12:55:02.549597Z","end":"2026-03-01T12:55:03.59458Z","steps":["trace[939662869] 'agreement among raft nodes before linearized reading' (duration: 1.044854017s)"],"step_count":1} {"level":"info","ts":"2026-03-01T12:55:03.594585Z","caller":"traceutil/trace.go:171","msg":"trace[1872201066] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:798; }","duration":"1.108687568s","start":"2026-03-01T12:55:02.485882Z","end":"2026-03-01T12:55:03.594569Z","steps":["trace[1872201066] 'agreement among raft nodes before linearized reading' (duration: 1.108603486s)"],"step_count":1} {"level":"warn","ts":"2026-03-01T12:55:03.594633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T12:55:02.549555Z","time spent":"1.045070712s","remote":"127.0.0.1:56138","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-03-01T12:55:03.594464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T12:55:02.484183Z","time spent":"1.110272576s","remote":"127.0.0.1:56372","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-03-01T12:55:03.594651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T12:55:02.485871Z","time spent":"1.108763159s","remote":"127.0.0.1:56372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-03-01T12:55:03.867309Z","caller":"traceutil/trace.go:171","msg":"trace[331415568] transaction","detail":"{read_only:false; response_revision:799; number_of_response:1; }","duration":"268.459711ms","start":"2026-03-01T12:55:03.598824Z","end":"2026-03-01T12:55:03.867284Z","steps":["trace[331415568] 'process raft request' (duration: 247.855748ms)","trace[331415568] 'compare' (duration: 20.245514ms)"],"step_count":2} {"level":"info","ts":"2026-03-01T12:55:03.867821Z","caller":"traceutil/trace.go:171","msg":"trace[1827474578] transaction","detail":"{read_only:false; response_revision:800; number_of_response:1; }","duration":"268.83597ms","start":"2026-03-01T12:55:03.59896Z","end":"2026-03-01T12:55:03.867795Z","steps":["trace[1827474578] 'process raft request' (duration: 268.241715ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T12:55:03.867995Z","caller":"traceutil/trace.go:171","msg":"trace[1594779887] transaction","detail":"{read_only:false; response_revision:801; number_of_response:1; }","duration":"268.966433ms","start":"2026-03-01T12:55:03.599003Z","end":"2026-03-01T12:55:03.867969Z","steps":["trace[1594779887] 'process raft request' (duration: 268.716707ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T12:55:03.86786Z","caller":"traceutil/trace.go:171","msg":"trace[1857289365] linearizableReadLoop","detail":"{readStateIndex:828; appliedIndex:825; }","duration":"268.780559ms","start":"2026-03-01T12:55:03.599057Z","end":"2026-03-01T12:55:03.867837Z","steps":["trace[1857289365] 'read index received' (duration: 247.629942ms)","trace[1857289365] 'applied index is now lower than readState.Index' (duration: 21.149507ms)"],"step_count":2} {"level":"warn","ts":"2026-03-01T12:55:03.868014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.938563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-03-01T12:55:03.868132Z","caller":"traceutil/trace.go:171","msg":"trace[88288212] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:801; }","duration":"269.082877ms","start":"2026-03-01T12:55:03.599032Z","end":"2026-03-01T12:55:03.868115Z","steps":["trace[88288212] 'agreement among raft nodes before linearized reading' (duration: 268.874912ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T12:55:03.868205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.645692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-01T12:55:03.868231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.924337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-01T12:55:03.868268Z","caller":"traceutil/trace.go:171","msg":"trace[1625422765] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:801; }","duration":"267.717343ms","start":"2026-03-01T12:55:03.600532Z","end":"2026-03-01T12:55:03.868249Z","steps":["trace[1625422765] 'agreement among raft nodes before linearized reading' (duration: 267.636731ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T12:55:03.868271Z","caller":"traceutil/trace.go:171","msg":"trace[367521066] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:801; }","duration":"136.980228ms","start":"2026-03-01T12:55:03.73128Z","end":"2026-03-01T12:55:03.86826Z","steps":["trace[367521066] 'agreement among raft nodes before linearized reading' (duration: 136.896746ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T12:55:05.030492Z","caller":"traceutil/trace.go:171","msg":"trace[1399632646] transaction","detail":"{read_only:false; response_revision:802; number_of_response:1; }","duration":"285.787825ms","start":"2026-03-01T12:55:04.744679Z","end":"2026-03-01T12:55:05.030467Z","steps":["trace[1399632646] 'process raft request' (duration: 285.637391ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T12:55:05.332972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.285196ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-01T12:55:05.3331Z","caller":"traceutil/trace.go:171","msg":"trace[961990715] linearizableReadLoop","detail":"{readStateIndex:830; appliedIndex:828; }","duration":"561.465243ms","start":"2026-03-01T12:55:04.771615Z","end":"2026-03-01T12:55:05.33308Z","steps":["trace[961990715] 'read index received' (duration: 258.664852ms)","trace[961990715] 'applied index is now lower than readState.Index' (duration: 302.798451ms)"],"step_count":2} {"level":"warn","ts":"2026-03-01T12:55:05.333231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"561.600526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-01T12:55:05.333612Z","caller":"traceutil/trace.go:171","msg":"trace[1616150239] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:803; }","duration":"562.003547ms","start":"2026-03-01T12:55:04.771588Z","end":"2026-03-01T12:55:05.333591Z","steps":["trace[1616150239] 'agreement among raft nodes before linearized reading' (duration: 561.587657ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T12:55:05.333686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T12:55:04.771576Z","time spent":"562.091768ms","remote":"127.0.0.1:56468","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":29,"request content":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true "} {"level":"warn","ts":"2026-03-01T12:55:05.333309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.352478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-01T12:55:05.333754Z","caller":"traceutil/trace.go:171","msg":"trace[1211048569] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:803; }","duration":"155.84034ms","start":"2026-03-01T12:55:05.177893Z","end":"2026-03-01T12:55:05.333733Z","steps":["trace[1211048569] 'agreement among raft nodes before linearized reading' (duration: 155.353298ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T12:55:05.333291Z","caller":"traceutil/trace.go:171","msg":"trace[1342000643] transaction","detail":"{read_only:false; response_revision:803; number_of_response:1; }","duration":"587.155632ms","start":"2026-03-01T12:55:04.745952Z","end":"2026-03-01T12:55:05.333108Z","steps":["trace[1342000643] 'process raft request' (duration: 359.652181ms)","trace[1342000643] 'compare' (duration: 227.150742ms)"],"step_count":2} {"level":"warn","ts":"2026-03-01T12:55:05.334024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T12:55:04.745884Z","time spent":"587.9436ms","remote":"127.0.0.1:56372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-01T12:55:05.915846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.067787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-01T12:55:05.915923Z","caller":"traceutil/trace.go:171","msg":"trace[1240048027] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:803; }","duration":"366.18675ms","start":"2026-03-01T12:55:05.549719Z","end":"2026-03-01T12:55:05.915906Z","steps":["trace[1240048027] 'range keys from in-memory index tree' (duration: 365.992616ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T12:55:05.91597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T12:55:05.5497Z","time spent":"366.261922ms","remote":"127.0.0.1:56136","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-03-01T12:55:06.433606Z","caller":"traceutil/trace.go:171","msg":"trace[1610693521] transaction","detail":"{read_only:false; response_revision:804; number_of_response:1; }","duration":"513.244414ms","start":"2026-03-01T12:55:05.920344Z","end":"2026-03-01T12:55:06.433589Z","steps":["trace[1610693521] 'process raft request' (duration: 513.130211ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T12:55:06.433717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T12:55:05.920322Z","time spent":"513.339458ms","remote":"127.0.0.1:56372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-01T12:55:06.446215Z","caller":"traceutil/trace.go:171","msg":"trace[396800504] transaction","detail":"{read_only:false; response_revision:805; number_of_response:1; }","duration":"525.635517ms","start":"2026-03-01T12:55:05.920564Z","end":"2026-03-01T12:55:06.446199Z","steps":["trace[396800504] 'process raft request' (duration: 525.521135ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T12:55:06.446335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T12:55:05.920554Z","time spent":"525.73438ms","remote":"127.0.0.1:56372","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-03-01T12:55:06.446468Z","caller":"traceutil/trace.go:171","msg":"trace[1438164837] transaction","detail":"{read_only:false; response_revision:806; number_of_response:1; }","duration":"525.789702ms","start":"2026-03-01T12:55:05.920662Z","end":"2026-03-01T12:55:06.446452Z","steps":["trace[1438164837] 'process raft request' (duration: 525.504575ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T12:55:06.446547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T12:55:05.920635Z","time spent":"525.874933ms","remote":"127.0.0.1:56372","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-03-01T12:55:06.446741Z","caller":"traceutil/trace.go:171","msg":"trace[720095171] transaction","detail":"{read_only:false; response_revision:807; number_of_response:1; }","duration":"485.512476ms","start":"2026-03-01T12:55:05.961218Z","end":"2026-03-01T12:55:06.446731Z","steps":["trace[720095171] 'process raft request' (duration: 485.168048ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T12:55:06.446791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T12:55:05.961201Z","time spent":"485.563197ms","remote":"127.0.0.1:56372","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-03-01T12:55:08.586668Z","caller":"traceutil/trace.go:171","msg":"trace[739673112] transaction","detail":"{read_only:false; response_revision:812; number_of_response:1; }","duration":"105.329685ms","start":"2026-03-01T12:55:08.481305Z","end":"2026-03-01T12:55:08.586634Z","steps":["trace[739673112] 'process raft request' (duration: 68.71473ms)","trace[739673112] 'compare' (duration: 36.361169ms)"],"step_count":2} {"level":"info","ts":"2026-03-01T12:55:15.520213Z","caller":"traceutil/trace.go:171","msg":"trace[393326698] transaction","detail":"{read_only:false; response_revision:831; number_of_response:1; }","duration":"106.956404ms","start":"2026-03-01T12:55:15.413238Z","end":"2026-03-01T12:55:15.520195Z","steps":["trace[393326698] 'process raft request' (duration: 106.850982ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T12:55:15.825474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.898163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-01T12:55:15.825571Z","caller":"traceutil/trace.go:171","msg":"trace[569914515] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:831; }","duration":"276.036036ms","start":"2026-03-01T12:55:15.549513Z","end":"2026-03-01T12:55:15.825549Z","steps":["trace[569914515] 'range keys from in-memory index tree' (duration: 275.827271ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T12:55:15.825601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.749044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:4 size:329948"} {"level":"info","ts":"2026-03-01T12:55:15.825678Z","caller":"traceutil/trace.go:171","msg":"trace[1465367117] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:4; response_revision:831; }","duration":"161.869447ms","start":"2026-03-01T12:55:15.663786Z","end":"2026-03-01T12:55:15.825655Z","steps":["trace[1465367117] 'range keys from in-memory index tree' (duration: 161.441906ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T12:55:16.760884Z","caller":"traceutil/trace.go:171","msg":"trace[72819744] transaction","detail":"{read_only:false; response_revision:834; number_of_response:1; }","duration":"139.51815ms","start":"2026-03-01T12:55:16.621308Z","end":"2026-03-01T12:55:16.760826Z","steps":["trace[72819744] 'process raft request' (duration: 139.343865ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T12:55:16.79752Z","caller":"traceutil/trace.go:171","msg":"trace[458796944] transaction","detail":"{read_only:false; response_revision:835; number_of_response:1; }","duration":"166.156381ms","start":"2026-03-01T12:55:16.631337Z","end":"2026-03-01T12:55:16.797493Z","steps":["trace[458796944] 'process raft request' (duration: 165.828753ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T12:55:19.480111Z","caller":"traceutil/trace.go:171","msg":"trace[230248967] transaction","detail":"{read_only:false; response_revision:891; number_of_response:1; }","duration":"590.40137ms","start":"2026-03-01T12:55:18.889682Z","end":"2026-03-01T12:55:19.480083Z","steps":["trace[230248967] 'process raft request' (duration: 590.238866ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T12:55:19.480278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T12:55:18.889619Z","time spent":"590.578394ms","remote":"127.0.0.1:56372","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-03-01T12:55:19.480685Z","caller":"traceutil/trace.go:171","msg":"trace[1824711856] linearizableReadLoop","detail":"{readStateIndex:923; appliedIndex:923; }","duration":"302.283227ms","start":"2026-03-01T12:55:19.178391Z","end":"2026-03-01T12:55:19.480674Z","steps":["trace[1824711856] 'read index received' (duration: 302.279467ms)","trace[1824711856] 'applied index is now lower than readState.Index' (duration: 3.02µs)"],"step_count":2} {"level":"warn","ts":"2026-03-01T12:55:19.480815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.430532ms","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-03-01T12:55:19.48085Z","caller":"traceutil/trace.go:171","msg":"trace[149395768] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:891; }","duration":"302.499653ms","start":"2026-03-01T12:55:19.178341Z","end":"2026-03-01T12:55:19.480841Z","steps":["trace[149395768] 'agreement among raft nodes before linearized reading' (duration: 302.380751ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T12:55:19.480882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T12:55:19.178323Z","time spent":"302.552934ms","remote":"127.0.0.1:56372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-01T12:55:21.112658Z","caller":"traceutil/trace.go:171","msg":"trace[967357296] transaction","detail":"{read_only:false; response_revision:894; number_of_response:1; }","duration":"463.136219ms","start":"2026-03-01T12:55:20.649491Z","end":"2026-03-01T12:55:21.112627Z","steps":["trace[967357296] 'process raft request' (duration: 462.872463ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T12:55:21.112941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T12:55:20.649481Z","time spent":"463.319224ms","remote":"127.0.0.1:56372","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-03-01T12:55:21.115805Z","caller":"traceutil/trace.go:171","msg":"trace[977206002] transaction","detail":"{read_only:false; response_revision:895; number_of_response:1; }","duration":"414.442259ms","start":"2026-03-01T12:55:20.701338Z","end":"2026-03-01T12:55:21.11578Z","steps":["trace[977206002] 'process raft request' (duration: 414.292105ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T12:55:21.115986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T12:55:20.701319Z","time spent":"414.574853ms","remote":"127.0.0.1:56372","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-03-01T12:55:21.859569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.452903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-03-01T12:55:21.85958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.045121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-01T12:55:21.859625Z","caller":"traceutil/trace.go:171","msg":"trace[1096126224] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:895; }","duration":"291.548206ms","start":"2026-03-01T12:55:21.568062Z","end":"2026-03-01T12:55:21.85961Z","steps":["trace[1096126224] 'range keys from in-memory index tree' (duration: 291.29921ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T12:55:21.859624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.95989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-01T12:55:21.859714Z","caller":"traceutil/trace.go:171","msg":"trace[631835311] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:895; }","duration":"284.084283ms","start":"2026-03-01T12:55:21.57561Z","end":"2026-03-01T12:55:21.859694Z","steps":["trace[631835311] 'range keys from in-memory index tree' (duration: 283.742435ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T12:55:21.859653Z","caller":"traceutil/trace.go:171","msg":"trace[858326110] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:895; }","duration":"374.172875ms","start":"2026-03-01T12:55:21.485462Z","end":"2026-03-01T12:55:21.859635Z","steps":["trace[858326110] 'range keys from in-memory index tree' (duration: 373.843606ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T12:55:21.859792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T12:55:21.485444Z","time spent":"374.336439ms","remote":"127.0.0.1:56372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-01T12:55:35.764523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.197107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-01T12:55:35.764529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.07656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-01T12:55:35.764626Z","caller":"traceutil/trace.go:171","msg":"trace[1865576621] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:935; }","duration":"213.371081ms","start":"2026-03-01T12:55:35.551227Z","end":"2026-03-01T12:55:35.764598Z","steps":["trace[1865576621] 'range keys from in-memory index tree' (duration: 213.068194ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T12:55:35.764674Z","caller":"traceutil/trace.go:171","msg":"trace[1971999472] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:935; }","duration":"279.302605ms","start":"2026-03-01T12:55:35.485339Z","end":"2026-03-01T12:55:35.764642Z","steps":["trace[1971999472] 'count revisions from in-memory index tree' (duration: 278.978598ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T12:55:36.484301Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976812220213306129,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-01T12:55:36.984816Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976812220213306129,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-01T12:55:37.035268Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.055070627s","expected-duration":"1s"} {"level":"info","ts":"2026-03-01T12:55:37.035578Z","caller":"traceutil/trace.go:171","msg":"trace[1446909680] linearizableReadLoop","detail":"{readStateIndex:971; appliedIndex:970; }","duration":"1.051610705s","start":"2026-03-01T12:55:35.983949Z","end":"2026-03-01T12:55:37.035559Z","steps":["trace[1446909680] 'read index received' (duration: 1.051478362s)","trace[1446909680] 'applied index is now lower than readState.Index' (duration: 131.683µs)"],"step_count":2} {"level":"info","ts":"2026-03-01T12:55:37.035684Z","caller":"traceutil/trace.go:171","msg":"trace[852658888] transaction","detail":"{read_only:false; response_revision:936; number_of_response:1; }","duration":"1.055529797s","start":"2026-03-01T12:55:35.980113Z","end":"2026-03-01T12:55:37.035643Z","steps":["trace[852658888] 'process raft request' (duration: 1.055320802s)"],"step_count":1} {"level":"warn","ts":"2026-03-01T12:55:37.035751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.051789729s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-01T12:55:37.035776Z","caller":"traceutil/trace.go:171","msg":"trace[1174597345] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:936; }","duration":"1.051859001s","start":"2026-03-01T12:55:35.983909Z","end":"2026-03-01T12:55:37.035768Z","steps":["trace[1174597345] 'agreement among raft nodes before linearized reading' (duration: 1.051726588s)"],"step_count":1} {"level":"warn","ts":"2026-03-01T12:55:37.035798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T12:55:35.983838Z","time spent":"1.051954253s","remote":"127.0.0.1:56372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-01T12:55:37.035811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T12:55:35.980095Z","time spent":"1.05564148s","remote":"127.0.0.1:56372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-01T12:55:37.035825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"999.066629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-03-01T12:55:37.035819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.89808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-01T12:55:37.035896Z","caller":"traceutil/trace.go:171","msg":"trace[1652817557] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:936; }","duration":"999.177521ms","start":"2026-03-01T12:55:36.036699Z","end":"2026-03-01T12:55:37.035876Z","steps":["trace[1652817557] 'agreement among raft nodes before linearized reading' (duration: 998.964046ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T12:55:37.035915Z","caller":"traceutil/trace.go:171","msg":"trace[447543256] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:936; }","duration":"326.051084ms","start":"2026-03-01T12:55:36.709843Z","end":"2026-03-01T12:55:37.035894Z","steps":["trace[447543256] 'agreement among raft nodes before linearized reading' (duration: 325.86998ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T12:55:37.035942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T12:55:36.036682Z","time spent":"999.252414ms","remote":"127.0.0.1:56372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-01T12:55:37.035961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T12:55:36.709826Z","time spent":"326.124756ms","remote":"127.0.0.1:56206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":15,"response size":31,"request content":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true "} {"level":"warn","ts":"2026-03-01T12:55:37.565293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.533386ms","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-03-01T12:55:37.565475Z","caller":"traceutil/trace.go:171","msg":"trace[1109335814] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:936; }","duration":"154.747032ms","start":"2026-03-01T12:55:37.410703Z","end":"2026-03-01T12:55:37.56545Z","steps":["trace[1109335814] 'range keys from in-memory index tree' (duration: 154.505055ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T12:55:37.566198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.063583ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-01T12:55:37.566299Z","caller":"traceutil/trace.go:171","msg":"trace[1226643548] transaction","detail":"{read_only:false; response_revision:937; number_of_response:1; }","duration":"526.717224ms","start":"2026-03-01T12:55:37.039567Z","end":"2026-03-01T12:55:37.566284Z","steps":["trace[1226643548] 'process raft request' (duration: 383.514357ms)","trace[1226643548] 'compare' (duration: 141.932287ms)"],"step_count":2} {"level":"warn","ts":"2026-03-01T12:55:37.566351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T12:55:37.039546Z","time spent":"526.788886ms","remote":"127.0.0.1:56372","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-03-01T12:55:38.528055Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.104922289s","expected-duration":"1s"} {"level":"info","ts":"2026-03-01T12:55:38.528453Z","caller":"traceutil/trace.go:171","msg":"trace[925513922] linearizableReadLoop","detail":"{readStateIndex:974; appliedIndex:971; }","duration":"1.214387826s","start":"2026-03-01T12:55:37.314035Z","end":"2026-03-01T12:55:38.528423Z","steps":["trace[925513922] 'read index received' (duration: 109.075517ms)","trace[925513922] 'applied index is now lower than readState.Index' (duration: 1.105311269s)"],"step_count":2} {"level":"info","ts":"2026-03-01T12:55:38.528479Z","caller":"traceutil/trace.go:171","msg":"trace[1336926680] transaction","detail":"{read_only:false; response_revision:938; number_of_response:1; }","duration":"1.48813172s","start":"2026-03-01T12:55:37.040319Z","end":"2026-03-01T12:55:38.528451Z","steps":["trace[1336926680] 'process raft request' (duration: 1.487908865s)"],"step_count":1} {"level":"warn","ts":"2026-03-01T12:55:38.528638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T12:55:37.040303Z","time spent":"1.488254583s","remote":"127.0.0.1:56372","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-03-01T12:55:38.528666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.214608602s","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-03-01T12:55:38.528713Z","caller":"traceutil/trace.go:171","msg":"trace[1466279705] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:939; }","duration":"1.214712004s","start":"2026-03-01T12:55:37.313989Z","end":"2026-03-01T12:55:38.528701Z","steps":["trace[1466279705] 'agreement among raft nodes before linearized reading' (duration: 1.21455593s)"],"step_count":1} {"level":"warn","ts":"2026-03-01T12:55:38.528749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T12:55:37.313966Z","time spent":"1.214774785s","remote":"127.0.0.1:56372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-01T12:55:38.529199Z","caller":"traceutil/trace.go:171","msg":"trace[1659327338] transaction","detail":"{read_only:false; response_revision:939; number_of_response:1; }","duration":"1.363564794s","start":"2026-03-01T12:55:37.165008Z","end":"2026-03-01T12:55:38.528573Z","steps":["trace[1659327338] 'process raft request' (duration: 1.363364719s)"],"step_count":1} {"level":"warn","ts":"2026-03-01T12:55:38.529451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T12:55:37.164975Z","time spent":"1.364361834s","remote":"127.0.0.1:56372","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-03-01T12:55:38.532461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.168337124s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-03-01T12:55:38.532486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"983.073889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-01T12:55:38.532538Z","caller":"traceutil/trace.go:171","msg":"trace[1014987751] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:939; }","duration":"983.165751ms","start":"2026-03-01T12:55:37.549359Z","end":"2026-03-01T12:55:38.532524Z","steps":["trace[1014987751] 'agreement among raft nodes before linearized reading' (duration: 983.059139ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T12:55:38.532529Z","caller":"traceutil/trace.go:171","msg":"trace[1668824384] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:939; }","duration":"1.168452357s","start":"2026-03-01T12:55:37.364057Z","end":"2026-03-01T12:55:38.53251Z","steps":["trace[1668824384] 'agreement among raft nodes before linearized reading' (duration: 1.168347885s)"],"step_count":1} {"level":"warn","ts":"2026-03-01T12:55:38.532573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T12:55:37.549339Z","time spent":"983.227173ms","remote":"127.0.0.1:56138","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-03-01T12:55:38.532586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T12:55:37.364039Z","time spent":"1.168536909s","remote":"127.0.0.1:56466","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":31,"request content":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true "} {"level":"warn","ts":"2026-03-01T12:55:38.532667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.150848889s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-03-01T12:55:38.532729Z","caller":"traceutil/trace.go:171","msg":"trace[1426286035] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:939; }","duration":"1.150958282s","start":"2026-03-01T12:55:37.381754Z","end":"2026-03-01T12:55:38.532713Z","steps":["trace[1426286035] 'agreement among raft nodes before linearized reading' (duration: 1.150731917s)"],"step_count":1} {"level":"warn","ts":"2026-03-01T12:55:38.532769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T12:55:37.381733Z","time spent":"1.151030534s","remote":"127.0.0.1:56372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-03-01T12:55:40.38116Z","caller":"traceutil/trace.go:171","msg":"trace[1972076332] transaction","detail":"{read_only:false; response_revision:949; number_of_response:1; }","duration":"100.056214ms","start":"2026-03-01T12:55:40.281076Z","end":"2026-03-01T12:55:40.381132Z","steps":["trace[1972076332] 'process raft request' (duration: 99.85623ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T12:55:42.771357Z","caller":"traceutil/trace.go:171","msg":"trace[1397068732] linearizableReadLoop","detail":"{readStateIndex:1003; appliedIndex:1002; }","duration":"219.925668ms","start":"2026-03-01T12:55:42.5514Z","end":"2026-03-01T12:55:42.771325Z","steps":["trace[1397068732] 'read index received' (duration: 219.854076ms)","trace[1397068732] 'applied index is now lower than readState.Index' (duration: 70.462µs)"],"step_count":2} {"level":"warn","ts":"2026-03-01T12:55:42.771892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.44671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-01T12:55:42.772021Z","caller":"traceutil/trace.go:171","msg":"trace[189655873] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:966; }","duration":"220.634344ms","start":"2026-03-01T12:55:42.551357Z","end":"2026-03-01T12:55:42.771991Z","steps":["trace[189655873] 'agreement among raft nodes before linearized reading' (duration: 220.385979ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T12:55:42.772005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.37689ms","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-03-01T12:55:42.772101Z","caller":"traceutil/trace.go:171","msg":"trace[1792666266] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:966; }","duration":"164.513932ms","start":"2026-03-01T12:55:42.607563Z","end":"2026-03-01T12:55:42.772077Z","steps":["trace[1792666266] 'agreement among raft nodes before linearized reading' (duration: 164.223756ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T12:55:42.771996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.69125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-03-01T12:55:42.772251Z","caller":"traceutil/trace.go:171","msg":"trace[676562603] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:966; }","duration":"158.009468ms","start":"2026-03-01T12:55:42.614213Z","end":"2026-03-01T12:55:42.772222Z","steps":["trace[676562603] 'agreement among raft nodes before linearized reading' (duration: 157.544317ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T12:55:42.772927Z","caller":"traceutil/trace.go:171","msg":"trace[1206293041] transaction","detail":"{read_only:false; response_revision:966; number_of_response:1; }","duration":"225.579841ms","start":"2026-03-01T12:55:42.547321Z","end":"2026-03-01T12:55:42.772901Z","steps":["trace[1206293041] 'process raft request' (duration: 223.83367ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T12:55:42.776005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.130606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-58mpt\" ","response":"range_response_count:1 size:11061"} {"level":"info","ts":"2026-03-01T12:55:42.776146Z","caller":"traceutil/trace.go:171","msg":"trace[725710578] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-58mpt; range_end:; response_count:1; response_revision:966; }","duration":"193.338037ms","start":"2026-03-01T12:55:42.582774Z","end":"2026-03-01T12:55:42.776112Z","steps":["trace[725710578] 'agreement among raft nodes before linearized reading' (duration: 188.963883ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T12:55:43.020711Z","caller":"traceutil/trace.go:171","msg":"trace[1892391813] transaction","detail":"{read_only:false; response_revision:967; number_of_response:1; }","duration":"242.748259ms","start":"2026-03-01T12:55:42.777936Z","end":"2026-03-01T12:55:43.020684Z","steps":["trace[1892391813] 'process raft request' (duration: 242.595305ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T12:55:43.537907Z","caller":"traceutil/trace.go:171","msg":"trace[1212970225] linearizableReadLoop","detail":"{readStateIndex:1006; appliedIndex:1003; }","duration":"672.537794ms","start":"2026-03-01T12:55:42.865343Z","end":"2026-03-01T12:55:43.537881Z","steps":["trace[1212970225] 'read index received' (duration: 155.11089ms)","trace[1212970225] 'applied index is now lower than readState.Index' (duration: 517.426414ms)"],"step_count":2} {"level":"info","ts":"2026-03-01T12:55:43.538165Z","caller":"traceutil/trace.go:171","msg":"trace[1652157341] transaction","detail":"{read_only:false; response_revision:968; number_of_response:1; }","duration":"759.299331ms","start":"2026-03-01T12:55:42.778858Z","end":"2026-03-01T12:55:43.538157Z","steps":["trace[1652157341] 'process raft request' (duration: 758.845559ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T12:55:43.538275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T12:55:42.778827Z","time spent":"759.359463ms","remote":"127.0.0.1:56372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-01T12:55:43.538484Z","caller":"traceutil/trace.go:171","msg":"trace[863466150] transaction","detail":"{read_only:false; response_revision:969; number_of_response:1; }","duration":"750.739349ms","start":"2026-03-01T12:55:42.787739Z","end":"2026-03-01T12:55:43.538479Z","steps":["trace[863466150] 'process raft request' (duration: 750.084282ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T12:55:43.538512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T12:55:42.787717Z","time spent":"750.77926ms","remote":"127.0.0.1:56266","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11375,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-01T12:55:43.538561Z","caller":"traceutil/trace.go:171","msg":"trace[1263683150] transaction","detail":"{read_only:false; response_revision:970; number_of_response:1; }","duration":"649.284502ms","start":"2026-03-01T12:55:42.889245Z","end":"2026-03-01T12:55:43.53853Z","steps":["trace[1263683150] 'process raft request' (duration: 648.611605ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T12:55:43.538714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T12:55:42.889221Z","time spent":"649.403015ms","remote":"127.0.0.1:56372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-01T12:55:43.5388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"673.463936ms","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-03-01T12:55:43.538825Z","caller":"traceutil/trace.go:171","msg":"trace[642737982] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:970; }","duration":"673.538368ms","start":"2026-03-01T12:55:42.86528Z","end":"2026-03-01T12:55:43.538819Z","steps":["trace[642737982] 'agreement among raft nodes before linearized reading' (duration: 673.490586ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T12:55:43.538844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T12:55:42.865262Z","time spent":"673.577609ms","remote":"127.0.0.1:56300","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":29,"request content":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true "} {"level":"warn","ts":"2026-03-01T12:55:43.538964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.108066ms","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-03-01T12:55:43.53899Z","caller":"traceutil/trace.go:171","msg":"trace[99865340] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:970; }","duration":"428.368671ms","start":"2026-03-01T12:55:43.110613Z","end":"2026-03-01T12:55:43.538982Z","steps":["trace[99865340] 'agreement among raft nodes before linearized reading' (duration: 428.251479ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T12:55:43.539009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T12:55:43.110593Z","time spent":"428.412133ms","remote":"127.0.0.1:56372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-01T12:55:43.75178Z","caller":"traceutil/trace.go:171","msg":"trace[1123244754] transaction","detail":"{read_only:false; response_revision:971; number_of_response:1; }","duration":"183.673337ms","start":"2026-03-01T12:55:43.568073Z","end":"2026-03-01T12:55:43.751747Z","steps":["trace[1123244754] 'process raft request' (duration: 90.821585ms)","trace[1123244754] 'compare' (duration: 92.719579ms)"],"step_count":2} {"level":"info","ts":"2026-03-01T12:55:43.752564Z","caller":"traceutil/trace.go:171","msg":"trace[1502711294] transaction","detail":"{read_only:false; response_revision:973; number_of_response:1; }","duration":"181.720351ms","start":"2026-03-01T12:55:43.570822Z","end":"2026-03-01T12:55:43.752542Z","steps":["trace[1502711294] 'process raft request' (duration: 181.561167ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T12:55:43.752542Z","caller":"traceutil/trace.go:171","msg":"trace[1922445609] linearizableReadLoop","detail":"{readStateIndex:1010; appliedIndex:1007; }","duration":"177.645603ms","start":"2026-03-01T12:55:43.574867Z","end":"2026-03-01T12:55:43.752513Z","steps":["trace[1922445609] 'read index received' (duration: 84.066254ms)","trace[1922445609] 'applied index is now lower than readState.Index' (duration: 93.578029ms)"],"step_count":2} {"level":"info","ts":"2026-03-01T12:55:43.752748Z","caller":"traceutil/trace.go:171","msg":"trace[493241915] transaction","detail":"{read_only:false; response_revision:972; number_of_response:1; }","duration":"183.159825ms","start":"2026-03-01T12:55:43.569578Z","end":"2026-03-01T12:55:43.752738Z","steps":["trace[493241915] 'process raft request' (duration: 182.622902ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T12:55:43.753207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.315669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-01T12:55:43.753258Z","caller":"traceutil/trace.go:171","msg":"trace[309938394] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:974; }","duration":"178.38342ms","start":"2026-03-01T12:55:43.574862Z","end":"2026-03-01T12:55:43.753246Z","steps":["trace[309938394] 'agreement among raft nodes before linearized reading' (duration: 178.291868ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T12:55:43.753433Z","caller":"traceutil/trace.go:171","msg":"trace[1371794823] transaction","detail":"{read_only:false; response_revision:974; number_of_response:1; }","duration":"169.753747ms","start":"2026-03-01T12:55:43.583668Z","end":"2026-03-01T12:55:43.753421Z","steps":["trace[1371794823] 'process raft request' (duration: 168.782694ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T12:55:43.753605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.458628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-01T12:55:43.753701Z","caller":"traceutil/trace.go:171","msg":"trace[362920713] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:974; }","duration":"139.640013ms","start":"2026-03-01T12:55:43.614041Z","end":"2026-03-01T12:55:43.753681Z","steps":["trace[362920713] 'agreement among raft nodes before linearized reading' (duration: 139.186321ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T12:55:45.131973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.049865834s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-01T12:55:45.132036Z","caller":"traceutil/trace.go:171","msg":"trace[1378897391] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:975; }","duration":"1.049965387s","start":"2026-03-01T12:55:44.082048Z","end":"2026-03-01T12:55:45.132013Z","steps":["trace[1378897391] 'count revisions from in-memory index tree' (duration: 1.049715191s)"],"step_count":1} {"level":"warn","ts":"2026-03-01T12:55:45.132072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T12:55:44.082025Z","time spent":"1.050036798s","remote":"127.0.0.1:56324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":29,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-03-01T12:55:45.132051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.213251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-01T12:55:45.132178Z","caller":"traceutil/trace.go:171","msg":"trace[1712297677] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:975; }","duration":"351.396115ms","start":"2026-03-01T12:55:44.780737Z","end":"2026-03-01T12:55:45.132133Z","steps":["trace[1712297677] 'range keys from in-memory index tree' (duration: 350.899473ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T12:55:45.132281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T12:55:44.780705Z","time spent":"351.56203ms","remote":"127.0.0.1:56372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-01T12:55:45.132336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"565.935544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-01T12:55:45.132374Z","caller":"traceutil/trace.go:171","msg":"trace[689486275] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:975; }","duration":"566.020636ms","start":"2026-03-01T12:55:44.566342Z","end":"2026-03-01T12:55:45.132363Z","steps":["trace[689486275] 'range keys from in-memory index tree' (duration: 565.837842ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T12:55:45.13195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.207919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-03-01T12:55:45.132394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T12:55:44.5663Z","time spent":"566.089017ms","remote":"127.0.0.1:56136","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-03-01T12:55:45.132655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"517.836323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-01T12:55:45.132684Z","caller":"traceutil/trace.go:171","msg":"trace[360513287] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:975; }","duration":"517.904085ms","start":"2026-03-01T12:55:44.614768Z","end":"2026-03-01T12:55:45.132672Z","steps":["trace[360513287] 'count revisions from in-memory index tree' (duration: 517.736861ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T12:55:45.132704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T12:55:44.614738Z","time spent":"517.958917ms","remote":"127.0.0.1:55326","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":3,"response size":31,"request content":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true "} {"level":"info","ts":"2026-03-01T12:55:45.132409Z","caller":"traceutil/trace.go:171","msg":"trace[2040305065] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:975; }","duration":"106.735642ms","start":"2026-03-01T12:55:45.025665Z","end":"2026-03-01T12:55:45.132401Z","steps":["trace[2040305065] 'range keys from in-memory index tree' (duration: 106.003953ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T12:55:45.52596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.656378ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-01T12:55:45.526067Z","caller":"traceutil/trace.go:171","msg":"trace[390476090] linearizableReadLoop","detail":"{readStateIndex:1014; appliedIndex:1013; }","duration":"347.282878ms","start":"2026-03-01T12:55:45.178762Z","end":"2026-03-01T12:55:45.526045Z","steps":["trace[390476090] 'read index received' (duration: 192.337353ms)","trace[390476090] 'applied index is now lower than readState.Index' (duration: 154.944175ms)"],"step_count":2} {"level":"warn","ts":"2026-03-01T12:55:45.526154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.3976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-01T12:55:45.526183Z","caller":"traceutil/trace.go:171","msg":"trace[1464217584] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:977; }","duration":"347.424511ms","start":"2026-03-01T12:55:45.178748Z","end":"2026-03-01T12:55:45.526173Z","steps":["trace[1464217584] 'agreement among raft nodes before linearized reading' (duration: 347.345899ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T12:55:45.526211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T12:55:45.178682Z","time spent":"347.522594ms","remote":"127.0.0.1:56138","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-03-01T12:55:45.52637Z","caller":"traceutil/trace.go:171","msg":"trace[543382015] transaction","detail":"{read_only:false; response_revision:977; number_of_response:1; }","duration":"387.623485ms","start":"2026-03-01T12:55:45.138701Z","end":"2026-03-01T12:55:45.526324Z","steps":["trace[543382015] 'process raft request' (duration: 232.488815ms)","trace[543382015] 'compare' (duration: 154.519815ms)"],"step_count":2} {"level":"warn","ts":"2026-03-01T12:55:45.526506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T12:55:45.138695Z","time spent":"387.763688ms","remote":"127.0.0.1:56372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-01T12:55:45.931202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.225589ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-01T12:55:45.931297Z","caller":"traceutil/trace.go:171","msg":"trace[714677927] linearizableReadLoop","detail":"{readStateIndex:1015; appliedIndex:1014; }","duration":"170.974006ms","start":"2026-03-01T12:55:45.760305Z","end":"2026-03-01T12:55:45.931279Z","steps":["trace[714677927] 'read index received' (duration: 64.212µs)","trace[714677927] 'applied index is now lower than readState.Index' (duration: 170.908684ms)"],"step_count":2} {"level":"info","ts":"2026-03-01T12:55:45.931555Z","caller":"traceutil/trace.go:171","msg":"trace[780525353] transaction","detail":"{read_only:false; response_revision:978; number_of_response:1; }","duration":"361.912914ms","start":"2026-03-01T12:55:45.569608Z","end":"2026-03-01T12:55:45.931521Z","steps":["trace[780525353] 'process raft request' (duration: 129.306147ms)","trace[780525353] 'compare' (duration: 232.090125ms)"],"step_count":2} {"level":"warn","ts":"2026-03-01T12:55:45.931653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.260971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-01T12:55:45.931681Z","caller":"traceutil/trace.go:171","msg":"trace[396412070] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:978; }","duration":"117.333483ms","start":"2026-03-01T12:55:45.81434Z","end":"2026-03-01T12:55:45.931673Z","steps":["trace[396412070] 'agreement among raft nodes before linearized reading' (duration: 117.21523ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T12:55:45.931717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T12:55:45.569589Z","time spent":"362.050058ms","remote":"127.0.0.1:56372","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-03-01T12:55:45.931964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.653791ms","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-03-01T12:55:45.931997Z","caller":"traceutil/trace.go:171","msg":"trace[424827573] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:978; }","duration":"171.745853ms","start":"2026-03-01T12:55:45.760243Z","end":"2026-03-01T12:55:45.931989Z","steps":["trace[424827573] 'agreement among raft nodes before linearized reading' (duration: 171.118189ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T12:55:46.393492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.213366ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-01T12:55:46.393701Z","caller":"traceutil/trace.go:171","msg":"trace[509337304] linearizableReadLoop","detail":"{readStateIndex:1017; appliedIndex:1015; }","duration":"221.536765ms","start":"2026-03-01T12:55:46.172145Z","end":"2026-03-01T12:55:46.393682Z","steps":["trace[509337304] 'read index received' (duration: 38.130764ms)","trace[509337304] 'applied index is now lower than readState.Index' (duration: 183.404581ms)"],"step_count":2} {"level":"warn","ts":"2026-03-01T12:55:46.393847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.696369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-01T12:55:46.393876Z","caller":"traceutil/trace.go:171","msg":"trace[637657298] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:980; }","duration":"221.777601ms","start":"2026-03-01T12:55:46.172087Z","end":"2026-03-01T12:55:46.393865Z","steps":["trace[637657298] 'agreement among raft nodes before linearized reading' (duration: 221.649168ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T12:55:46.393913Z","caller":"traceutil/trace.go:171","msg":"trace[1264055757] transaction","detail":"{read_only:false; response_revision:979; number_of_response:1; }","duration":"458.097597ms","start":"2026-03-01T12:55:45.935799Z","end":"2026-03-01T12:55:46.393897Z","steps":["trace[1264055757] 'process raft request' (duration: 274.382789ms)","trace[1264055757] 'compare' (duration: 182.732406ms)"],"step_count":2} {"level":"warn","ts":"2026-03-01T12:55:46.393963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T12:55:45.935783Z","time spent":"458.153348ms","remote":"127.0.0.1:56372","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-03-01T12:55:46.39401Z","caller":"traceutil/trace.go:171","msg":"trace[1272026922] transaction","detail":"{read_only:false; response_revision:980; number_of_response:1; }","duration":"456.53508ms","start":"2026-03-01T12:55:45.937433Z","end":"2026-03-01T12:55:46.393969Z","steps":["trace[1272026922] 'process raft request' (duration: 456.180162ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T12:55:46.394231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T12:55:45.937413Z","time spent":"456.679713ms","remote":"127.0.0.1:56372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-01T12:55:46.701984Z","caller":"traceutil/trace.go:171","msg":"trace[91013746] transaction","detail":"{read_only:false; response_revision:981; number_of_response:1; }","duration":"432.088229ms","start":"2026-03-01T12:55:46.26987Z","end":"2026-03-01T12:55:46.701958Z","steps":["trace[91013746] 'process raft request' (duration: 431.855484ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T12:55:46.702071Z","caller":"traceutil/trace.go:171","msg":"trace[2039084112] linearizableReadLoop","detail":"{readStateIndex:1018; appliedIndex:1017; }","duration":"231.231005ms","start":"2026-03-01T12:55:46.470819Z","end":"2026-03-01T12:55:46.70205Z","steps":["trace[2039084112] 'read index received' (duration: 230.818626ms)","trace[2039084112] 'applied index is now lower than readState.Index' (duration: 410.449µs)"],"step_count":2} {"level":"warn","ts":"2026-03-01T12:55:46.70218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T12:55:46.269825Z","time spent":"432.245303ms","remote":"127.0.0.1:56254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5736,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-01T12:55:46.702227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.03569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-01T12:55:46.702254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.40411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-01T12:55:46.702306Z","caller":"traceutil/trace.go:171","msg":"trace[321370451] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:981; }","duration":"150.129992ms","start":"2026-03-01T12:55:46.552157Z","end":"2026-03-01T12:55:46.702287Z","steps":["trace[321370451] 'agreement among raft nodes before linearized reading' (duration: 150.003359ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T12:55:46.702338Z","caller":"traceutil/trace.go:171","msg":"trace[1558494036] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:981; }","duration":"231.562414ms","start":"2026-03-01T12:55:46.470756Z","end":"2026-03-01T12:55:46.702319Z","steps":["trace[1558494036] 'agreement among raft nodes before linearized reading' (duration: 231.384519ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T12:55:47.315815Z","caller":"traceutil/trace.go:171","msg":"trace[455092049] linearizableReadLoop","detail":"{readStateIndex:1019; appliedIndex:1018; }","duration":"133.515337ms","start":"2026-03-01T12:55:47.182271Z","end":"2026-03-01T12:55:47.315787Z","steps":["trace[455092049] 'read index received' (duration: 133.230311ms)","trace[455092049] 'applied index is now lower than readState.Index' (duration: 284.196µs)"],"step_count":2} {"level":"info","ts":"2026-03-01T12:55:47.315887Z","caller":"traceutil/trace.go:171","msg":"trace[1173182032] transaction","detail":"{read_only:false; response_revision:982; number_of_response:1; }","duration":"397.97006ms","start":"2026-03-01T12:55:46.917865Z","end":"2026-03-01T12:55:47.315836Z","steps":["trace[1173182032] 'process raft request' (duration: 397.737384ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T12:55:47.316083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T12:55:46.917834Z","time spent":"398.159614ms","remote":"127.0.0.1:56186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":891,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-01T12:55:47.316158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.851355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-01T12:55:47.316252Z","caller":"traceutil/trace.go:171","msg":"trace[650756278] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:982; }","duration":"134.002009ms","start":"2026-03-01T12:55:47.182229Z","end":"2026-03-01T12:55:47.316231Z","steps":["trace[650756278] 'agreement among raft nodes before linearized reading' (duration: 133.678981ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T12:55:48.031971Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976812220213306229,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-01T12:55:48.08364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"674.451029ms","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-03-01T12:55:48.083806Z","caller":"traceutil/trace.go:171","msg":"trace[1251203820] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:982; }","duration":"674.629233ms","start":"2026-03-01T12:55:47.40915Z","end":"2026-03-01T12:55:48.08378Z","steps":["trace[1251203820] 'range keys from in-memory index tree' (duration: 674.430028ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T12:55:48.117393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"574.885358ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-01T12:55:48.117952Z","caller":"traceutil/trace.go:171","msg":"trace[1669731389] transaction","detail":"{read_only:false; response_revision:983; number_of_response:1; }","duration":"796.917793ms","start":"2026-03-01T12:55:47.320986Z","end":"2026-03-01T12:55:48.117904Z","steps":["trace[1669731389] 'process raft request' (duration: 205.799361ms)","trace[1669731389] 'compare' (duration: 556.8898ms)","trace[1669731389] 'store kv pair into bolt db' {req_type:put; key:/registry/leases/kube-system/kube-scheduler; req_size:460; } (duration: 17.858714ms)"],"step_count":3} {"level":"warn","ts":"2026-03-01T12:55:48.11875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T12:55:47.320951Z","time spent":"797.087547ms","remote":"127.0.0.1:56372","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-03-01T12:55:48.599825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"482.28414ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-03-01T12:55:48.600014Z","caller":"traceutil/trace.go:171","msg":"trace[854518080] linearizableReadLoop","detail":"{readStateIndex:1021; appliedIndex:1019; }","duration":"1.068973098s","start":"2026-03-01T12:55:47.531017Z","end":"2026-03-01T12:55:48.59999Z","steps":["trace[854518080] 'read index received' (duration: 546.525744ms)","trace[854518080] 'applied index is now lower than readState.Index' (duration: 522.442394ms)"],"step_count":2} {"level":"warn","ts":"2026-03-01T12:55:48.60018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.041755292s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-01T12:55:48.600216Z","caller":"traceutil/trace.go:171","msg":"trace[359412257] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:983; }","duration":"1.041835504s","start":"2026-03-01T12:55:47.558369Z","end":"2026-03-01T12:55:48.600204Z","steps":["trace[359412257] 'agreement among raft nodes before linearized reading' (duration: 1.041747842s)"],"step_count":1} {"level":"warn","ts":"2026-03-01T12:55:48.600253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T12:55:47.558352Z","time spent":"1.041892865s","remote":"127.0.0.1:56138","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-03-01T12:55:48.600382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.666818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-01T12:55:48.600471Z","caller":"traceutil/trace.go:171","msg":"trace[1585553688] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:983; }","duration":"202.839442ms","start":"2026-03-01T12:55:48.397611Z","end":"2026-03-01T12:55:48.60045Z","steps":["trace[1585553688] 'agreement among raft nodes before linearized reading' (duration: 202.528955ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T12:55:48.600523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.069431178s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-03-01T12:55:48.600643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.113219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-01T12:55:48.600683Z","caller":"traceutil/trace.go:171","msg":"trace[579586052] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:983; }","duration":"203.194021ms","start":"2026-03-01T12:55:48.397478Z","end":"2026-03-01T12:55:48.600672Z","steps":["trace[579586052] 'agreement among raft nodes before linearized reading' (duration: 203.049027ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T12:55:48.600655Z","caller":"traceutil/trace.go:171","msg":"trace[281449380] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:983; }","duration":"1.069664714s","start":"2026-03-01T12:55:47.530965Z","end":"2026-03-01T12:55:48.60063Z","steps":["trace[281449380] 'agreement among raft nodes before linearized reading' (duration: 1.069116071s)"],"step_count":1} {"level":"warn","ts":"2026-03-01T12:55:48.600786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T12:55:47.530945Z","time spent":"1.069790996s","remote":"127.0.0.1:56372","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-03-01T12:55:48.6008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"664.301587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-03-01T12:55:48.600858Z","caller":"traceutil/trace.go:171","msg":"trace[1404261136] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:983; }","duration":"664.40111ms","start":"2026-03-01T12:55:47.93644Z","end":"2026-03-01T12:55:48.600841Z","steps":["trace[1404261136] 'agreement among raft nodes before linearized reading' (duration: 664.243286ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T12:55:48.600902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T12:55:47.936407Z","time spent":"664.486432ms","remote":"127.0.0.1:56372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-01T12:55:48.601369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.886727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-01T12:55:48.601426Z","caller":"traceutil/trace.go:171","msg":"trace[1777050752] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:983; }","duration":"121.038911ms","start":"2026-03-01T12:55:48.480373Z","end":"2026-03-01T12:55:48.601412Z","steps":["trace[1777050752] 'agreement among raft nodes before linearized reading' (duration: 120.245942ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T12:55:49.209023Z","caller":"traceutil/trace.go:171","msg":"trace[1483341587] transaction","detail":"{read_only:false; response_revision:985; number_of_response:1; }","duration":"602.472091ms","start":"2026-03-01T12:55:48.606523Z","end":"2026-03-01T12:55:49.208995Z","steps":["trace[1483341587] 'process raft request' (duration: 602.285747ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T12:55:49.209269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T12:55:48.606502Z","time spent":"602.675426ms","remote":"127.0.0.1:56372","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-03-01T12:55:49.209491Z","caller":"traceutil/trace.go:171","msg":"trace[421291116] transaction","detail":"{read_only:false; response_revision:986; number_of_response:1; }","duration":"601.884758ms","start":"2026-03-01T12:55:48.607597Z","end":"2026-03-01T12:55:49.209481Z","steps":["trace[421291116] 'process raft request' (duration: 601.349055ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T12:55:49.209617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T12:55:48.60759Z","time spent":"601.99152ms","remote":"127.0.0.1:56372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-01T12:55:49.209677Z","caller":"traceutil/trace.go:171","msg":"trace[670793916] linearizableReadLoop","detail":"{readStateIndex:1025; appliedIndex:1021; }","duration":"599.821868ms","start":"2026-03-01T12:55:48.609797Z","end":"2026-03-01T12:55:49.209619Z","steps":["trace[670793916] 'read index received' (duration: 93.151299ms)","trace[670793916] 'applied index is now lower than readState.Index' (duration: 506.669539ms)"],"step_count":2} {"level":"info","ts":"2026-03-01T12:55:49.209828Z","caller":"traceutil/trace.go:171","msg":"trace[799607680] transaction","detail":"{read_only:false; response_revision:987; number_of_response:1; }","duration":"601.935958ms","start":"2026-03-01T12:55:48.607833Z","end":"2026-03-01T12:55:49.209769Z","steps":["trace[799607680] 'process raft request' (duration: 601.603401ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T12:55:49.209871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"600.050683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-01T12:55:49.209935Z","caller":"traceutil/trace.go:171","msg":"trace[2114912638] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:987; }","duration":"600.160756ms","start":"2026-03-01T12:55:48.609762Z","end":"2026-03-01T12:55:49.209923Z","steps":["trace[2114912638] 'agreement among raft nodes before linearized reading' (duration: 600.016033ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T12:55:49.20999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T12:55:48.609753Z","time spent":"600.206757ms","remote":"127.0.0.1:56138","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-03-01T12:55:49.210135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T12:55:48.607812Z","time spent":"602.072202ms","remote":"127.0.0.1:56372","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-03-01T12:55:49.827801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"513.511901ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-01T12:55:49.827908Z","caller":"traceutil/trace.go:171","msg":"trace[672343538] transaction","detail":"{read_only:false; response_revision:988; number_of_response:1; }","duration":"956.5506ms","start":"2026-03-01T12:55:48.871339Z","end":"2026-03-01T12:55:49.82789Z","steps":["trace[672343538] 'process raft request' (duration: 442.832644ms)","trace[672343538] 'compare' (duration: 513.408169ms)"],"step_count":2} {"level":"warn","ts":"2026-03-01T12:55:49.827961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T12:55:48.871312Z","time spent":"956.624713ms","remote":"127.0.0.1:56372","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-03-01T12:55:50.541272Z","caller":"traceutil/trace.go:171","msg":"trace[30497266] linearizableReadLoop","detail":"{readStateIndex:1027; appliedIndex:1026; }","duration":"261.37304ms","start":"2026-03-01T12:55:50.279869Z","end":"2026-03-01T12:55:50.541242Z","steps":["trace[30497266] 'read index received' (duration: 261.198806ms)","trace[30497266] 'applied index is now lower than readState.Index' (duration: 173.184µs)"],"step_count":2} {"level":"info","ts":"2026-03-01T12:55:50.541366Z","caller":"traceutil/trace.go:171","msg":"trace[1287551853] transaction","detail":"{read_only:false; response_revision:989; number_of_response:1; }","duration":"396.533036ms","start":"2026-03-01T12:55:50.144791Z","end":"2026-03-01T12:55:50.541324Z","steps":["trace[1287551853] 'process raft request' (duration: 396.28066ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T12:55:50.54147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.564695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-01T12:55:50.541534Z","caller":"traceutil/trace.go:171","msg":"trace[1872238955] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:989; }","duration":"261.708259ms","start":"2026-03-01T12:55:50.279811Z","end":"2026-03-01T12:55:50.54152Z","steps":["trace[1872238955] 'agreement among raft nodes before linearized reading' (duration: 261.541505ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T12:55:50.541581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T12:55:50.144762Z","time spent":"396.70885ms","remote":"127.0.0.1:56372","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-03-01T12:55:51.270236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"520.432985ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-01T12:55:51.270536Z","caller":"traceutil/trace.go:171","msg":"trace[1702346503] linearizableReadLoop","detail":"{readStateIndex:1028; appliedIndex:1027; }","duration":"719.004706ms","start":"2026-03-01T12:55:50.551507Z","end":"2026-03-01T12:55:51.270511Z","steps":["trace[1702346503] 'read index received' (duration: 198.11861ms)","trace[1702346503] 'applied index is now lower than readState.Index' (duration: 520.838294ms)"],"step_count":2} {"level":"info","ts":"2026-03-01T12:55:51.270636Z","caller":"traceutil/trace.go:171","msg":"trace[1590778890] transaction","detail":"{read_only:false; response_revision:990; number_of_response:1; }","duration":"719.528828ms","start":"2026-03-01T12:55:50.551041Z","end":"2026-03-01T12:55:51.27057Z","steps":["trace[1590778890] 'process raft request' (duration: 198.650962ms)","trace[1590778890] 'compare' (duration: 519.841331ms)"],"step_count":2} {"level":"warn","ts":"2026-03-01T12:55:51.270888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"719.335423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-01T12:55:51.270928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T12:55:50.551002Z","time spent":"719.775194ms","remote":"127.0.0.1:56186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":787,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-01T12:55:51.271017Z","caller":"traceutil/trace.go:171","msg":"trace[1769158577] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:990; }","duration":"719.548358ms","start":"2026-03-01T12:55:50.551418Z","end":"2026-03-01T12:55:51.270966Z","steps":["trace[1769158577] 'agreement among raft nodes before linearized reading' (duration: 719.281722ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T12:55:51.27113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T12:55:50.551396Z","time spent":"719.719463ms","remote":"127.0.0.1:56138","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-03-01T12:55:51.271622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"619.171747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-01T12:55:51.271677Z","caller":"traceutil/trace.go:171","msg":"trace[149237835] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:0; response_revision:990; }","duration":"619.2699ms","start":"2026-03-01T12:55:50.652394Z","end":"2026-03-01T12:55:51.271664Z","steps":["trace[149237835] 'agreement among raft nodes before linearized reading' (duration: 619.166537ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T12:55:51.271709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T12:55:50.652377Z","time spent":"619.32632ms","remote":"127.0.0.1:56372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":0,"response size":29,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-03-01T12:55:51.272282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.795812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-01T12:55:51.272367Z","caller":"traceutil/trace.go:171","msg":"trace[739230741] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:990; }","duration":"345.884684ms","start":"2026-03-01T12:55:50.92643Z","end":"2026-03-01T12:55:51.272314Z","steps":["trace[739230741] 'agreement among raft nodes before linearized reading' (duration: 345.813772ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T12:55:51.272472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T12:55:50.926403Z","time spent":"345.996747ms","remote":"127.0.0.1:35108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true "} {"level":"warn","ts":"2026-03-01T12:55:51.273083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"566.069147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-01T12:55:51.273127Z","caller":"traceutil/trace.go:171","msg":"trace[301511553] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:990; }","duration":"566.114038ms","start":"2026-03-01T12:55:50.707002Z","end":"2026-03-01T12:55:51.273116Z","steps":["trace[301511553] 'agreement among raft nodes before linearized reading' (duration: 565.997616ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T12:55:51.273157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T12:55:50.706885Z","time spent":"566.265882ms","remote":"127.0.0.1:56372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-01T12:55:51.272341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.658957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-01T12:55:51.273423Z","caller":"traceutil/trace.go:171","msg":"trace[1950647135] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:990; }","duration":"343.820775ms","start":"2026-03-01T12:55:50.929585Z","end":"2026-03-01T12:55:51.273406Z","steps":["trace[1950647135] 'agreement among raft nodes before linearized reading' (duration: 342.570445ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T12:55:51.273507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T12:55:50.92956Z","time spent":"343.932678ms","remote":"127.0.0.1:56186","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":124,"response size":31,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"info","ts":"2026-03-01T12:55:51.647942Z","caller":"traceutil/trace.go:171","msg":"trace[548082301] transaction","detail":"{read_only:false; response_revision:991; number_of_response:1; }","duration":"371.762569ms","start":"2026-03-01T12:55:51.276143Z","end":"2026-03-01T12:55:51.647906Z","steps":["trace[548082301] 'process raft request' (duration: 290.655355ms)","trace[548082301] 'compare' (duration: 80.876928ms)"],"step_count":2} {"level":"warn","ts":"2026-03-01T12:55:51.648306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T12:55:51.276126Z","time spent":"372.013845ms","remote":"127.0.0.1:56372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-01T12:55:51.909971Z","caller":"traceutil/trace.go:171","msg":"trace[1925101891] transaction","detail":"{read_only:false; response_revision:992; number_of_response:1; }","duration":"632.475773ms","start":"2026-03-01T12:55:51.27745Z","end":"2026-03-01T12:55:51.909926Z","steps":["trace[1925101891] 'process raft request' (duration: 632.157016ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T12:55:51.91015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"631.090701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-01T12:55:51.91021Z","caller":"traceutil/trace.go:171","msg":"trace[944528168] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:995; }","duration":"631.188003ms","start":"2026-03-01T12:55:51.27901Z","end":"2026-03-01T12:55:51.910198Z","steps":["trace[944528168] 'agreement among raft nodes before linearized reading' (duration: 631.054889ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T12:55:51.910235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T12:55:51.277425Z","time spent":"632.694659ms","remote":"127.0.0.1:56372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-01T12:55:51.910256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T12:55:51.279003Z","time spent":"631.241584ms","remote":"127.0.0.1:56196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":29,"request content":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" "} {"level":"info","ts":"2026-03-01T12:55:51.910404Z","caller":"traceutil/trace.go:171","msg":"trace[923054248] transaction","detail":"{read_only:false; response_revision:994; number_of_response:1; }","duration":"632.376481ms","start":"2026-03-01T12:55:51.277987Z","end":"2026-03-01T12:55:51.910363Z","steps":["trace[923054248] 'process raft request' (duration: 631.826679ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T12:55:51.910516Z","caller":"traceutil/trace.go:171","msg":"trace[679863462] transaction","detail":"{read_only:false; response_revision:995; number_of_response:1; }","duration":"421.072228ms","start":"2026-03-01T12:55:51.489432Z","end":"2026-03-01T12:55:51.910504Z","steps":["trace[679863462] 'process raft request' (duration: 420.419652ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T12:55:51.910607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T12:55:51.489406Z","time spent":"421.14378ms","remote":"127.0.0.1:56186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":787,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-01T12:55:51.910004Z","caller":"traceutil/trace.go:171","msg":"trace[1549708930] linearizableReadLoop","detail":"{readStateIndex:1032; appliedIndex:1028; }","duration":"630.923906ms","start":"2026-03-01T12:55:51.279034Z","end":"2026-03-01T12:55:51.909958Z","steps":["trace[1549708930] 'read index received' (duration: 287.831828ms)","trace[1549708930] 'applied index is now lower than readState.Index' (duration: 343.090768ms)"],"step_count":2} {"level":"warn","ts":"2026-03-01T12:55:51.910779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T12:55:51.277978Z","time spent":"632.518405ms","remote":"127.0.0.1:56372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-01T12:55:51.91086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.692993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-01T12:55:51.910905Z","caller":"traceutil/trace.go:171","msg":"trace[1823379261] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:995; }","duration":"472.762794ms","start":"2026-03-01T12:55:51.43813Z","end":"2026-03-01T12:55:51.910893Z","steps":["trace[1823379261] 'agreement among raft nodes before linearized reading' (duration: 472.690182ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T12:55:51.910941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T12:55:51.43811Z","time spent":"472.823166ms","remote":"127.0.0.1:56398","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":29,"request content":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true "} {"level":"info","ts":"2026-03-01T12:55:51.911068Z","caller":"traceutil/trace.go:171","msg":"trace[1145144916] transaction","detail":"{read_only:false; response_revision:993; number_of_response:1; }","duration":"633.225761ms","start":"2026-03-01T12:55:51.27783Z","end":"2026-03-01T12:55:51.911056Z","steps":["trace[1145144916] 'process raft request' (duration: 631.942171ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T12:55:51.911262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T12:55:51.277819Z","time spent":"633.368825ms","remote":"127.0.0.1:56372","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-03-01T12:55:52.247591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.541591ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-01T12:55:52.247738Z","caller":"traceutil/trace.go:171","msg":"trace[909159521] transaction","detail":"{read_only:false; response_revision:997; number_of_response:1; }","duration":"332.422255ms","start":"2026-03-01T12:55:51.9153Z","end":"2026-03-01T12:55:52.247722Z","steps":["trace[909159521] 'process raft request' (duration: 332.381834ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T12:55:52.247787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T12:55:51.915276Z","time spent":"332.487827ms","remote":"127.0.0.1:56186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":835,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-01T12:55:52.247937Z","caller":"traceutil/trace.go:171","msg":"trace[547287749] transaction","detail":"{read_only:false; response_revision:996; number_of_response:1; }","duration":"334.121576ms","start":"2026-03-01T12:55:51.913755Z","end":"2026-03-01T12:55:52.247877Z","steps":["trace[547287749] 'process raft request' (duration: 89.228136ms)","trace[547287749] 'compare' (duration: 244.396368ms)"],"step_count":2} {"level":"warn","ts":"2026-03-01T12:55:52.24819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T12:55:51.913724Z","time spent":"334.357112ms","remote":"127.0.0.1:56372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-01T12:55:52.587194Z","caller":"traceutil/trace.go:171","msg":"trace[949939719] transaction","detail":"{read_only:false; response_revision:998; number_of_response:1; }","duration":"331.515394ms","start":"2026-03-01T12:55:52.255657Z","end":"2026-03-01T12:55:52.587172Z","steps":["trace[949939719] 'process raft request' (duration: 331.383351ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T12:55:52.587337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T12:55:52.255627Z","time spent":"331.642248ms","remote":"127.0.0.1:56186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":772,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-01T12:55:53.120059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.786282ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-01T12:55:53.120187Z","caller":"traceutil/trace.go:171","msg":"trace[1935312444] linearizableReadLoop","detail":"{readStateIndex:1037; appliedIndex:1035; }","duration":"854.626083ms","start":"2026-03-01T12:55:52.265536Z","end":"2026-03-01T12:55:53.120162Z","steps":["trace[1935312444] 'read index received' (duration: 321.437616ms)","trace[1935312444] 'applied index is now lower than readState.Index' (duration: 533.187067ms)"],"step_count":2} {"level":"info","ts":"2026-03-01T12:55:53.120248Z","caller":"traceutil/trace.go:171","msg":"trace[1604009943] transaction","detail":"{read_only:false; response_revision:999; number_of_response:1; }","duration":"863.352659ms","start":"2026-03-01T12:55:52.256857Z","end":"2026-03-01T12:55:53.120209Z","steps":["trace[1604009943] 'process raft request' (duration: 673.301151ms)","trace[1604009943] 'compare' (duration: 189.590138ms)"],"step_count":2} {"level":"warn","ts":"2026-03-01T12:55:53.120402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.090164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-03-01T12:55:53.120414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"854.867779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.204\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-03-01T12:55:53.120423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"569.192042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-01T12:55:53.120436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T12:55:52.256834Z","time spent":"863.499323ms","remote":"127.0.0.1:56372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":458,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-01T12:55:53.120456Z","caller":"traceutil/trace.go:171","msg":"trace[1431945612] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:999; }","duration":"100.213248ms","start":"2026-03-01T12:55:53.020228Z","end":"2026-03-01T12:55:53.120441Z","steps":["trace[1431945612] 'agreement among raft nodes before linearized reading' (duration: 100.100005ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T12:55:53.120464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"569.95496ms","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-03-01T12:55:53.120477Z","caller":"traceutil/trace.go:171","msg":"trace[442112417] range","detail":"{range_begin:/registry/masterleases/162.253.55.204; range_end:; response_count:1; response_revision:999; }","duration":"854.978401ms","start":"2026-03-01T12:55:52.265484Z","end":"2026-03-01T12:55:53.120462Z","steps":["trace[442112417] 'agreement among raft nodes before linearized reading' (duration: 854.783497ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T12:55:53.120493Z","caller":"traceutil/trace.go:171","msg":"trace[15868962] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:999; }","duration":"569.294034ms","start":"2026-03-01T12:55:52.551171Z","end":"2026-03-01T12:55:53.120465Z","steps":["trace[15868962] 'agreement among raft nodes before linearized reading' (duration: 569.180732ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T12:55:53.120501Z","caller":"traceutil/trace.go:171","msg":"trace[49387451] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:999; }","duration":"570.042462ms","start":"2026-03-01T12:55:52.550448Z","end":"2026-03-01T12:55:53.12049Z","steps":["trace[49387451] 'agreement among raft nodes before linearized reading' (duration: 569.901589ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T12:55:53.120513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T12:55:52.551141Z","time spent":"569.367256ms","remote":"127.0.0.1:56138","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-03-01T12:55:53.120516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T12:55:52.265471Z","time spent":"855.030462ms","remote":"127.0.0.1:56172","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/162.253.55.204\" "} {"level":"warn","ts":"2026-03-01T12:55:53.120856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T12:55:52.550423Z","time spent":"570.113933ms","remote":"127.0.0.1:56372","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-03-01T12:55:53.892218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.876944ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-01T12:55:53.892674Z","caller":"traceutil/trace.go:171","msg":"trace[976231373] transaction","detail":"{read_only:false; response_revision:1002; number_of_response:1; }","duration":"130.599349ms","start":"2026-03-01T12:55:53.762053Z","end":"2026-03-01T12:55:53.892652Z","steps":["trace[976231373] 'process raft request' (duration: 130.543027ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T12:55:53.892987Z","caller":"traceutil/trace.go:171","msg":"trace[562017517] transaction","detail":"{read_only:false; response_revision:1000; number_of_response:1; }","duration":"768.781467ms","start":"2026-03-01T12:55:53.124188Z","end":"2026-03-01T12:55:53.89297Z","steps":["trace[562017517] 'process raft request' (duration: 330.477359ms)","trace[562017517] 'compare' (duration: 436.691139ms)"],"step_count":2} {"level":"warn","ts":"2026-03-01T12:55:53.893155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T12:55:53.124153Z","time spent":"768.896749ms","remote":"127.0.0.1:56372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-01T12:55:53.893367Z","caller":"traceutil/trace.go:171","msg":"trace[1983903482] linearizableReadLoop","detail":"{readStateIndex:1040; appliedIndex:1038; }","duration":"239.441389ms","start":"2026-03-01T12:55:53.653881Z","end":"2026-03-01T12:55:53.893323Z","steps":["trace[1983903482] 'read index received' (duration: 206.406835ms)","trace[1983903482] 'applied index is now lower than readState.Index' (duration: 33.025474ms)"],"step_count":2} {"level":"warn","ts":"2026-03-01T12:55:53.893696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.785577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-03-01T12:55:53.893667Z","caller":"traceutil/trace.go:171","msg":"trace[1463552151] transaction","detail":"{read_only:false; response_revision:1001; number_of_response:1; }","duration":"676.60823ms","start":"2026-03-01T12:55:53.216895Z","end":"2026-03-01T12:55:53.893503Z","steps":["trace[1463552151] 'process raft request' (duration: 675.586466ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T12:55:53.893743Z","caller":"traceutil/trace.go:171","msg":"trace[291173091] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1002; }","duration":"239.90662ms","start":"2026-03-01T12:55:53.653824Z","end":"2026-03-01T12:55:53.893731Z","steps":["trace[291173091] 'agreement among raft nodes before linearized reading' (duration: 239.648944ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T12:55:53.893958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T12:55:53.216871Z","time spent":"676.907387ms","remote":"127.0.0.1:56172","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-01T12:55:55.210682Z","caller":"traceutil/trace.go:171","msg":"trace[29547763] transaction","detail":"{read_only:false; response_revision:1007; number_of_response:1; }","duration":"188.854499ms","start":"2026-03-01T12:55:55.021797Z","end":"2026-03-01T12:55:55.210651Z","steps":["trace[29547763] 'process raft request' (duration: 188.680735ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T12:55:58.529613Z","caller":"traceutil/trace.go:171","msg":"trace[88123046] transaction","detail":"{read_only:false; response_revision:1031; number_of_response:1; }","duration":"397.727444ms","start":"2026-03-01T12:55:58.131863Z","end":"2026-03-01T12:55:58.52959Z","steps":["trace[88123046] 'process raft request' (duration: 397.606281ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T12:55:58.529745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T12:55:58.131844Z","time spent":"397.833117ms","remote":"127.0.0.1:56372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":458,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-01T12:56:02.095387Z","caller":"traceutil/trace.go:171","msg":"trace[220298527] transaction","detail":"{read_only:false; response_revision:1063; number_of_response:1; }","duration":"106.124998ms","start":"2026-03-01T12:56:01.989231Z","end":"2026-03-01T12:56:02.095356Z","steps":["trace[220298527] 'process raft request' (duration: 106.041546ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T12:56:02.095626Z","caller":"traceutil/trace.go:171","msg":"trace[2131296095] transaction","detail":"{read_only:false; response_revision:1062; number_of_response:1; }","duration":"106.539107ms","start":"2026-03-01T12:56:01.989061Z","end":"2026-03-01T12:56:02.0956Z","steps":["trace[2131296095] 'process raft request' (duration: 62.83013ms)","trace[2131296095] 'compare' (duration: 43.255827ms)"],"step_count":2} {"level":"info","ts":"2026-03-01T12:56:28.402517Z","caller":"traceutil/trace.go:171","msg":"trace[159175623] linearizableReadLoop","detail":"{readStateIndex:1215; appliedIndex:1214; }","duration":"180.593904ms","start":"2026-03-01T12:56:28.221891Z","end":"2026-03-01T12:56:28.402485Z","steps":["trace[159175623] 'read index received' (duration: 130.367042ms)","trace[159175623] 'applied index is now lower than readState.Index' (duration: 50.226322ms)"],"step_count":2} {"level":"info","ts":"2026-03-01T12:56:28.402546Z","caller":"traceutil/trace.go:171","msg":"trace[1968832534] transaction","detail":"{read_only:false; response_revision:1169; number_of_response:1; }","duration":"221.688778ms","start":"2026-03-01T12:56:28.180814Z","end":"2026-03-01T12:56:28.402503Z","steps":["trace[1968832534] 'process raft request' (duration: 171.499538ms)","trace[1968832534] 'compare' (duration: 50.091138ms)"],"step_count":2} {"level":"warn","ts":"2026-03-01T12:56:28.402741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.82454ms","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-03-01T12:56:28.402769Z","caller":"traceutil/trace.go:171","msg":"trace[913295402] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1169; }","duration":"180.916552ms","start":"2026-03-01T12:56:28.221841Z","end":"2026-03-01T12:56:28.402757Z","steps":["trace[913295402] 'agreement among raft nodes before linearized reading' (duration: 180.790379ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T12:56:28.402802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.782228ms","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-03-01T12:56:28.402846Z","caller":"traceutil/trace.go:171","msg":"trace[1565502780] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1169; }","duration":"177.87066ms","start":"2026-03-01T12:56:28.224964Z","end":"2026-03-01T12:56:28.402834Z","steps":["trace[1565502780] 'agreement among raft nodes before linearized reading' (duration: 177.716717ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T12:56:44.440501Z","caller":"traceutil/trace.go:171","msg":"trace[1465175513] linearizableReadLoop","detail":"{readStateIndex:1283; appliedIndex:1282; }","duration":"188.606809ms","start":"2026-03-01T12:56:44.251868Z","end":"2026-03-01T12:56:44.440475Z","steps":["trace[1465175513] 'read index received' (duration: 188.384684ms)","trace[1465175513] 'applied index is now lower than readState.Index' (duration: 221.235µs)"],"step_count":2} {"level":"info","ts":"2026-03-01T12:56:44.440628Z","caller":"traceutil/trace.go:171","msg":"trace[2128112574] transaction","detail":"{read_only:false; response_revision:1234; number_of_response:1; }","duration":"205.608373ms","start":"2026-03-01T12:56:44.234951Z","end":"2026-03-01T12:56:44.440559Z","steps":["trace[2128112574] 'process raft request' (duration: 205.368268ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T12:56:44.440704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.810414ms","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-03-01T12:56:44.440998Z","caller":"traceutil/trace.go:171","msg":"trace[1950736603] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1234; }","duration":"189.127942ms","start":"2026-03-01T12:56:44.251837Z","end":"2026-03-01T12:56:44.440965Z","steps":["trace[1950736603] 'agreement among raft nodes before linearized reading' (duration: 188.737063ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T12:56:44.729125Z","caller":"traceutil/trace.go:171","msg":"trace[1097598140] transaction","detail":"{read_only:false; response_revision:1236; number_of_response:1; }","duration":"215.407125ms","start":"2026-03-01T12:56:44.513684Z","end":"2026-03-01T12:56:44.729091Z","steps":["trace[1097598140] 'process raft request' (duration: 115.494762ms)","trace[1097598140] 'compare' (duration: 99.739219ms)"],"step_count":2} {"level":"warn","ts":"2026-03-01T12:56:44.729454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.130529ms","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-03-01T12:56:44.729553Z","caller":"traceutil/trace.go:171","msg":"trace[1255488959] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1236; }","duration":"215.265052ms","start":"2026-03-01T12:56:44.514258Z","end":"2026-03-01T12:56:44.729523Z","steps":["trace[1255488959] 'agreement among raft nodes before linearized reading' (duration: 215.010866ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T12:56:44.729333Z","caller":"traceutil/trace.go:171","msg":"trace[674090560] linearizableReadLoop","detail":"{readStateIndex:1285; appliedIndex:1284; }","duration":"214.845312ms","start":"2026-03-01T12:56:44.514318Z","end":"2026-03-01T12:56:44.729163Z","steps":["trace[674090560] 'read index received' (duration: 114.839327ms)","trace[674090560] 'applied index is now lower than readState.Index' (duration: 100.003985ms)"],"step_count":2} {"level":"warn","ts":"2026-03-01T12:56:44.730009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.616651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-01T12:56:44.730103Z","caller":"traceutil/trace.go:171","msg":"trace[1998897709] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1236; }","duration":"215.805444ms","start":"2026-03-01T12:56:44.514282Z","end":"2026-03-01T12:56:44.730088Z","steps":["trace[1998897709] 'agreement among raft nodes before linearized reading' (duration: 215.469577ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T12:57:15.036883Z","caller":"traceutil/trace.go:171","msg":"trace[1068700346] linearizableReadLoop","detail":"{readStateIndex:1412; appliedIndex:1411; }","duration":"241.426764ms","start":"2026-03-01T12:57:14.795428Z","end":"2026-03-01T12:57:15.036854Z","steps":["trace[1068700346] 'read index received' (duration: 241.216969ms)","trace[1068700346] 'applied index is now lower than readState.Index' (duration: 209.095µs)"],"step_count":2} {"level":"info","ts":"2026-03-01T12:57:15.036957Z","caller":"traceutil/trace.go:171","msg":"trace[1766968943] transaction","detail":"{read_only:false; response_revision:1357; number_of_response:1; }","duration":"254.270629ms","start":"2026-03-01T12:57:14.782647Z","end":"2026-03-01T12:57:15.036917Z","steps":["trace[1766968943] 'process raft request' (duration: 254.026383ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T12:57:15.037191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.735921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-01T12:57:15.037267Z","caller":"traceutil/trace.go:171","msg":"trace[420924181] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1357; }","duration":"241.885755ms","start":"2026-03-01T12:57:14.795358Z","end":"2026-03-01T12:57:15.037244Z","steps":["trace[420924181] 'agreement among raft nodes before linearized reading' (duration: 241.639559ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T12:57:15.189126Z","caller":"traceutil/trace.go:171","msg":"trace[1673395174] transaction","detail":"{read_only:false; response_revision:1358; number_of_response:1; }","duration":"147.019341ms","start":"2026-03-01T12:57:15.041944Z","end":"2026-03-01T12:57:15.188964Z","steps":["trace[1673395174] 'process raft request' (duration: 66.860278ms)","trace[1673395174] 'compare' (duration: 80.00835ms)"],"step_count":2} {"level":"info","ts":"2026-03-01T12:57:15.21249Z","caller":"traceutil/trace.go:171","msg":"trace[498121318] transaction","detail":"{read_only:false; response_revision:1360; number_of_response:1; }","duration":"170.16546ms","start":"2026-03-01T12:57:15.042289Z","end":"2026-03-01T12:57:15.212455Z","steps":["trace[498121318] 'process raft request' (duration: 170.102468ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T12:57:15.212514Z","caller":"traceutil/trace.go:171","msg":"trace[253263013] transaction","detail":"{read_only:false; response_revision:1359; number_of_response:1; }","duration":"170.350384ms","start":"2026-03-01T12:57:15.042127Z","end":"2026-03-01T12:57:15.212478Z","steps":["trace[253263013] 'process raft request' (duration: 170.086218ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T12:57:15.212662Z","caller":"traceutil/trace.go:171","msg":"trace[581325256] transaction","detail":"{read_only:false; response_revision:1361; number_of_response:1; }","duration":"169.825452ms","start":"2026-03-01T12:57:15.042805Z","end":"2026-03-01T12:57:15.212631Z","steps":["trace[581325256] 'process raft request' (duration: 169.617417ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T12:57:15.995624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.449244ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-01T12:57:15.995882Z","caller":"traceutil/trace.go:171","msg":"trace[519626430] transaction","detail":"{read_only:false; response_revision:1364; number_of_response:1; }","duration":"294.420261ms","start":"2026-03-01T12:57:15.701431Z","end":"2026-03-01T12:57:15.995851Z","steps":["trace[519626430] 'process raft request' (duration: 294.34468ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T12:57:15.995927Z","caller":"traceutil/trace.go:171","msg":"trace[987282821] transaction","detail":"{read_only:false; response_revision:1363; number_of_response:1; }","duration":"346.478128ms","start":"2026-03-01T12:57:15.649409Z","end":"2026-03-01T12:57:15.995887Z","steps":["trace[987282821] 'process raft request' (duration: 136.593454ms)","trace[987282821] 'compare' (duration: 209.237509ms)"],"step_count":2} {"level":"warn","ts":"2026-03-01T12:57:15.996117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T12:57:15.649375Z","time spent":"346.628011ms","remote":"127.0.0.1:56372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":494,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-01T12:57:49.733114Z","caller":"traceutil/trace.go:171","msg":"trace[1920509304] transaction","detail":"{read_only:false; response_revision:1500; number_of_response:1; }","duration":"127.092934ms","start":"2026-03-01T12:57:49.605973Z","end":"2026-03-01T12:57:49.733066Z","steps":["trace[1920509304] 'process raft request' (duration: 55.203513ms)","trace[1920509304] 'compare' (duration: 71.683856ms)"],"step_count":2} {"level":"info","ts":"2026-03-01T12:57:49.733367Z","caller":"traceutil/trace.go:171","msg":"trace[349159648] transaction","detail":"{read_only:false; response_revision:1501; number_of_response:1; }","duration":"127.289379ms","start":"2026-03-01T12:57:49.606067Z","end":"2026-03-01T12:57:49.733356Z","steps":["trace[349159648] 'process raft request' (duration: 126.952671ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T12:59:00.472958Z","caller":"traceutil/trace.go:171","msg":"trace[604871156] transaction","detail":"{read_only:false; response_revision:1811; number_of_response:1; }","duration":"132.44444ms","start":"2026-03-01T12:59:00.340485Z","end":"2026-03-01T12:59:00.472929Z","steps":["trace[604871156] 'process raft request' (duration: 52.183787ms)","trace[604871156] 'compare' (duration: 80.143671ms)"],"step_count":2} {"level":"info","ts":"2026-03-01T12:59:04.446905Z","caller":"traceutil/trace.go:171","msg":"trace[1266993709] transaction","detail":"{read_only:false; response_revision:1828; number_of_response:1; }","duration":"242.042429ms","start":"2026-03-01T12:59:04.204839Z","end":"2026-03-01T12:59:04.446882Z","steps":["trace[1266993709] 'process raft request' (duration: 241.269201ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T12:59:12.636005Z","caller":"traceutil/trace.go:171","msg":"trace[1604698655] transaction","detail":"{read_only:false; response_revision:1867; number_of_response:1; }","duration":"141.600889ms","start":"2026-03-01T12:59:12.494367Z","end":"2026-03-01T12:59:12.635968Z","steps":["trace[1604698655] 'process raft request' (duration: 141.401814ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T12:59:12.786811Z","caller":"traceutil/trace.go:171","msg":"trace[1287578835] transaction","detail":"{read_only:false; response_revision:1868; number_of_response:1; }","duration":"290.255904ms","start":"2026-03-01T12:59:12.496471Z","end":"2026-03-01T12:59:12.786727Z","steps":["trace[1287578835] 'process raft request' (duration: 223.741646ms)","trace[1287578835] 'compare' (duration: 64.554741ms)"],"step_count":2} {"level":"info","ts":"2026-03-01T12:59:12.787295Z","caller":"traceutil/trace.go:171","msg":"trace[1773158384] transaction","detail":"{read_only:false; response_revision:1869; number_of_response:1; }","duration":"290.421718ms","start":"2026-03-01T12:59:12.49684Z","end":"2026-03-01T12:59:12.787262Z","steps":["trace[1773158384] 'process raft request' (duration: 288.626136ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T12:59:13.005938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.794436ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-01T12:59:13.006404Z","caller":"traceutil/trace.go:171","msg":"trace[133735182] transaction","detail":"{read_only:false; response_revision:1870; number_of_response:1; }","duration":"144.277902ms","start":"2026-03-01T12:59:12.862085Z","end":"2026-03-01T12:59:13.006363Z","steps":["trace[133735182] 'process raft request' (duration: 38.935443ms)","trace[133735182] 'compare' (duration: 104.071128ms)"],"step_count":2} {"level":"info","ts":"2026-03-01T12:59:14.028879Z","caller":"traceutil/trace.go:171","msg":"trace[1709281847] transaction","detail":"{read_only:false; response_revision:1871; number_of_response:1; }","duration":"155.298293ms","start":"2026-03-01T12:59:13.87354Z","end":"2026-03-01T12:59:14.028838Z","steps":["trace[1709281847] 'process raft request' (duration: 154.970556ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T12:59:14.272817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.729114ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-01T12:59:14.272949Z","caller":"traceutil/trace.go:171","msg":"trace[1339589713] linearizableReadLoop","detail":"{readStateIndex:1952; appliedIndex:1951; }","duration":"157.553456ms","start":"2026-03-01T12:59:14.115372Z","end":"2026-03-01T12:59:14.272925Z","steps":["trace[1339589713] 'read index received' (duration: 33.509655ms)","trace[1339589713] 'applied index is now lower than readState.Index' (duration: 124.041971ms)"],"step_count":2} {"level":"info","ts":"2026-03-01T12:59:14.273118Z","caller":"traceutil/trace.go:171","msg":"trace[173429135] transaction","detail":"{read_only:false; response_revision:1872; number_of_response:1; }","duration":"226.759247ms","start":"2026-03-01T12:59:14.046291Z","end":"2026-03-01T12:59:14.273051Z","steps":["trace[173429135] 'process raft request' (duration: 102.686745ms)","trace[173429135] 'compare' (duration: 123.583941ms)"],"step_count":2} {"level":"warn","ts":"2026-03-01T12:59:14.273212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.838593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-01T12:59:14.273271Z","caller":"traceutil/trace.go:171","msg":"trace[1320289740] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1872; }","duration":"157.925835ms","start":"2026-03-01T12:59:14.115327Z","end":"2026-03-01T12:59:14.273253Z","steps":["trace[1320289740] 'agreement among raft nodes before linearized reading' (duration: 157.73119ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T12:59:32.346403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.333332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-node-lease/\" range_end:\"/registry/pods/kube-node-lease0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-01T12:59:32.346503Z","caller":"traceutil/trace.go:171","msg":"trace[1441137983] range","detail":"{range_begin:/registry/pods/kube-node-lease/; range_end:/registry/pods/kube-node-lease0; response_count:0; response_revision:1952; }","duration":"109.496126ms","start":"2026-03-01T12:59:32.236986Z","end":"2026-03-01T12:59:32.346482Z","steps":["trace[1441137983] 'range keys from in-memory index tree' (duration: 109.260561ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T12:59:32.346628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.186385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/default/\" range_end:\"/registry/rolebindings/default0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-01T12:59:32.346696Z","caller":"traceutil/trace.go:171","msg":"trace[2130750945] range","detail":"{range_begin:/registry/rolebindings/default/; range_end:/registry/rolebindings/default0; response_count:0; response_revision:1952; }","duration":"105.290347ms","start":"2026-03-01T12:59:32.241389Z","end":"2026-03-01T12:59:32.346679Z","steps":["trace[2130750945] 'range keys from in-memory index tree' (duration: 105.118413ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T12:59:40.535528Z","caller":"traceutil/trace.go:171","msg":"trace[1231416017] transaction","detail":"{read_only:false; response_revision:1987; number_of_response:1; }","duration":"118.618933ms","start":"2026-03-01T12:59:40.416818Z","end":"2026-03-01T12:59:40.535437Z","steps":["trace[1231416017] 'process raft request' (duration: 118.365027ms)"],"step_count":1}