{"level":"warn","ts":"2026-04-01T23:20:26.879604Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-01T23:20:26.87971Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.36: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.36:2380","--initial-cluster=instance=https://162.253.55.36:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.36:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.36: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-04-01T23:20:26.879788Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-01T23:20:26.879802Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.36:2380"]} {"level":"info","ts":"2026-04-01T23:20:26.87982Z","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-04-01T23:20:26.880426Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.36:2379"]} {"level":"info","ts":"2026-04-01T23:20:26.880496Z","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.36:2380"],"listen-peer-urls":["https://162.253.55.36:2380"],"advertise-client-urls":["https://162.253.55.36:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.36:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.36: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-04-01T23:20:26.884068Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.373883ms"} {"level":"info","ts":"2026-04-01T23:20:26.894209Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"cb89ec34a9687f0b","cluster-id":"d8e0d5e222e856c9"} {"level":"info","ts":"2026-04-01T23:20:26.894311Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b switched to configuration voters=()"} {"level":"info","ts":"2026-04-01T23:20:26.89435Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b became follower at term 0"} {"level":"info","ts":"2026-04-01T23:20:26.894375Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft cb89ec34a9687f0b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-01T23:20:26.894394Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b became follower at term 1"} {"level":"info","ts":"2026-04-01T23:20:26.894457Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b switched to configuration voters=(14666513372433383179)"} {"level":"warn","ts":"2026-04-01T23:20:26.900321Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-01T23:20:26.901525Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-01T23:20:26.903194Z","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-04-01T23:20:26.90504Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"cb89ec34a9687f0b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-01T23:20:26.905164Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"cb89ec34a9687f0b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-01T23:20:26.905253Z","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-04-01T23:20:26.90535Z","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-04-01T23:20:26.905373Z","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-04-01T23:20:26.906231Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b switched to configuration voters=(14666513372433383179)"} {"level":"info","ts":"2026-04-01T23:20:26.9064Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"d8e0d5e222e856c9","local-member-id":"cb89ec34a9687f0b","added-peer-id":"cb89ec34a9687f0b","added-peer-peer-urls":["https://162.253.55.36:2380"]} {"level":"info","ts":"2026-04-01T23:20:26.909451Z","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-04-01T23:20:26.90966Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"cb89ec34a9687f0b","initial-advertise-peer-urls":["https://162.253.55.36:2380"],"listen-peer-urls":["https://162.253.55.36:2380"],"advertise-client-urls":["https://162.253.55.36:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.36:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-01T23:20:26.909744Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-01T23:20:26.909822Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.36:2380"} {"level":"info","ts":"2026-04-01T23:20:26.909841Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.36:2380"} {"level":"info","ts":"2026-04-01T23:20:27.895554Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b is starting a new election at term 1"} {"level":"info","ts":"2026-04-01T23:20:27.895625Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b became pre-candidate at term 1"} {"level":"info","ts":"2026-04-01T23:20:27.895731Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b received MsgPreVoteResp from cb89ec34a9687f0b at term 1"} {"level":"info","ts":"2026-04-01T23:20:27.895757Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b became candidate at term 2"} {"level":"info","ts":"2026-04-01T23:20:27.895765Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b received MsgVoteResp from cb89ec34a9687f0b at term 2"} {"level":"info","ts":"2026-04-01T23:20:27.895845Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b became leader at term 2"} {"level":"info","ts":"2026-04-01T23:20:27.895877Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: cb89ec34a9687f0b elected leader cb89ec34a9687f0b at term 2"} {"level":"info","ts":"2026-04-01T23:20:27.897789Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-01T23:20:27.898974Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"cb89ec34a9687f0b","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.36:2379]}","request-path":"/0/members/cb89ec34a9687f0b/attributes","cluster-id":"d8e0d5e222e856c9","publish-timeout":"7s"} {"level":"info","ts":"2026-04-01T23:20:27.898988Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-01T23:20:27.899059Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-01T23:20:27.899268Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-01T23:20:27.899311Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-01T23:20:27.89983Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"d8e0d5e222e856c9","local-member-id":"cb89ec34a9687f0b","cluster-version":"3.5"} {"level":"info","ts":"2026-04-01T23:20:27.899957Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-01T23:20:27.899994Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-01T23:20:27.903073Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.36:2379"} {"level":"info","ts":"2026-04-01T23:20:27.90333Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-04-01T23:20:30.566223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.97355ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-04-01T23:20:30.56635Z","caller":"traceutil/trace.go:171","msg":"trace[1922291044] transaction","detail":"{read_only:false; response_revision:80; number_of_response:1; }","duration":"295.205642ms","start":"2026-04-01T23:20:30.271125Z","end":"2026-04-01T23:20:30.56633Z","steps":["trace[1922291044] 'process raft request' (duration: 38.592711ms)","trace[1922291044] 'compare' (duration: 255.835529ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:20:30.874364Z","caller":"traceutil/trace.go:171","msg":"trace[701947358] transaction","detail":"{read_only:false; response_revision:86; number_of_response:1; }","duration":"106.50295ms","start":"2026-04-01T23:20:30.767832Z","end":"2026-04-01T23:20:30.874335Z","steps":["trace[701947358] 'process raft request' (duration: 64.11023ms)","trace[701947358] 'compare' (duration: 42.216128ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:20:31.07901Z","caller":"traceutil/trace.go:171","msg":"trace[374196622] transaction","detail":"{read_only:false; response_revision:91; number_of_response:1; }","duration":"103.919126ms","start":"2026-04-01T23:20:30.975052Z","end":"2026-04-01T23:20:31.078971Z","steps":["trace[374196622] 'process raft request' (duration: 27.293286ms)","trace[374196622] 'compare' (duration: 76.401726ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T23:20:34.455728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.756319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-01T23:20:34.455787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.069988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/\" range_end:\"/registry/deployments/kube-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T23:20:34.455833Z","caller":"traceutil/trace.go:171","msg":"trace[2105737129] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:0; response_revision:245; }","duration":"171.947001ms","start":"2026-04-01T23:20:34.283861Z","end":"2026-04-01T23:20:34.455808Z","steps":["trace[2105737129] 'range keys from in-memory index tree' (duration: 171.608237ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:20:34.455896Z","caller":"traceutil/trace.go:171","msg":"trace[278737134] range","detail":"{range_begin:/registry/deployments/kube-system/; range_end:/registry/deployments/kube-system0; response_count:0; response_revision:245; }","duration":"130.302981ms","start":"2026-04-01T23:20:34.325556Z","end":"2026-04-01T23:20:34.455859Z","steps":["trace[278737134] 'range keys from in-memory index tree' (duration: 129.992047ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:20:34.53276Z","caller":"traceutil/trace.go:171","msg":"trace[689626872] linearizableReadLoop","detail":"{readStateIndex:252; appliedIndex:251; }","duration":"133.906767ms","start":"2026-04-01T23:20:34.39883Z","end":"2026-04-01T23:20:34.532737Z","steps":["trace[689626872] 'read index received' (duration: 133.798146ms)","trace[689626872] 'applied index is now lower than readState.Index' (duration: 107.361µs)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:20:34.532817Z","caller":"traceutil/trace.go:171","msg":"trace[1095633737] transaction","detail":"{read_only:false; response_revision:246; number_of_response:1; }","duration":"134.010239ms","start":"2026-04-01T23:20:34.398757Z","end":"2026-04-01T23:20:34.532767Z","steps":["trace[1095633737] 'process raft request' (duration: 133.839125ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:20:34.532916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.067029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T23:20:34.532982Z","caller":"traceutil/trace.go:171","msg":"trace[835045688] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:0; response_revision:246; }","duration":"134.180022ms","start":"2026-04-01T23:20:34.39879Z","end":"2026-04-01T23:20:34.53297Z","steps":["trace[835045688] 'agreement among raft nodes before linearized reading' (duration: 134.06318ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:20:34.617374Z","caller":"traceutil/trace.go:171","msg":"trace[314395679] transaction","detail":"{read_only:false; number_of_response:0; response_revision:246; }","duration":"118.4828ms","start":"2026-04-01T23:20:34.498852Z","end":"2026-04-01T23:20:34.617335Z","steps":["trace[314395679] 'process raft request' (duration: 74.246152ms)","trace[314395679] 'compare' (duration: 44.061636ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T23:20:34.617685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.241514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/coredns\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-01T23:20:34.61773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.728598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-04-01T23:20:34.617757Z","caller":"traceutil/trace.go:171","msg":"trace[1888203594] range","detail":"{range_begin:/registry/configmaps/kube-system/coredns; range_end:; response_count:0; response_revision:250; }","duration":"153.342476ms","start":"2026-04-01T23:20:34.464398Z","end":"2026-04-01T23:20:34.617741Z","steps":["trace[1888203594] 'agreement among raft nodes before linearized reading' (duration: 153.199444ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:20:34.617772Z","caller":"traceutil/trace.go:171","msg":"trace[951214974] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:250; }","duration":"158.801929ms","start":"2026-04-01T23:20:34.458955Z","end":"2026-04-01T23:20:34.617757Z","steps":["trace[951214974] 'agreement among raft nodes before linearized reading' (duration: 158.705057ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:20:34.617782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.184969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-04-01T23:20:34.617846Z","caller":"traceutil/trace.go:171","msg":"trace[1820308159] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:250; }","duration":"156.27289ms","start":"2026-04-01T23:20:34.461557Z","end":"2026-04-01T23:20:34.61783Z","steps":["trace[1820308159] 'agreement among raft nodes before linearized reading' (duration: 156.174799ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:20:35.23966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.577238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-04-01T23:20:35.239779Z","caller":"traceutil/trace.go:171","msg":"trace[1232267956] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:273; }","duration":"156.74282ms","start":"2026-04-01T23:20:35.08301Z","end":"2026-04-01T23:20:35.239753Z","steps":["trace[1232267956] 'range keys from in-memory index tree' (duration: 156.402554ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:20:35.554061Z","caller":"traceutil/trace.go:171","msg":"trace[414431504] transaction","detail":"{read_only:false; number_of_response:0; response_revision:277; }","duration":"116.593374ms","start":"2026-04-01T23:20:35.437445Z","end":"2026-04-01T23:20:35.554038Z","steps":["trace[414431504] 'process raft request' (duration: 66.275564ms)","trace[414431504] 'compare' (duration: 50.190219ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:20:35.554091Z","caller":"traceutil/trace.go:171","msg":"trace[514483659] transaction","detail":"{read_only:false; number_of_response:0; response_revision:277; }","duration":"116.602014ms","start":"2026-04-01T23:20:35.437471Z","end":"2026-04-01T23:20:35.554073Z","steps":["trace[514483659] 'process raft request' (duration: 116.511834ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:20:35.554226Z","caller":"traceutil/trace.go:171","msg":"trace[188857485] transaction","detail":"{read_only:false; number_of_response:0; response_revision:277; }","duration":"115.411897ms","start":"2026-04-01T23:20:35.438798Z","end":"2026-04-01T23:20:35.55421Z","steps":["trace[188857485] 'process raft request' (duration: 115.280656ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:20:35.554301Z","caller":"traceutil/trace.go:171","msg":"trace[1704306765] transaction","detail":"{read_only:false; number_of_response:0; response_revision:277; }","duration":"116.511834ms","start":"2026-04-01T23:20:35.437783Z","end":"2026-04-01T23:20:35.554295Z","steps":["trace[1704306765] 'process raft request' (duration: 116.246509ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:20:35.554358Z","caller":"traceutil/trace.go:171","msg":"trace[1728247231] transaction","detail":"{read_only:false; number_of_response:0; response_revision:277; }","duration":"116.512563ms","start":"2026-04-01T23:20:35.437839Z","end":"2026-04-01T23:20:35.554352Z","steps":["trace[1728247231] 'process raft request' (duration: 116.215598ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:20:35.554412Z","caller":"traceutil/trace.go:171","msg":"trace[603254024] transaction","detail":"{read_only:false; response_revision:278; number_of_response:1; }","duration":"114.698925ms","start":"2026-04-01T23:20:35.439707Z","end":"2026-04-01T23:20:35.554406Z","steps":["trace[603254024] 'process raft request' (duration: 114.390881ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:20:35.874661Z","caller":"traceutil/trace.go:171","msg":"trace[212993233] transaction","detail":"{read_only:false; response_revision:284; number_of_response:1; }","duration":"137.772509ms","start":"2026-04-01T23:20:35.736846Z","end":"2026-04-01T23:20:35.874618Z","steps":["trace[212993233] 'process raft request' (duration: 121.974907ms)","trace[212993233] 'compare' (duration: 15.628189ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:20:38.693958Z","caller":"traceutil/trace.go:171","msg":"trace[566467464] transaction","detail":"{read_only:false; response_revision:336; number_of_response:1; }","duration":"225.890166ms","start":"2026-04-01T23:20:38.468035Z","end":"2026-04-01T23:20:38.693925Z","steps":["trace[566467464] 'process raft request' (duration: 153.146672ms)","trace[566467464] 'compare' (duration: 72.618672ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:20:38.699792Z","caller":"traceutil/trace.go:171","msg":"trace[398886982] linearizableReadLoop","detail":"{readStateIndex:353; appliedIndex:349; }","duration":"217.00266ms","start":"2026-04-01T23:20:38.482763Z","end":"2026-04-01T23:20:38.699766Z","steps":["trace[398886982] 'read index received' (duration: 138.441699ms)","trace[398886982] 'applied index is now lower than readState.Index' (duration: 78.558671ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:20:38.69981Z","caller":"traceutil/trace.go:171","msg":"trace[1267159898] transaction","detail":"{read_only:false; response_revision:337; number_of_response:1; }","duration":"230.441794ms","start":"2026-04-01T23:20:38.469294Z","end":"2026-04-01T23:20:38.699736Z","steps":["trace[1267159898] 'process raft request' (duration: 229.958856ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:20:38.700034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.203143ms","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-04-01T23:20:38.700069Z","caller":"traceutil/trace.go:171","msg":"trace[705353555] transaction","detail":"{read_only:false; response_revision:339; number_of_response:1; }","duration":"228.826559ms","start":"2026-04-01T23:20:38.471205Z","end":"2026-04-01T23:20:38.700032Z","steps":["trace[705353555] 'process raft request' (duration: 228.17103ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:20:38.700094Z","caller":"traceutil/trace.go:171","msg":"trace[720270095] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:339; }","duration":"217.349355ms","start":"2026-04-01T23:20:38.482729Z","end":"2026-04-01T23:20:38.700078Z","steps":["trace[720270095] 'agreement among raft nodes before linearized reading' (duration: 217.140441ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:20:38.700303Z","caller":"traceutil/trace.go:171","msg":"trace[537288164] transaction","detail":"{read_only:false; response_revision:338; number_of_response:1; }","duration":"230.099129ms","start":"2026-04-01T23:20:38.470037Z","end":"2026-04-01T23:20:38.700136Z","steps":["trace[537288164] 'process raft request' (duration: 229.293346ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:20:38.91553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.612061ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-01T23:20:38.915628Z","caller":"traceutil/trace.go:171","msg":"trace[820567289] transaction","detail":"{read_only:false; response_revision:341; number_of_response:1; }","duration":"204.349317ms","start":"2026-04-01T23:20:38.711262Z","end":"2026-04-01T23:20:38.915612Z","steps":["trace[820567289] 'process raft request' (duration: 92.593814ms)","trace[820567289] 'compare' (duration: 111.435538ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T23:20:39.244462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.539896ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-01T23:20:39.244714Z","caller":"traceutil/trace.go:171","msg":"trace[1772181692] transaction","detail":"{read_only:false; response_revision:347; number_of_response:1; }","duration":"285.874273ms","start":"2026-04-01T23:20:38.958815Z","end":"2026-04-01T23:20:39.244689Z","steps":["trace[1772181692] 'process raft request' (duration: 285.765241ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:20:39.244772Z","caller":"traceutil/trace.go:171","msg":"trace[144770201] transaction","detail":"{read_only:false; response_revision:346; number_of_response:1; }","duration":"288.488393ms","start":"2026-04-01T23:20:38.956258Z","end":"2026-04-01T23:20:39.244746Z","steps":["trace[144770201] 'process raft request' (duration: 90.580291ms)","trace[144770201] 'compare' (duration: 197.402984ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:20:39.252699Z","caller":"traceutil/trace.go:171","msg":"trace[974512256] transaction","detail":"{read_only:false; response_revision:349; number_of_response:1; }","duration":"253.462241ms","start":"2026-04-01T23:20:38.999212Z","end":"2026-04-01T23:20:39.252674Z","steps":["trace[974512256] 'process raft request' (duration: 253.395991ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:20:39.252786Z","caller":"traceutil/trace.go:171","msg":"trace[1634415619] transaction","detail":"{read_only:false; response_revision:348; number_of_response:1; }","duration":"256.817842ms","start":"2026-04-01T23:20:38.995936Z","end":"2026-04-01T23:20:39.252754Z","steps":["trace[1634415619] 'process raft request' (duration: 256.580439ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:20:39.810316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.620463ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-01T23:20:39.810596Z","caller":"traceutil/trace.go:171","msg":"trace[1504643751] transaction","detail":"{read_only:false; response_revision:352; number_of_response:1; }","duration":"431.433301ms","start":"2026-04-01T23:20:39.379144Z","end":"2026-04-01T23:20:39.810578Z","steps":["trace[1504643751] 'process raft request' (duration: 431.39159ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:20:39.810699Z","caller":"traceutil/trace.go:171","msg":"trace[1518117250] linearizableReadLoop","detail":"{readStateIndex:365; appliedIndex:363; }","duration":"505.41432ms","start":"2026-04-01T23:20:39.305261Z","end":"2026-04-01T23:20:39.810675Z","steps":["trace[1518117250] 'read index received' (duration: 157.353949ms)","trace[1518117250] 'applied index is now lower than readState.Index' (duration: 348.058921ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:20:39.810673Z","caller":"traceutil/trace.go:171","msg":"trace[140226873] transaction","detail":"{read_only:false; response_revision:350; number_of_response:1; }","duration":"750.961701ms","start":"2026-04-01T23:20:39.059675Z","end":"2026-04-01T23:20:39.810637Z","steps":["trace[140226873] 'process raft request' (duration: 402.934941ms)","trace[140226873] 'compare' (duration: 347.433961ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:20:39.810741Z","caller":"traceutil/trace.go:171","msg":"trace[486432472] transaction","detail":"{read_only:false; response_revision:351; number_of_response:1; }","duration":"558.869307ms","start":"2026-04-01T23:20:39.251852Z","end":"2026-04-01T23:20:39.810722Z","steps":["trace[486432472] 'process raft request' (duration: 558.607724ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:20:39.810838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"505.766965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-04-01T23:20:39.810879Z","caller":"traceutil/trace.go:171","msg":"trace[344645762] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:352; }","duration":"505.862866ms","start":"2026-04-01T23:20:39.305006Z","end":"2026-04-01T23:20:39.810869Z","steps":["trace[344645762] 'agreement among raft nodes before linearized reading' (duration: 505.760944ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:20:39.810837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:20:39.059652Z","time spent":"751.109242ms","remote":"127.0.0.1:59704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":777,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-01T23:20:39.810894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"495.714843ms","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-04-01T23:20:39.810898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:20:39.379119Z","time spent":"431.525123ms","remote":"127.0.0.1:59918","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-01T23:20:39.810926Z","caller":"traceutil/trace.go:171","msg":"trace[1570552001] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:352; }","duration":"495.783274ms","start":"2026-04-01T23:20:39.315133Z","end":"2026-04-01T23:20:39.810917Z","steps":["trace[1570552001] 'agreement among raft nodes before linearized reading' (duration: 495.712843ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:20:39.81096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:20:39.315113Z","time spent":"495.841934ms","remote":"127.0.0.1:59918","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-01T23:20:39.810958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:20:39.25183Z","time spent":"558.956929ms","remote":"127.0.0.1:60160","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3804,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-01T23:20:39.810911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:20:39.304986Z","time spent":"505.918457ms","remote":"127.0.0.1:59918","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-01T23:20:39.811046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.979204ms","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-04-01T23:20:39.811075Z","caller":"traceutil/trace.go:171","msg":"trace[550503308] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:352; }","duration":"128.037685ms","start":"2026-04-01T23:20:39.683028Z","end":"2026-04-01T23:20:39.811065Z","steps":["trace[550503308] 'agreement among raft nodes before linearized reading' (duration: 127.985135ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:20:40.043105Z","caller":"traceutil/trace.go:171","msg":"trace[16880417] transaction","detail":"{read_only:false; response_revision:354; number_of_response:1; }","duration":"227.355716ms","start":"2026-04-01T23:20:39.815711Z","end":"2026-04-01T23:20:40.043067Z","steps":["trace[16880417] 'process raft request' (duration: 174.031301ms)","trace[16880417] 'compare' (duration: 53.04203ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:20:40.043364Z","caller":"traceutil/trace.go:171","msg":"trace[1455600233] transaction","detail":"{read_only:false; response_revision:355; number_of_response:1; }","duration":"227.078971ms","start":"2026-04-01T23:20:39.816249Z","end":"2026-04-01T23:20:40.043328Z","steps":["trace[1455600233] 'process raft request' (duration: 226.765247ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:20:40.043533Z","caller":"traceutil/trace.go:171","msg":"trace[1172519671] transaction","detail":"{read_only:false; response_revision:356; number_of_response:1; }","duration":"223.996474ms","start":"2026-04-01T23:20:39.819483Z","end":"2026-04-01T23:20:40.043479Z","steps":["trace[1172519671] 'process raft request' (duration: 223.780361ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:20:41.594795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.395361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7294"} {"level":"info","ts":"2026-04-01T23:20:41.594893Z","caller":"traceutil/trace.go:171","msg":"trace[166517705] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:363; }","duration":"144.550062ms","start":"2026-04-01T23:20:41.45032Z","end":"2026-04-01T23:20:41.59487Z","steps":["trace[166517705] 'range keys from in-memory index tree' (duration: 144.175646ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:20:49.137943Z","caller":"traceutil/trace.go:171","msg":"trace[1429395035] transaction","detail":"{read_only:false; response_revision:385; number_of_response:1; }","duration":"330.18025ms","start":"2026-04-01T23:20:48.807741Z","end":"2026-04-01T23:20:49.137921Z","steps":["trace[1429395035] 'process raft request' (duration: 330.043388ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:20:49.138148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:20:48.807717Z","time spent":"330.355201ms","remote":"127.0.0.1:59822","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4360,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-01T23:20:54.591393Z","caller":"traceutil/trace.go:171","msg":"trace[1108792257] linearizableReadLoop","detail":"{readStateIndex:444; appliedIndex:443; }","duration":"473.146916ms","start":"2026-04-01T23:20:54.118219Z","end":"2026-04-01T23:20:54.591366Z","steps":["trace[1108792257] 'read index received' (duration: 473.079145ms)","trace[1108792257] 'applied index is now lower than readState.Index' (duration: 66.431µs)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:20:54.591891Z","caller":"traceutil/trace.go:171","msg":"trace[1238972161] transaction","detail":"{read_only:false; response_revision:425; number_of_response:1; }","duration":"615.446301ms","start":"2026-04-01T23:20:53.976391Z","end":"2026-04-01T23:20:54.591837Z","steps":["trace[1238972161] 'process raft request' (duration: 614.833362ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:20:54.592126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:20:53.976371Z","time spent":"615.619893ms","remote":"127.0.0.1:59918","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-04-01T23:20:54.592267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.912722ms","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-04-01T23:20:54.592279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.067139ms","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-04-01T23:20:54.592269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.54243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T23:20:54.59231Z","caller":"traceutil/trace.go:171","msg":"trace[273652935] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:425; }","duration":"474.148201ms","start":"2026-04-01T23:20:54.118154Z","end":"2026-04-01T23:20:54.592302Z","steps":["trace[273652935] 'agreement among raft nodes before linearized reading' (duration: 474.064709ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:20:54.592313Z","caller":"traceutil/trace.go:171","msg":"trace[809890467] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:425; }","duration":"415.986204ms","start":"2026-04-01T23:20:54.176312Z","end":"2026-04-01T23:20:54.592298Z","steps":["trace[809890467] 'agreement among raft nodes before linearized reading' (duration: 415.908292ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:20:54.592337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:20:54.176292Z","time spent":"416.038264ms","remote":"127.0.0.1:59918","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-04-01T23:20:54.592337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:20:54.118128Z","time spent":"474.201761ms","remote":"127.0.0.1:59918","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-04-01T23:20:54.592318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.396392ms","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-04-01T23:20:54.592342Z","caller":"traceutil/trace.go:171","msg":"trace[2004080041] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:425; }","duration":"195.668532ms","start":"2026-04-01T23:20:54.396649Z","end":"2026-04-01T23:20:54.592317Z","steps":["trace[2004080041] 'agreement among raft nodes before linearized reading' (duration: 195.56241ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:20:54.592391Z","caller":"traceutil/trace.go:171","msg":"trace[1600114054] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:425; }","duration":"434.498523ms","start":"2026-04-01T23:20:54.157874Z","end":"2026-04-01T23:20:54.592372Z","steps":["trace[1600114054] 'agreement among raft nodes before linearized reading' (duration: 434.34233ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:20:54.592431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:20:54.157852Z","time spent":"434.571253ms","remote":"127.0.0.1:59918","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-04-01T23:20:55.098004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.973224ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-01T23:20:55.098256Z","caller":"traceutil/trace.go:171","msg":"trace[1534248108] transaction","detail":"{read_only:false; response_revision:426; number_of_response:1; }","duration":"501.673579ms","start":"2026-04-01T23:20:54.596551Z","end":"2026-04-01T23:20:55.098225Z","steps":["trace[1534248108] 'process raft request' (duration: 281.37408ms)","trace[1534248108] 'compare' (duration: 219.748441ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:20:55.098303Z","caller":"traceutil/trace.go:171","msg":"trace[268006274] transaction","detail":"{read_only:false; response_revision:428; number_of_response:1; }","duration":"500.195927ms","start":"2026-04-01T23:20:54.598087Z","end":"2026-04-01T23:20:55.098283Z","steps":["trace[268006274] 'process raft request' (duration: 500.144757ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:20:55.098373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:20:54.598079Z","time spent":"500.262948ms","remote":"127.0.0.1:59918","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-01T23:20:55.09851Z","caller":"traceutil/trace.go:171","msg":"trace[234994324] transaction","detail":"{read_only:false; response_revision:427; number_of_response:1; }","duration":"501.544207ms","start":"2026-04-01T23:20:54.596947Z","end":"2026-04-01T23:20:55.098491Z","steps":["trace[234994324] 'process raft request' (duration: 501.188942ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:20:55.098604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:20:54.596934Z","time spent":"501.616719ms","remote":"127.0.0.1:59918","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-01T23:20:55.098604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:20:54.59652Z","time spent":"501.816981ms","remote":"127.0.0.1:59918","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-04-01T23:21:04.720768Z","caller":"traceutil/trace.go:171","msg":"trace[2073745810] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"630.479857ms","start":"2026-04-01T23:21:04.090265Z","end":"2026-04-01T23:21:04.720745Z","steps":["trace[2073745810] 'process raft request' (duration: 630.377946ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:21:04.720883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:21:04.090241Z","time spent":"630.58847ms","remote":"127.0.0.1:59668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-01T23:21:04.729563Z","caller":"traceutil/trace.go:171","msg":"trace[405626655] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"635.51748ms","start":"2026-04-01T23:21:04.094013Z","end":"2026-04-01T23:21:04.72953Z","steps":["trace[405626655] 'process raft request' (duration: 635.311047ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:21:04.72976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:21:04.094Z","time spent":"635.687772ms","remote":"127.0.0.1:59668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-01T23:21:04.729933Z","caller":"traceutil/trace.go:171","msg":"trace[1975975697] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"622.443702ms","start":"2026-04-01T23:21:04.107473Z","end":"2026-04-01T23:21:04.729917Z","steps":["trace[1975975697] 'process raft request' (duration: 622.099628ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:21:04.730063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:21:04.107463Z","time spent":"622.566874ms","remote":"127.0.0.1:59668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-01T23:21:04.730117Z","caller":"traceutil/trace.go:171","msg":"trace[1590601880] linearizableReadLoop","detail":"{readStateIndex:515; appliedIndex:503; }","duration":"337.189513ms","start":"2026-04-01T23:21:04.39291Z","end":"2026-04-01T23:21:04.7301Z","steps":["trace[1590601880] 'read index received' (duration: 327.705878ms)","trace[1590601880] 'applied index is now lower than readState.Index' (duration: 9.482885ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:21:04.730228Z","caller":"traceutil/trace.go:171","msg":"trace[1180706376] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"622.664576ms","start":"2026-04-01T23:21:04.107555Z","end":"2026-04-01T23:21:04.730219Z","steps":["trace[1180706376] 'process raft request' (duration: 622.050497ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:21:04.730269Z","caller":"traceutil/trace.go:171","msg":"trace[1061135680] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"600.636309ms","start":"2026-04-01T23:21:04.129613Z","end":"2026-04-01T23:21:04.73025Z","steps":["trace[1061135680] 'process raft request' (duration: 600.281484ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:21:04.730305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:21:04.107548Z","time spent":"622.702686ms","remote":"127.0.0.1:59668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-01T23:21:04.73034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:21:04.129599Z","time spent":"600.70744ms","remote":"127.0.0.1:59668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-01T23:21:04.730332Z","caller":"traceutil/trace.go:171","msg":"trace[388970959] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"630.327425ms","start":"2026-04-01T23:21:04.099909Z","end":"2026-04-01T23:21:04.730236Z","steps":["trace[388970959] 'process raft request' (duration: 629.514104ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:21:04.730435Z","caller":"traceutil/trace.go:171","msg":"trace[1376958507] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"620.805669ms","start":"2026-04-01T23:21:04.10962Z","end":"2026-04-01T23:21:04.730426Z","steps":["trace[1376958507] 'process raft request' (duration: 620.037838ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:21:04.730478Z","caller":"traceutil/trace.go:171","msg":"trace[1899150695] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"588.648628ms","start":"2026-04-01T23:21:04.141815Z","end":"2026-04-01T23:21:04.730464Z","steps":["trace[1899150695] 'process raft request' (duration: 588.133391ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:21:04.730451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:21:04.099877Z","time spent":"630.548438ms","remote":"127.0.0.1:59668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-01T23:21:04.730529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:21:04.141795Z","time spent":"588.713679ms","remote":"127.0.0.1:59668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-01T23:21:04.730618Z","caller":"traceutil/trace.go:171","msg":"trace[1347516822] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"452.04267ms","start":"2026-04-01T23:21:04.278565Z","end":"2026-04-01T23:21:04.730608Z","steps":["trace[1347516822] 'process raft request' (duration: 451.482741ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:21:04.730552Z","caller":"traceutil/trace.go:171","msg":"trace[1249404706] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"618.436525ms","start":"2026-04-01T23:21:04.112011Z","end":"2026-04-01T23:21:04.730447Z","steps":["trace[1249404706] 'process raft request' (duration: 617.736886ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:21:04.730617Z","caller":"traceutil/trace.go:171","msg":"trace[359795386] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"627.343882ms","start":"2026-04-01T23:21:04.103247Z","end":"2026-04-01T23:21:04.730591Z","steps":["trace[359795386] 'process raft request' (duration: 626.221276ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:21:04.730734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.824172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-01T23:21:04.730756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:21:04.103229Z","time spent":"627.464083ms","remote":"127.0.0.1:59668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-01T23:21:04.730778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:21:04.111991Z","time spent":"618.688958ms","remote":"127.0.0.1:59668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-01T23:21:04.73048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:21:04.109613Z","time spent":"620.847539ms","remote":"127.0.0.1:59668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-01T23:21:04.730669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:21:04.278536Z","time spent":"452.11243ms","remote":"127.0.0.1:59668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-01T23:21:04.730512Z","caller":"traceutil/trace.go:171","msg":"trace[1698784817] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"612.881435ms","start":"2026-04-01T23:21:04.117623Z","end":"2026-04-01T23:21:04.730505Z","steps":["trace[1698784817] 'process raft request' (duration: 612.177905ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:21:04.731772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:21:04.117606Z","time spent":"614.107553ms","remote":"127.0.0.1:59668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-01T23:21:04.730873Z","caller":"traceutil/trace.go:171","msg":"trace[632331981] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:494; }","duration":"337.916994ms","start":"2026-04-01T23:21:04.39286Z","end":"2026-04-01T23:21:04.730777Z","steps":["trace[632331981] 'agreement among raft nodes before linearized reading' (duration: 337.759412ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:21:04.73575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:21:04.39284Z","time spent":"342.883245ms","remote":"127.0.0.1:59614","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-04-01T23:21:04.742224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.96855ms","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-04-01T23:21:04.742292Z","caller":"traceutil/trace.go:171","msg":"trace[1750661640] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:496; }","duration":"111.098573ms","start":"2026-04-01T23:21:04.631178Z","end":"2026-04-01T23:21:04.742277Z","steps":["trace[1750661640] 'agreement among raft nodes before linearized reading' (duration: 110.91691ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:21:06.518814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"567.395521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/backendtrafficpolicies.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:98713"} {"level":"info","ts":"2026-04-01T23:21:06.518883Z","caller":"traceutil/trace.go:171","msg":"trace[2122033663] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/backendtrafficpolicies.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:559; }","duration":"567.498453ms","start":"2026-04-01T23:21:05.951369Z","end":"2026-04-01T23:21:06.518868Z","steps":["trace[2122033663] 'range keys from in-memory index tree' (duration: 567.201809ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:21:06.518913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:21:05.951357Z","time spent":"567.550153ms","remote":"127.0.0.1:59668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":103,"response count":1,"response size":98737,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/backendtrafficpolicies.gateway.envoyproxy.io\" "} {"level":"info","ts":"2026-04-01T23:21:06.51944Z","caller":"traceutil/trace.go:171","msg":"trace[2026474546] linearizableReadLoop","detail":"{readStateIndex:585; appliedIndex:584; }","duration":"126.930926ms","start":"2026-04-01T23:21:06.392497Z","end":"2026-04-01T23:21:06.519428Z","steps":["trace[2026474546] 'read index received' (duration: 126.370276ms)","trace[2026474546] 'applied index is now lower than readState.Index' (duration: 560.14µs)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:21:06.519479Z","caller":"traceutil/trace.go:171","msg":"trace[287698225] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"465.737279ms","start":"2026-04-01T23:21:06.053732Z","end":"2026-04-01T23:21:06.519469Z","steps":["trace[287698225] 'process raft request' (duration: 465.300722ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:21:06.519516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.042349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T23:21:06.519541Z","caller":"traceutil/trace.go:171","msg":"trace[1731384545] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:560; }","duration":"127.104179ms","start":"2026-04-01T23:21:06.392429Z","end":"2026-04-01T23:21:06.519533Z","steps":["trace[1731384545] 'agreement among raft nodes before linearized reading' (duration: 127.062187ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:21:06.519547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:21:06.053705Z","time spent":"465.795972ms","remote":"127.0.0.1:59668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":570090,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-01T23:21:11.46471Z","caller":"traceutil/trace.go:171","msg":"trace[751122811] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"628.059626ms","start":"2026-04-01T23:21:10.836612Z","end":"2026-04-01T23:21:11.464671Z","steps":["trace[751122811] 'process raft request' (duration: 627.082783ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:21:11.464938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:21:10.836592Z","time spent":"628.237405ms","remote":"127.0.0.1:59726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036257,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-01T23:21:11.465213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.901737ms","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-04-01T23:21:11.465328Z","caller":"traceutil/trace.go:171","msg":"trace[1730050144] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:588; }","duration":"267.062115ms","start":"2026-04-01T23:21:11.198236Z","end":"2026-04-01T23:21:11.465298Z","steps":["trace[1730050144] 'agreement among raft nodes before linearized reading' (duration: 266.750791ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:21:11.465363Z","caller":"traceutil/trace.go:171","msg":"trace[1833133090] linearizableReadLoop","detail":"{readStateIndex:614; appliedIndex:613; }","duration":"265.949435ms","start":"2026-04-01T23:21:11.198281Z","end":"2026-04-01T23:21:11.46423Z","steps":["trace[1833133090] 'read index received' (duration: 265.076036ms)","trace[1833133090] 'applied index is now lower than readState.Index' (duration: 872.478µs)"],"step_count":2} {"level":"warn","ts":"2026-04-01T23:21:11.465596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.436342ms","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-04-01T23:21:11.465632Z","caller":"traceutil/trace.go:171","msg":"trace[858009348] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:588; }","duration":"263.490246ms","start":"2026-04-01T23:21:11.202129Z","end":"2026-04-01T23:21:11.465619Z","steps":["trace[858009348] 'agreement among raft nodes before linearized reading' (duration: 263.334449ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:21:11.465906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.163349ms","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-04-01T23:21:11.465936Z","caller":"traceutil/trace.go:171","msg":"trace[622222023] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:588; }","duration":"256.215981ms","start":"2026-04-01T23:21:11.209711Z","end":"2026-04-01T23:21:11.465927Z","steps":["trace[622222023] 'agreement among raft nodes before linearized reading' (duration: 256.129597ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:21:12.029898Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9154583614504440635,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-01T23:21:12.27502Z","caller":"traceutil/trace.go:171","msg":"trace[1068269141] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"803.688388ms","start":"2026-04-01T23:21:11.471298Z","end":"2026-04-01T23:21:12.274986Z","steps":["trace[1068269141] 'process raft request' (duration: 803.467079ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:21:12.275246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:21:11.471268Z","time spent":"803.870268ms","remote":"127.0.0.1:59918","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-01T23:21:12.670965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.063015ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-01T23:21:12.6713Z","caller":"traceutil/trace.go:171","msg":"trace[1403180414] linearizableReadLoop","detail":"{readStateIndex:618; appliedIndex:614; }","duration":"1.14209869s","start":"2026-04-01T23:21:11.52918Z","end":"2026-04-01T23:21:12.671279Z","steps":["trace[1403180414] 'read index received' (duration: 745.499241ms)","trace[1403180414] 'applied index is now lower than readState.Index' (duration: 396.475594ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:21:12.671306Z","caller":"traceutil/trace.go:171","msg":"trace[674364857] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"1.198804322s","start":"2026-04-01T23:21:11.472465Z","end":"2026-04-01T23:21:12.67127Z","steps":["trace[674364857] 'process raft request' (duration: 1.198595292s)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:21:12.671422Z","caller":"traceutil/trace.go:171","msg":"trace[164264983] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"1.147158136s","start":"2026-04-01T23:21:11.524184Z","end":"2026-04-01T23:21:12.671342Z","steps":["trace[164264983] 'process raft request' (duration: 1.146930565s)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:21:12.671518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:21:11.524159Z","time spent":"1.147313243s","remote":"127.0.0.1:60028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-01T23:21:12.671545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:21:11.472442Z","time spent":"1.198962419s","remote":"127.0.0.1:59918","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-01T23:21:12.671676Z","caller":"traceutil/trace.go:171","msg":"trace[827848096] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"1.199360606s","start":"2026-04-01T23:21:11.472302Z","end":"2026-04-01T23:21:12.671662Z","steps":["trace[827848096] 'process raft request' (duration: 816.525837ms)","trace[827848096] 'compare' (duration: 381.93963ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T23:21:12.671729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:21:11.472236Z","time spent":"1.199468802s","remote":"127.0.0.1:59918","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-04-01T23:21:12.671781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.142598232s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-mxzrm\" ","response":"range_response_count:1 size:16961"} {"level":"warn","ts":"2026-04-01T23:21:12.671805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.821073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.36\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-01T23:21:12.671825Z","caller":"traceutil/trace.go:171","msg":"trace[144707701] range","detail":"{range_begin:/registry/pods/kube-system/cilium-mxzrm; range_end:; response_count:1; response_revision:592; }","duration":"1.142671276s","start":"2026-04-01T23:21:11.52914Z","end":"2026-04-01T23:21:12.671811Z","steps":["trace[144707701] 'agreement among raft nodes before linearized reading' (duration: 1.142359872s)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:21:12.671861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.873724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-01T23:21:12.671865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:21:11.52913Z","time spent":"1.142727187s","remote":"127.0.0.1:59828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16985,"request content":"key:\"/registry/pods/kube-system/cilium-mxzrm\" "} {"level":"info","ts":"2026-04-01T23:21:12.671878Z","caller":"traceutil/trace.go:171","msg":"trace[145869770] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:592; }","duration":"276.922426ms","start":"2026-04-01T23:21:12.39495Z","end":"2026-04-01T23:21:12.671872Z","steps":["trace[145869770] 'agreement among raft nodes before linearized reading' (duration: 276.896095ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:21:12.671833Z","caller":"traceutil/trace.go:171","msg":"trace[1276690442] range","detail":"{range_begin:/registry/masterleases/162.253.55.36; range_end:; response_count:1; response_revision:592; }","duration":"371.902626ms","start":"2026-04-01T23:21:12.299923Z","end":"2026-04-01T23:21:12.671826Z","steps":["trace[1276690442] 'agreement among raft nodes before linearized reading' (duration: 371.842133ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:21:12.671959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:21:12.299883Z","time spent":"372.067004ms","remote":"127.0.0.1:59654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/162.253.55.36\" "} {"level":"warn","ts":"2026-04-01T23:21:13.185445Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9154583614504440642,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-01T23:21:13.686545Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9154583614504440642,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-01T23:21:13.803209Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.130039168s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-01T23:21:14.478157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"674.645616ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"warn","ts":"2026-04-01T23:21:14.478273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:21:12.67301Z","time spent":"1.805258379s","remote":"127.0.0.1:59654","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-04-01T23:21:14.483118Z","caller":"traceutil/trace.go:171","msg":"trace[403817590] linearizableReadLoop","detail":"{readStateIndex:620; appliedIndex:618; }","duration":"1.798703574s","start":"2026-04-01T23:21:12.684399Z","end":"2026-04-01T23:21:14.483102Z","steps":["trace[403817590] 'read index received' (duration: 1.119077632s)","trace[403817590] 'applied index is now lower than readState.Index' (duration: 679.625272ms)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:21:14.483355Z","caller":"traceutil/trace.go:171","msg":"trace[808947745] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"1.794043479s","start":"2026-04-01T23:21:12.6893Z","end":"2026-04-01T23:21:14.483344Z","steps":["trace[808947745] 'process raft request' (duration: 1.793712194s)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:21:14.483434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:21:12.689278Z","time spent":"1.794103121s","remote":"127.0.0.1:59828","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-01T23:21:14.483417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.798972636s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T23:21:14.483536Z","caller":"traceutil/trace.go:171","msg":"trace[1193655540] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system; range_end:; response_count:0; response_revision:593; }","duration":"1.799148334s","start":"2026-04-01T23:21:12.684362Z","end":"2026-04-01T23:21:14.48351Z","steps":["trace[1193655540] 'agreement among raft nodes before linearized reading' (duration: 1.798926195s)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:21:14.483578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:21:12.684348Z","time spent":"1.799221807s","remote":"127.0.0.1:60184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":29,"request content":"key:\"/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system\" "} {"level":"warn","ts":"2026-04-01T23:21:14.509922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.114244808s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-01T23:21:14.510005Z","caller":"traceutil/trace.go:171","msg":"trace[1321835613] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:594; }","duration":"1.114379605s","start":"2026-04-01T23:21:13.395608Z","end":"2026-04-01T23:21:14.509987Z","steps":["trace[1321835613] 'agreement among raft nodes before linearized reading' (duration: 1.114208997s)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:21:14.510041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:21:13.395589Z","time spent":"1.114446007s","remote":"127.0.0.1:59614","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-04-01T23:21:14.510053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.665064ms","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-04-01T23:21:14.510145Z","caller":"traceutil/trace.go:171","msg":"trace[1131209232] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:594; }","duration":"229.833732ms","start":"2026-04-01T23:21:14.280287Z","end":"2026-04-01T23:21:14.510121Z","steps":["trace[1131209232] 'agreement among raft nodes before linearized reading' (duration: 229.461967ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:21:14.510557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.613262162s","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-04-01T23:21:14.510596Z","caller":"traceutil/trace.go:171","msg":"trace[1071754897] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:594; }","duration":"1.613405597s","start":"2026-04-01T23:21:12.897183Z","end":"2026-04-01T23:21:14.510588Z","steps":["trace[1071754897] 'agreement among raft nodes before linearized reading' (duration: 1.612538999s)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:21:14.510621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:21:12.897147Z","time spent":"1.613467s","remote":"127.0.0.1:59918","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-04-01T23:21:15.913045Z","caller":"traceutil/trace.go:171","msg":"trace[901640442] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"263.302417ms","start":"2026-04-01T23:21:15.649698Z","end":"2026-04-01T23:21:15.913Z","steps":["trace[901640442] 'process raft request' (duration: 257.622793ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:21:19.85768Z","caller":"traceutil/trace.go:171","msg":"trace[642445520] transaction","detail":"{read_only:false; response_revision:678; number_of_response:1; }","duration":"138.979506ms","start":"2026-04-01T23:21:19.718665Z","end":"2026-04-01T23:21:19.857645Z","steps":["trace[642445520] 'process raft request' (duration: 135.080095ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:21:31.136891Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9154583614504440968,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-01T23:21:31.270022Z","caller":"traceutil/trace.go:171","msg":"trace[398900129] transaction","detail":"{read_only:false; response_revision:760; number_of_response:1; }","duration":"657.929493ms","start":"2026-04-01T23:21:30.61206Z","end":"2026-04-01T23:21:31.26999Z","steps":["trace[398900129] 'process raft request' (duration: 657.729886ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:21:31.270423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:21:30.612042Z","time spent":"658.074498ms","remote":"127.0.0.1:59918","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-01T23:21:31.277627Z","caller":"traceutil/trace.go:171","msg":"trace[849569868] transaction","detail":"{read_only:false; response_revision:761; number_of_response:1; }","duration":"664.615893ms","start":"2026-04-01T23:21:30.612986Z","end":"2026-04-01T23:21:31.277602Z","steps":["trace[849569868] 'process raft request' (duration: 664.366193ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:21:31.277712Z","caller":"traceutil/trace.go:171","msg":"trace[1511295014] transaction","detail":"{read_only:false; response_revision:762; number_of_response:1; }","duration":"469.741819ms","start":"2026-04-01T23:21:30.807911Z","end":"2026-04-01T23:21:31.277653Z","steps":["trace[1511295014] 'process raft request' (duration: 469.622984ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:21:31.277618Z","caller":"traceutil/trace.go:171","msg":"trace[1574231432] linearizableReadLoop","detail":"{readStateIndex:794; appliedIndex:791; }","duration":"641.644028ms","start":"2026-04-01T23:21:30.635943Z","end":"2026-04-01T23:21:31.277588Z","steps":["trace[1574231432] 'read index received' (duration: 633.851448ms)","trace[1574231432] 'applied index is now lower than readState.Index' (duration: 7.79132ms)"],"step_count":2} {"level":"warn","ts":"2026-04-01T23:21:31.27791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:21:30.807885Z","time spent":"469.930095ms","remote":"127.0.0.1:59918","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-04-01T23:21:31.277885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:21:30.612956Z","time spent":"664.778079ms","remote":"127.0.0.1:59918","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-04-01T23:21:31.278037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"523.81622ms","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-04-01T23:21:31.278087Z","caller":"traceutil/trace.go:171","msg":"trace[156971580] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:762; }","duration":"523.913044ms","start":"2026-04-01T23:21:30.75416Z","end":"2026-04-01T23:21:31.278073Z","steps":["trace[156971580] 'agreement among raft nodes before linearized reading' (duration: 523.729307ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:21:31.278122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:21:30.754143Z","time spent":"523.970986ms","remote":"127.0.0.1:59918","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-04-01T23:21:31.278041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"517.714912ms","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-04-01T23:21:31.278293Z","caller":"traceutil/trace.go:171","msg":"trace[1401313898] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:762; }","duration":"517.966731ms","start":"2026-04-01T23:21:30.760293Z","end":"2026-04-01T23:21:31.27826Z","steps":["trace[1401313898] 'agreement among raft nodes before linearized reading' (duration: 517.654889ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:21:31.278367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:21:30.760283Z","time spent":"518.072045ms","remote":"127.0.0.1:59918","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-04-01T23:21:31.278175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.90494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-01T23:21:31.278002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"642.024783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-chwt2\" ","response":"range_response_count:1 size:3964"} {"level":"info","ts":"2026-04-01T23:21:31.278588Z","caller":"traceutil/trace.go:171","msg":"trace[262569408] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-chwt2; range_end:; response_count:1; response_revision:762; }","duration":"642.653227ms","start":"2026-04-01T23:21:30.635916Z","end":"2026-04-01T23:21:31.278569Z","steps":["trace[262569408] 'agreement among raft nodes before linearized reading' (duration: 641.770234ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:21:31.278632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:21:30.635904Z","time spent":"642.715749ms","remote":"127.0.0.1:59828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3988,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-chwt2\" "} {"level":"info","ts":"2026-04-01T23:21:31.278509Z","caller":"traceutil/trace.go:171","msg":"trace[602427701] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:762; }","duration":"233.337196ms","start":"2026-04-01T23:21:31.045105Z","end":"2026-04-01T23:21:31.278442Z","steps":["trace[602427701] 'agreement among raft nodes before linearized reading' (duration: 232.808356ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:21:45.219288Z","caller":"traceutil/trace.go:171","msg":"trace[1070541615] transaction","detail":"{read_only:false; response_revision:923; number_of_response:1; }","duration":"109.469198ms","start":"2026-04-01T23:21:45.109793Z","end":"2026-04-01T23:21:45.219262Z","steps":["trace[1070541615] 'process raft request' (duration: 109.274972ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:22:22.448865Z","caller":"traceutil/trace.go:171","msg":"trace[522212111] linearizableReadLoop","detail":"{readStateIndex:1111; appliedIndex:1110; }","duration":"146.146768ms","start":"2026-04-01T23:22:22.302695Z","end":"2026-04-01T23:22:22.448842Z","steps":["trace[522212111] 'read index received' (duration: 146.118847ms)","trace[522212111] 'applied index is now lower than readState.Index' (duration: 26.881µs)"],"step_count":2} {"level":"warn","ts":"2026-04-01T23:22:22.449082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.370084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.36\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-01T23:22:22.449304Z","caller":"traceutil/trace.go:171","msg":"trace[1934866983] range","detail":"{range_begin:/registry/masterleases/162.253.55.36; range_end:; response_count:1; response_revision:1066; }","duration":"146.460665ms","start":"2026-04-01T23:22:22.302648Z","end":"2026-04-01T23:22:22.449108Z","steps":["trace[1934866983] 'agreement among raft nodes before linearized reading' (duration: 146.329372ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:22:22.449057Z","caller":"traceutil/trace.go:171","msg":"trace[1781572198] transaction","detail":"{read_only:false; response_revision:1066; number_of_response:1; }","duration":"538.473893ms","start":"2026-04-01T23:22:21.910512Z","end":"2026-04-01T23:22:22.448985Z","steps":["trace[1781572198] 'process raft request' (duration: 538.166095ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:22:22.45021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:22:21.910483Z","time spent":"539.596383ms","remote":"127.0.0.1:59918","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-01T23:22:30.589927Z","caller":"traceutil/trace.go:171","msg":"trace[2035270051] transaction","detail":"{read_only:false; response_revision:1093; number_of_response:1; }","duration":"108.457043ms","start":"2026-04-01T23:22:30.481426Z","end":"2026-04-01T23:22:30.589883Z","steps":["trace[2035270051] 'process raft request' (duration: 108.230748ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:22:42.762328Z","caller":"traceutil/trace.go:171","msg":"trace[1565018997] linearizableReadLoop","detail":"{readStateIndex:1183; appliedIndex:1182; }","duration":"112.313239ms","start":"2026-04-01T23:22:42.649993Z","end":"2026-04-01T23:22:42.762306Z","steps":["trace[1565018997] 'read index received' (duration: 112.114664ms)","trace[1565018997] 'applied index is now lower than readState.Index' (duration: 197.975µs)"],"step_count":2} {"level":"warn","ts":"2026-04-01T23:22:42.762536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.522544ms","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-04-01T23:22:42.762566Z","caller":"traceutil/trace.go:171","msg":"trace[1464109699] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1133; }","duration":"112.594225ms","start":"2026-04-01T23:22:42.649964Z","end":"2026-04-01T23:22:42.762558Z","steps":["trace[1464109699] 'agreement among raft nodes before linearized reading' (duration: 112.425981ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:22:42.762726Z","caller":"traceutil/trace.go:171","msg":"trace[1612461694] transaction","detail":"{read_only:false; response_revision:1133; number_of_response:1; }","duration":"126.6884ms","start":"2026-04-01T23:22:42.636006Z","end":"2026-04-01T23:22:42.762695Z","steps":["trace[1612461694] 'process raft request' (duration: 126.189508ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:22:47.009544Z","caller":"traceutil/trace.go:171","msg":"trace[575795745] transaction","detail":"{read_only:false; response_revision:1146; number_of_response:1; }","duration":"230.582076ms","start":"2026-04-01T23:22:46.778927Z","end":"2026-04-01T23:22:47.009509Z","steps":["trace[575795745] 'process raft request' (duration: 230.325049ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:22:47.009413Z","caller":"traceutil/trace.go:171","msg":"trace[1860325994] linearizableReadLoop","detail":"{readStateIndex:1196; appliedIndex:1195; }","duration":"224.867792ms","start":"2026-04-01T23:22:46.784528Z","end":"2026-04-01T23:22:47.009396Z","steps":["trace[1860325994] 'read index received' (duration: 224.503593ms)","trace[1860325994] 'applied index is now lower than readState.Index' (duration: 363.349µs)"],"step_count":2} {"level":"warn","ts":"2026-04-01T23:22:47.009755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.21377ms","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-04-01T23:22:47.009795Z","caller":"traceutil/trace.go:171","msg":"trace[222557962] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1146; }","duration":"225.353263ms","start":"2026-04-01T23:22:46.784433Z","end":"2026-04-01T23:22:47.009787Z","steps":["trace[222557962] 'agreement among raft nodes before linearized reading' (duration: 225.176169ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:23:37.364493Z","caller":"traceutil/trace.go:171","msg":"trace[676506695] linearizableReadLoop","detail":"{readStateIndex:1415; appliedIndex:1414; }","duration":"110.62869ms","start":"2026-04-01T23:23:37.253842Z","end":"2026-04-01T23:23:37.364471Z","steps":["trace[676506695] 'read index received' (duration: 109.833695ms)","trace[676506695] 'applied index is now lower than readState.Index' (duration: 794.035µs)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:23:37.364558Z","caller":"traceutil/trace.go:171","msg":"trace[351513888] transaction","detail":"{read_only:false; response_revision:1353; number_of_response:1; }","duration":"111.018809ms","start":"2026-04-01T23:23:37.253506Z","end":"2026-04-01T23:23:37.364525Z","steps":["trace[351513888] 'process raft request' (duration: 110.126631ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:23:37.364672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.815744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/kubeadm:kubelet-bootstrap\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-01T23:23:37.364708Z","caller":"traceutil/trace.go:171","msg":"trace[1054661535] range","detail":"{range_begin:/registry/clusterrolebindings/kubeadm:kubelet-bootstrap; range_end:; response_count:1; response_revision:1353; }","duration":"110.896787ms","start":"2026-04-01T23:23:37.253801Z","end":"2026-04-01T23:23:37.364698Z","steps":["trace[1054661535] 'agreement among raft nodes before linearized reading' (duration: 110.813195ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:23:38.078496Z","caller":"traceutil/trace.go:171","msg":"trace[1382218010] linearizableReadLoop","detail":"{readStateIndex:1417; appliedIndex:1416; }","duration":"135.057447ms","start":"2026-04-01T23:23:37.943419Z","end":"2026-04-01T23:23:38.078477Z","steps":["trace[1382218010] 'read index received' (duration: 134.927004ms)","trace[1382218010] 'applied index is now lower than readState.Index' (duration: 129.453µs)"],"step_count":2} {"level":"info","ts":"2026-04-01T23:23:38.07861Z","caller":"traceutil/trace.go:171","msg":"trace[2010714186] transaction","detail":"{read_only:false; response_revision:1354; number_of_response:1; }","duration":"138.150499ms","start":"2026-04-01T23:23:37.940441Z","end":"2026-04-01T23:23:38.078591Z","steps":["trace[2010714186] 'process raft request' (duration: 137.917603ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:23:38.078704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.244041ms","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-04-01T23:23:38.078736Z","caller":"traceutil/trace.go:171","msg":"trace[1648402463] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1354; }","duration":"135.361253ms","start":"2026-04-01T23:23:37.943365Z","end":"2026-04-01T23:23:38.078726Z","steps":["trace[1648402463] 'agreement among raft nodes before linearized reading' (duration: 135.229351ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:23:38.972762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.064489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:attachdetach-controller\" ","response":"range_response_count:1 size:767"} {"level":"info","ts":"2026-04-01T23:23:38.972821Z","caller":"traceutil/trace.go:171","msg":"trace[629469753] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:attachdetach-controller; range_end:; response_count:1; response_revision:1359; }","duration":"313.154581ms","start":"2026-04-01T23:23:38.659652Z","end":"2026-04-01T23:23:38.972806Z","steps":["trace[629469753] 'range keys from in-memory index tree' (duration: 312.984888ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:23:38.972842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:23:38.659635Z","time spent":"313.201812ms","remote":"127.0.0.1:60028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":73,"response count":1,"response size":791,"request content":"key:\"/registry/clusterrolebindings/system:controller:attachdetach-controller\" "} {"level":"warn","ts":"2026-04-01T23:23:38.972948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.507468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:replicaset-controller\" ","response":"range_response_count:1 size:841"} {"level":"info","ts":"2026-04-01T23:23:38.972968Z","caller":"traceutil/trace.go:171","msg":"trace[1545265381] range","detail":"{range_begin:/registry/clusterroles/system:controller:replicaset-controller; range_end:; response_count:1; response_revision:1359; }","duration":"302.568908ms","start":"2026-04-01T23:23:38.670394Z","end":"2026-04-01T23:23:38.972963Z","steps":["trace[1545265381] 'range keys from in-memory index tree' (duration: 302.417186ms)"],"step_count":1} {"level":"warn","ts":"2026-04-01T23:23:38.972986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-01T23:23:38.670372Z","time spent":"302.610439ms","remote":"127.0.0.1:60016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":865,"request content":"key:\"/registry/clusterroles/system:controller:replicaset-controller\" "} {"level":"info","ts":"2026-04-01T23:24:02.328614Z","caller":"traceutil/trace.go:171","msg":"trace[871796325] transaction","detail":"{read_only:false; response_revision:1434; number_of_response:1; }","duration":"110.651952ms","start":"2026-04-01T23:24:02.217939Z","end":"2026-04-01T23:24:02.328591Z","steps":["trace[871796325] 'process raft request' (duration: 110.473398ms)"],"step_count":1} {"level":"info","ts":"2026-04-01T23:24:02.331427Z","caller":"traceutil/trace.go:171","msg":"trace[1707004656] transaction","detail":"{read_only:false; response_revision:1435; number_of_response:1; }","duration":"100.520448ms","start":"2026-04-01T23:24:02.230874Z","end":"2026-04-01T23:24:02.331395Z","steps":["trace[1707004656] 'process raft request' (duration: 99.795704ms)"],"step_count":1}