{"level":"warn","ts":"2026-09-18T05:17:27.30061Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-18T05:17:27.300712Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.148: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.148:2380","--initial-cluster=instance=https://162.253.55.148:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.148:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.148: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-09-18T05:17:27.30078Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-18T05:17:27.300794Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.148:2380"]} {"level":"info","ts":"2026-09-18T05:17:27.300818Z","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-09-18T05:17:27.301625Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.148:2379"]} {"level":"info","ts":"2026-09-18T05:17:27.301727Z","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.148:2380"],"listen-peer-urls":["https://162.253.55.148:2380"],"advertise-client-urls":["https://162.253.55.148:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.148:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.148: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-09-18T05:17:27.305325Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.369743ms"} {"level":"info","ts":"2026-09-18T05:17:27.311745Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"a6342599e225e01b","cluster-id":"8ad3da7aa894cc05"} {"level":"info","ts":"2026-09-18T05:17:27.311817Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a6342599e225e01b switched to configuration voters=()"} {"level":"info","ts":"2026-09-18T05:17:27.311838Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a6342599e225e01b became follower at term 0"} {"level":"info","ts":"2026-09-18T05:17:27.311846Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft a6342599e225e01b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-18T05:17:27.311877Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a6342599e225e01b became follower at term 1"} {"level":"info","ts":"2026-09-18T05:17:27.311909Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a6342599e225e01b switched to configuration voters=(11976238651939348507)"} {"level":"warn","ts":"2026-09-18T05:17:27.317919Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-18T05:17:27.319481Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-18T05:17:27.321649Z","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-09-18T05:17:27.32373Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"a6342599e225e01b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-18T05:17:27.324317Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"a6342599e225e01b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-18T05:17:27.324352Z","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-09-18T05:17:27.324467Z","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-09-18T05:17:27.324479Z","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-09-18T05:17:27.32817Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a6342599e225e01b switched to configuration voters=(11976238651939348507)"} {"level":"info","ts":"2026-09-18T05:17:27.328422Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"8ad3da7aa894cc05","local-member-id":"a6342599e225e01b","added-peer-id":"a6342599e225e01b","added-peer-peer-urls":["https://162.253.55.148:2380"]} {"level":"info","ts":"2026-09-18T05:17:27.329221Z","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-09-18T05:17:27.329553Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"a6342599e225e01b","initial-advertise-peer-urls":["https://162.253.55.148:2380"],"listen-peer-urls":["https://162.253.55.148:2380"],"advertise-client-urls":["https://162.253.55.148:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.148:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-18T05:17:27.329596Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-18T05:17:27.330115Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.148:2380"} {"level":"info","ts":"2026-09-18T05:17:27.330143Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.148:2380"} {"level":"info","ts":"2026-09-18T05:17:28.312136Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a6342599e225e01b is starting a new election at term 1"} {"level":"info","ts":"2026-09-18T05:17:28.312234Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a6342599e225e01b became pre-candidate at term 1"} {"level":"info","ts":"2026-09-18T05:17:28.312288Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a6342599e225e01b received MsgPreVoteResp from a6342599e225e01b at term 1"} {"level":"info","ts":"2026-09-18T05:17:28.312319Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a6342599e225e01b became candidate at term 2"} {"level":"info","ts":"2026-09-18T05:17:28.312386Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a6342599e225e01b received MsgVoteResp from a6342599e225e01b at term 2"} {"level":"info","ts":"2026-09-18T05:17:28.312506Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a6342599e225e01b became leader at term 2"} {"level":"info","ts":"2026-09-18T05:17:28.312583Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: a6342599e225e01b elected leader a6342599e225e01b at term 2"} {"level":"info","ts":"2026-09-18T05:17:28.314586Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-18T05:17:28.315627Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"a6342599e225e01b","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.148:2379]}","request-path":"/0/members/a6342599e225e01b/attributes","cluster-id":"8ad3da7aa894cc05","publish-timeout":"7s"} {"level":"info","ts":"2026-09-18T05:17:28.315663Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-18T05:17:28.315701Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-18T05:17:28.315862Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-18T05:17:28.315915Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-18T05:17:28.316252Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"8ad3da7aa894cc05","local-member-id":"a6342599e225e01b","cluster-version":"3.5"} {"level":"info","ts":"2026-09-18T05:17:28.316352Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-18T05:17:28.316385Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-18T05:17:28.318327Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-18T05:17:28.318883Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.148:2379"} {"level":"info","ts":"2026-09-18T05:17:30.247373Z","caller":"traceutil/trace.go:171","msg":"trace[1917686698] linearizableReadLoop","detail":"{readStateIndex:6; appliedIndex:4; }","duration":"423.546332ms","start":"2026-09-18T05:17:29.823805Z","end":"2026-09-18T05:17:30.247352Z","steps":["trace[1917686698] 'read index received' (duration: 37.269566ms)","trace[1917686698] 'applied index is now lower than readState.Index' (duration: 386.275886ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:17:30.247405Z","caller":"traceutil/trace.go:171","msg":"trace[1955078857] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"419.365905ms","start":"2026-09-18T05:17:29.827983Z","end":"2026-09-18T05:17:30.247349Z","steps":["trace[1955078857] 'process raft request' (duration: 419.219641ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:17:30.247469Z","caller":"traceutil/trace.go:171","msg":"trace[536069097] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"419.115929ms","start":"2026-09-18T05:17:29.828347Z","end":"2026-09-18T05:17:30.247463Z","steps":["trace[536069097] 'process raft request' (duration: 418.878752ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:17:30.247678Z","caller":"traceutil/trace.go:171","msg":"trace[931086337] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"404.332769ms","start":"2026-09-18T05:17:29.843308Z","end":"2026-09-18T05:17:30.247641Z","steps":["trace[931086337] 'process raft request' (duration: 403.995749ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:17:30.24765Z","caller":"traceutil/trace.go:171","msg":"trace[1150172360] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"475.107174ms","start":"2026-09-18T05:17:29.772486Z","end":"2026-09-18T05:17:30.247593Z","steps":["trace[1150172360] 'process raft request' (duration: 474.554867ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:17:30.247838Z","caller":"traceutil/trace.go:171","msg":"trace[1681196717] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"419.297304ms","start":"2026-09-18T05:17:29.828518Z","end":"2026-09-18T05:17:30.247816Z","steps":["trace[1681196717] 'process raft request' (duration: 418.730777ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:17:30.247975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.101628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.148\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-09-18T05:17:30.24806Z","caller":"traceutil/trace.go:171","msg":"trace[1567005844] range","detail":"{range_begin:/registry/masterleases/162.253.55.148; range_end:; response_count:0; response_revision:11; }","duration":"424.293753ms","start":"2026-09-18T05:17:29.823749Z","end":"2026-09-18T05:17:30.248043Z","steps":["trace[1567005844] 'agreement among raft nodes before linearized reading' (duration: 424.065807ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:17:30.248132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:17:29.823729Z","time spent":"424.393406ms","remote":"127.0.0.1:53886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":0,"response size":28,"request content":"key:\"/registry/masterleases/162.253.55.148\" "} {"level":"warn","ts":"2026-09-18T05:17:30.248089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:17:29.828497Z","time spent":"419.393427ms","remote":"127.0.0.1:54370","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-18T05:17:30.248325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:17:29.828336Z","time spent":"419.15488ms","remote":"127.0.0.1:54370","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":992,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-18T05:17:30.248443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.425156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-09-18T05:17:30.248462Z","caller":"traceutil/trace.go:171","msg":"trace[1607258860] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"400.252595ms","start":"2026-09-18T05:17:29.848193Z","end":"2026-09-18T05:17:30.248446Z","steps":["trace[1607258860] 'process raft request' (duration: 399.135423ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:17:30.248493Z","caller":"traceutil/trace.go:171","msg":"trace[841922061] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"424.492459ms","start":"2026-09-18T05:17:29.823989Z","end":"2026-09-18T05:17:30.248481Z","steps":["trace[841922061] 'agreement among raft nodes before linearized reading' (duration: 424.395776ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:17:30.24845Z","caller":"traceutil/trace.go:171","msg":"trace[347405100] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"419.903241ms","start":"2026-09-18T05:17:29.828527Z","end":"2026-09-18T05:17:30.24843Z","steps":["trace[347405100] 'process raft request' (duration: 418.740578ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:17:30.248581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.125491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-09-18T05:17:30.248584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:17:29.848171Z","time spent":"400.325687ms","remote":"127.0.0.1:54104","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":845,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-18T05:17:30.248609Z","caller":"traceutil/trace.go:171","msg":"trace[973976636] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"329.182972ms","start":"2026-09-18T05:17:29.919419Z","end":"2026-09-18T05:17:30.248602Z","steps":["trace[973976636] 'agreement among raft nodes before linearized reading' (duration: 329.145101ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:17:30.248633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:17:29.919397Z","time spent":"329.230733ms","remote":"127.0.0.1:53954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":28,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-09-18T05:17:30.248018Z","caller":"traceutil/trace.go:171","msg":"trace[1138831244] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"420.787815ms","start":"2026-09-18T05:17:29.827212Z","end":"2026-09-18T05:17:30.248Z","steps":["trace[1138831244] 'process raft request' (duration: 419.960501ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:17:30.248722Z","caller":"traceutil/trace.go:171","msg":"trace[257400427] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"420.026694ms","start":"2026-09-18T05:17:29.828686Z","end":"2026-09-18T05:17:30.248712Z","steps":["trace[257400427] 'process raft request' (duration: 418.597584ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:17:30.24875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:17:29.827182Z","time spent":"421.545397ms","remote":"127.0.0.1:54278","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":698,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-18T05:17:30.248772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:17:29.828648Z","time spent":"420.099116ms","remote":"127.0.0.1:54370","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":883,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-18T05:17:30.248714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:17:29.828517Z","time spent":"420.078415ms","remote":"127.0.0.1:54370","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":971,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-18T05:17:30.248868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.629037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-09-18T05:17:30.248954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:17:29.82797Z","time spent":"419.51616ms","remote":"127.0.0.1:54370","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":920,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-18T05:17:30.249015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:17:29.843294Z","time spent":"404.469962ms","remote":"127.0.0.1:54006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3159,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-18T05:17:30.248903Z","caller":"traceutil/trace.go:171","msg":"trace[16888809] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"332.710669ms","start":"2026-09-18T05:17:29.916184Z","end":"2026-09-18T05:17:30.248894Z","steps":["trace[16888809] 'agreement among raft nodes before linearized reading' (duration: 332.654308ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:17:30.249129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:17:29.916153Z","time spent":"332.964706ms","remote":"127.0.0.1:53954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":28,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-09-18T05:17:30.248523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:17:29.823983Z","time spent":"424.53297ms","remote":"127.0.0.1:53954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":28,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-09-18T05:17:30.249235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.48444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-09-18T05:17:30.24927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:17:29.772461Z","time spent":"475.34524ms","remote":"127.0.0.1:53906","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":100,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T05:17:30.249283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.758045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-09-18T05:17:30.249289Z","caller":"traceutil/trace.go:171","msg":"trace[355282939] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:11; }","duration":"423.575083ms","start":"2026-09-18T05:17:29.825698Z","end":"2026-09-18T05:17:30.249273Z","steps":["trace[355282939] 'agreement among raft nodes before linearized reading' (duration: 423.49121ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:17:30.249311Z","caller":"traceutil/trace.go:171","msg":"trace[1559987069] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"424.784285ms","start":"2026-09-18T05:17:29.824517Z","end":"2026-09-18T05:17:30.249302Z","steps":["trace[1559987069] 'agreement among raft nodes before linearized reading' (duration: 424.730924ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:17:30.249323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:17:29.825655Z","time spent":"423.658405ms","remote":"127.0.0.1:53932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":28,"request content":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" "} {"level":"warn","ts":"2026-09-18T05:17:30.24939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:17:29.824498Z","time spent":"424.837807ms","remote":"127.0.0.1:53954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":28,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-09-18T05:17:30.249495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.491605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-09-18T05:17:30.249525Z","caller":"traceutil/trace.go:171","msg":"trace[1115077321] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:11; }","duration":"421.533645ms","start":"2026-09-18T05:17:29.827983Z","end":"2026-09-18T05:17:30.249517Z","steps":["trace[1115077321] 'agreement among raft nodes before linearized reading' (duration: 421.488264ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:17:30.249564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:17:29.827975Z","time spent":"421.584107ms","remote":"127.0.0.1:54222","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":28,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"warn","ts":"2026-09-18T05:17:30.249499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.013191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-09-18T05:17:30.249646Z","caller":"traceutil/trace.go:171","msg":"trace[1399267548] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"372.225707ms","start":"2026-09-18T05:17:29.877382Z","end":"2026-09-18T05:17:30.249608Z","steps":["trace[1399267548] 'agreement among raft nodes before linearized reading' (duration: 372.070413ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:17:30.249709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:17:29.87735Z","time spent":"372.34315ms","remote":"127.0.0.1:53954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":28,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-09-18T05:17:30.556181Z","caller":"traceutil/trace.go:171","msg":"trace[1720914354] linearizableReadLoop","detail":"{readStateIndex:15; appliedIndex:14; }","duration":"301.181123ms","start":"2026-09-18T05:17:30.254958Z","end":"2026-09-18T05:17:30.55614Z","steps":["trace[1720914354] 'read index received' (duration: 250.241819ms)","trace[1720914354] 'applied index is now lower than readState.Index' (duration: 50.937754ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:17:30.556302Z","caller":"traceutil/trace.go:171","msg":"trace[1972743765] transaction","detail":"{read_only:false; response_revision:12; number_of_response:1; }","duration":"301.383999ms","start":"2026-09-18T05:17:30.254871Z","end":"2026-09-18T05:17:30.556255Z","steps":["trace[1972743765] 'process raft request' (duration: 250.355593ms)","trace[1972743765] 'compare' (duration: 50.721158ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T05:17:30.556506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:17:30.25485Z","time spent":"301.530273ms","remote":"127.0.0.1:54370","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":923,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-18T05:17:30.556524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.545184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/csr-grt2h\" ","response":"range_response_count:1 size:857"} {"level":"info","ts":"2026-09-18T05:17:30.556851Z","caller":"traceutil/trace.go:171","msg":"trace[1555663914] range","detail":"{range_begin:/registry/certificatesigningrequests/csr-grt2h; range_end:; response_count:1; response_revision:12; }","duration":"301.887513ms","start":"2026-09-18T05:17:30.254921Z","end":"2026-09-18T05:17:30.556809Z","steps":["trace[1555663914] 'agreement among raft nodes before linearized reading' (duration: 301.42972ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:17:30.557007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:17:30.254907Z","time spent":"302.056848ms","remote":"127.0.0.1:54104","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":881,"request content":"key:\"/registry/certificatesigningrequests/csr-grt2h\" "} {"level":"warn","ts":"2026-09-18T05:17:30.560028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.01448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-09-18T05:17:30.560106Z","caller":"traceutil/trace.go:171","msg":"trace[1488996886] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:19; }","duration":"305.122092ms","start":"2026-09-18T05:17:30.254963Z","end":"2026-09-18T05:17:30.560085Z","steps":["trace[1488996886] 'agreement among raft nodes before linearized reading' (duration: 304.892136ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:17:30.560083Z","caller":"traceutil/trace.go:171","msg":"trace[355390178] transaction","detail":"{read_only:false; response_revision:16; number_of_response:1; }","duration":"304.861696ms","start":"2026-09-18T05:17:30.25517Z","end":"2026-09-18T05:17:30.560032Z","steps":["trace[355390178] 'process raft request' (duration: 304.303651ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:17:30.560169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:17:30.254947Z","time spent":"305.207875ms","remote":"127.0.0.1:53842","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-09-18T05:17:30.56005Z","caller":"traceutil/trace.go:171","msg":"trace[1825153300] transaction","detail":"{read_only:false; response_revision:13; number_of_response:1; }","duration":"305.038851ms","start":"2026-09-18T05:17:30.254989Z","end":"2026-09-18T05:17:30.560027Z","steps":["trace[1825153300] 'process raft request' (duration: 304.272ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:17:30.560267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:17:30.255148Z","time spent":"305.02661ms","remote":"127.0.0.1:53954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":338,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-18T05:17:30.560527Z","caller":"traceutil/trace.go:171","msg":"trace[763061741] transaction","detail":"{read_only:false; response_revision:18; number_of_response:1; }","duration":"301.905032ms","start":"2026-09-18T05:17:30.258605Z","end":"2026-09-18T05:17:30.56051Z","steps":["trace[763061741] 'process raft request' (duration: 301.030708ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:17:30.560579Z","caller":"traceutil/trace.go:171","msg":"trace[1010581452] transaction","detail":"{read_only:false; response_revision:17; number_of_response:1; }","duration":"302.405337ms","start":"2026-09-18T05:17:30.258138Z","end":"2026-09-18T05:17:30.560543Z","steps":["trace[1010581452] 'process raft request' (duration: 301.444391ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:17:30.560634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:17:30.258118Z","time spent":"302.48865ms","remote":"127.0.0.1:54370","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":965,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-18T05:17:30.560692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:17:30.258288Z","time spent":"302.319155ms","remote":"127.0.0.1:54278","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-18T05:17:30.560715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.468095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-09-18T05:17:30.560798Z","caller":"traceutil/trace.go:171","msg":"trace[1930467065] range","detail":"{range_begin:/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te; range_end:; response_count:0; response_revision:19; }","duration":"102.605178ms","start":"2026-09-18T05:17:30.458166Z","end":"2026-09-18T05:17:30.560771Z","steps":["trace[1930467065] 'agreement among raft nodes before linearized reading' (duration: 102.460014ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:17:30.560875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.137429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-09-18T05:17:30.560929Z","caller":"traceutil/trace.go:171","msg":"trace[1174750218] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:19; }","duration":"244.220811ms","start":"2026-09-18T05:17:30.316695Z","end":"2026-09-18T05:17:30.560915Z","steps":["trace[1174750218] 'agreement among raft nodes before linearized reading' (duration: 244.136409ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:17:30.561098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.257226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3491"} {"level":"info","ts":"2026-09-18T05:17:30.561154Z","caller":"traceutil/trace.go:171","msg":"trace[1773701449] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:19; }","duration":"305.338957ms","start":"2026-09-18T05:17:30.255799Z","end":"2026-09-18T05:17:30.561138Z","steps":["trace[1773701449] 'agreement among raft nodes before linearized reading' (duration: 305.258985ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:17:30.561193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:17:30.255787Z","time spent":"305.395922ms","remote":"127.0.0.1:54006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":3515,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-09-18T05:17:30.561233Z","caller":"traceutil/trace.go:171","msg":"trace[1829599891] transaction","detail":"{read_only:false; response_revision:15; number_of_response:1; }","duration":"306.129932ms","start":"2026-09-18T05:17:30.25509Z","end":"2026-09-18T05:17:30.56122Z","steps":["trace[1829599891] 'process raft request' (duration: 304.327571ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:17:30.561326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:17:30.255077Z","time spent":"306.217724ms","remote":"127.0.0.1:54370","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":941,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-18T05:17:30.561082Z","caller":"traceutil/trace.go:171","msg":"trace[1782545754] transaction","detail":"{read_only:false; number_of_response:0; response_revision:18; }","duration":"301.839801ms","start":"2026-09-18T05:17:30.259224Z","end":"2026-09-18T05:17:30.561064Z","steps":["trace[1782545754] 'process raft request' (duration: 300.475284ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:17:30.56143Z","caller":"traceutil/trace.go:171","msg":"trace[1460974072] transaction","detail":"{read_only:false; response_revision:19; number_of_response:1; }","duration":"297.822391ms","start":"2026-09-18T05:17:30.263588Z","end":"2026-09-18T05:17:30.561411Z","steps":["trace[1460974072] 'process raft request' (duration: 296.165134ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:17:30.560525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:17:30.254962Z","time spent":"305.294067ms","remote":"127.0.0.1:54370","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-18T05:17:30.561491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:17:30.259215Z","time spent":"302.240233ms","remote":"127.0.0.1:53954","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-18T05:17:30.56157Z","caller":"traceutil/trace.go:171","msg":"trace[1931542899] transaction","detail":"{read_only:false; response_revision:14; number_of_response:1; }","duration":"306.492502ms","start":"2026-09-18T05:17:30.25499Z","end":"2026-09-18T05:17:30.561482Z","steps":["trace[1931542899] 'process raft request' (duration: 304.380473ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:17:30.565662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:17:30.254962Z","time spent":"310.644496ms","remote":"127.0.0.1:54370","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":941,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-18T05:17:37.146042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.26819ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-18T05:17:37.146273Z","caller":"traceutil/trace.go:171","msg":"trace[1021283661] transaction","detail":"{read_only:false; response_revision:310; number_of_response:1; }","duration":"124.861346ms","start":"2026-09-18T05:17:37.021384Z","end":"2026-09-18T05:17:37.146245Z","steps":["trace[1021283661] 'process raft request' (duration: 12.98818ms)","trace[1021283661] 'compare' (duration: 111.003092ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:17:37.146896Z","caller":"traceutil/trace.go:171","msg":"trace[1543177367] linearizableReadLoop","detail":"{readStateIndex:319; appliedIndex:317; }","duration":"120.240569ms","start":"2026-09-18T05:17:37.026642Z","end":"2026-09-18T05:17:37.146883Z","steps":["trace[1543177367] 'read index received' (duration: 7.697574ms)","trace[1543177367] 'applied index is now lower than readState.Index' (duration: 112.541765ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:17:37.147095Z","caller":"traceutil/trace.go:171","msg":"trace[2049142289] transaction","detail":"{read_only:false; response_revision:311; number_of_response:1; }","duration":"124.568688ms","start":"2026-09-18T05:17:37.022516Z","end":"2026-09-18T05:17:37.147084Z","steps":["trace[2049142289] 'process raft request' (duration: 123.689114ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:17:37.147382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.462119ms","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-09-18T05:17:37.147436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.775894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-09-18T05:17:37.147483Z","caller":"traceutil/trace.go:171","msg":"trace[432908346] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:311; }","duration":"120.860077ms","start":"2026-09-18T05:17:37.026607Z","end":"2026-09-18T05:17:37.147467Z","steps":["trace[432908346] 'agreement among raft nodes before linearized reading' (duration: 120.753144ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:17:37.14749Z","caller":"traceutil/trace.go:171","msg":"trace[1974057226] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:311; }","duration":"105.632414ms","start":"2026-09-18T05:17:37.04183Z","end":"2026-09-18T05:17:37.147462Z","steps":["trace[1974057226] 'agreement among raft nodes before linearized reading' (duration: 105.316884ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:18:11.50545Z","caller":"traceutil/trace.go:171","msg":"trace[971723394] transaction","detail":"{read_only:false; response_revision:465; number_of_response:1; }","duration":"175.084901ms","start":"2026-09-18T05:18:11.330329Z","end":"2026-09-18T05:18:11.505414Z","steps":["trace[971723394] 'process raft request' (duration: 174.952058ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:18:15.679119Z","caller":"traceutil/trace.go:171","msg":"trace[1731877636] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"115.001194ms","start":"2026-09-18T05:18:15.564093Z","end":"2026-09-18T05:18:15.679095Z","steps":["trace[1731877636] 'process raft request' (duration: 114.85516ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:18:23.171343Z","caller":"traceutil/trace.go:171","msg":"trace[1120034806] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"125.67577ms","start":"2026-09-18T05:18:23.045646Z","end":"2026-09-18T05:18:23.171321Z","steps":["trace[1120034806] 'process raft request' (duration: 125.150735ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:18:23.739485Z","caller":"traceutil/trace.go:171","msg":"trace[179268714] linearizableReadLoop","detail":"{readStateIndex:643; appliedIndex:642; }","duration":"139.576746ms","start":"2026-09-18T05:18:23.599871Z","end":"2026-09-18T05:18:23.739448Z","steps":["trace[179268714] 'read index received' (duration: 48.406364ms)","trace[179268714] 'applied index is now lower than readState.Index' (duration: 91.168762ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:18:23.739814Z","caller":"traceutil/trace.go:171","msg":"trace[682677506] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"415.351613ms","start":"2026-09-18T05:18:23.324417Z","end":"2026-09-18T05:18:23.739769Z","steps":["trace[682677506] 'process raft request' (duration: 323.918665ms)","trace[682677506] 'compare' (duration: 90.820862ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T05:18:23.739901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.011267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-w9jp9\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-09-18T05:18:23.739953Z","caller":"traceutil/trace.go:171","msg":"trace[51901131] range","detail":"{range_begin:/registry/pods/kube-system/cilium-w9jp9; range_end:; response_count:1; response_revision:623; }","duration":"140.07388ms","start":"2026-09-18T05:18:23.599862Z","end":"2026-09-18T05:18:23.739936Z","steps":["trace[51901131] 'agreement among raft nodes before linearized reading' (duration: 139.896884ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:18:23.739975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:18:23.324397Z","time spent":"415.504029ms","remote":"127.0.0.1:54020","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2735,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-18T05:18:24.466918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"555.056953ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-18T05:18:24.467225Z","caller":"traceutil/trace.go:171","msg":"trace[1828693247] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"721.577807ms","start":"2026-09-18T05:18:23.745529Z","end":"2026-09-18T05:18:24.467107Z","steps":["trace[1828693247] 'process raft request' (duration: 166.228036ms)","trace[1828693247] 'compare' (duration: 554.826657ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T05:18:24.467436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:18:23.7455Z","time spent":"721.852085ms","remote":"127.0.0.1:53916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":775,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-18T05:18:24.491123Z","caller":"traceutil/trace.go:171","msg":"trace[2026655733] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"744.898376ms","start":"2026-09-18T05:18:23.746196Z","end":"2026-09-18T05:18:24.491095Z","steps":["trace[2026655733] 'process raft request' (duration: 744.583738ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:18:24.491288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:18:23.746159Z","time spent":"745.03807ms","remote":"127.0.0.1:53916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":905,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-18T05:18:24.491399Z","caller":"traceutil/trace.go:171","msg":"trace[939002833] linearizableReadLoop","detail":"{readStateIndex:649; appliedIndex:644; }","duration":"416.595578ms","start":"2026-09-18T05:18:24.07477Z","end":"2026-09-18T05:18:24.491365Z","steps":["trace[939002833] 'read index received' (duration: 416.121326ms)","trace[939002833] 'applied index is now lower than readState.Index' (duration: 472.642µs)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:18:24.49154Z","caller":"traceutil/trace.go:171","msg":"trace[23249336] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"744.149625ms","start":"2026-09-18T05:18:23.747374Z","end":"2026-09-18T05:18:24.491524Z","steps":["trace[23249336] 'process raft request' (duration: 743.59884ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:18:24.491786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:18:23.747361Z","time spent":"744.30904ms","remote":"127.0.0.1:54088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T05:18:24.491803Z","caller":"traceutil/trace.go:171","msg":"trace[1640856202] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"731.74979ms","start":"2026-09-18T05:18:23.75997Z","end":"2026-09-18T05:18:24.49172Z","steps":["trace[1640856202] 'process raft request' (duration: 731.327659ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:18:24.491926Z","caller":"traceutil/trace.go:171","msg":"trace[2084974508] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"742.801257ms","start":"2026-09-18T05:18:23.749107Z","end":"2026-09-18T05:18:24.491908Z","steps":["trace[2084974508] 'process raft request' (duration: 742.137599ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:18:24.492035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:18:23.749068Z","time spent":"742.89801ms","remote":"127.0.0.1:54108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T05:18:24.492053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.285148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-09-18T05:18:24.492054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:18:23.759909Z","time spent":"732.023078ms","remote":"127.0.0.1:54020","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T05:18:24.492098Z","caller":"traceutil/trace.go:171","msg":"trace[1793314467] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:629; }","duration":"417.410521ms","start":"2026-09-18T05:18:24.074676Z","end":"2026-09-18T05:18:24.492086Z","steps":["trace[1793314467] 'agreement among raft nodes before linearized reading' (duration: 417.244797ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:18:24.492132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:18:24.074656Z","time spent":"417.470423ms","remote":"127.0.0.1:54108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-09-18T05:18:24.491861Z","caller":"traceutil/trace.go:171","msg":"trace[33635139] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"743.366352ms","start":"2026-09-18T05:18:23.748473Z","end":"2026-09-18T05:18:24.49184Z","steps":["trace[33635139] 'process raft request' (duration: 742.578971ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:18:24.492294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:18:23.748462Z","time spent":"743.777124ms","remote":"127.0.0.1:54020","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3209,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T05:18:24.495316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.837469ms","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-09-18T05:18:24.495404Z","caller":"traceutil/trace.go:171","msg":"trace[1018916137] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:630; }","duration":"164.012534ms","start":"2026-09-18T05:18:24.33137Z","end":"2026-09-18T05:18:24.495383Z","steps":["trace[1018916137] 'agreement among raft nodes before linearized reading' (duration: 163.732067ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:18:24.495623Z","caller":"traceutil/trace.go:171","msg":"trace[452694746] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"293.274313ms","start":"2026-09-18T05:18:24.202332Z","end":"2026-09-18T05:18:24.495607Z","steps":["trace[452694746] 'process raft request' (duration: 292.630876ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:19:23.01642Z","caller":"traceutil/trace.go:171","msg":"trace[1717674378] linearizableReadLoop","detail":"{readStateIndex:1112; appliedIndex:1111; }","duration":"122.045469ms","start":"2026-09-18T05:19:22.894345Z","end":"2026-09-18T05:19:23.01639Z","steps":["trace[1717674378] 'read index received' (duration: 121.922166ms)","trace[1717674378] 'applied index is now lower than readState.Index' (duration: 122.403µs)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:19:23.016509Z","caller":"traceutil/trace.go:171","msg":"trace[752687560] transaction","detail":"{read_only:false; response_revision:1077; number_of_response:1; }","duration":"151.807106ms","start":"2026-09-18T05:19:22.86465Z","end":"2026-09-18T05:19:23.016457Z","steps":["trace[752687560] 'process raft request' (duration: 151.5795ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:19:23.016653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.296296ms","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-09-18T05:19:23.016686Z","caller":"traceutil/trace.go:171","msg":"trace[351774678] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1077; }","duration":"122.401888ms","start":"2026-09-18T05:19:22.894274Z","end":"2026-09-18T05:19:23.016676Z","steps":["trace[351774678] 'agreement among raft nodes before linearized reading' (duration: 122.244794ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:19:23.301646Z","caller":"traceutil/trace.go:171","msg":"trace[495183264] transaction","detail":"{read_only:false; response_revision:1081; number_of_response:1; }","duration":"189.711299ms","start":"2026-09-18T05:19:23.111907Z","end":"2026-09-18T05:19:23.301618Z","steps":["trace[495183264] 'process raft request' (duration: 189.562815ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:19:42.803261Z","caller":"traceutil/trace.go:171","msg":"trace[1358306859] transaction","detail":"{read_only:false; response_revision:1162; number_of_response:1; }","duration":"234.065819ms","start":"2026-09-18T05:19:42.569163Z","end":"2026-09-18T05:19:42.803229Z","steps":["trace[1358306859] 'process raft request' (duration: 233.905735ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:19:43.067683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.969569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-18T05:19:43.067767Z","caller":"traceutil/trace.go:171","msg":"trace[1863488529] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1162; }","duration":"148.116533ms","start":"2026-09-18T05:19:42.919632Z","end":"2026-09-18T05:19:43.067749Z","steps":["trace[1863488529] 'count revisions from in-memory index tree' (duration: 147.902218ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:19:43.244633Z","caller":"traceutil/trace.go:171","msg":"trace[195538901] transaction","detail":"{read_only:false; response_revision:1163; number_of_response:1; }","duration":"130.00465ms","start":"2026-09-18T05:19:43.114605Z","end":"2026-09-18T05:19:43.244609Z","steps":["trace[195538901] 'process raft request' (duration: 129.491955ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:19:43.356991Z","caller":"traceutil/trace.go:171","msg":"trace[410049834] transaction","detail":"{read_only:false; response_revision:1164; number_of_response:1; }","duration":"109.207242ms","start":"2026-09-18T05:19:43.247751Z","end":"2026-09-18T05:19:43.356959Z","steps":["trace[410049834] 'process raft request' (duration: 37.271464ms)","trace[410049834] 'compare' (duration: 71.761824ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:19:43.357278Z","caller":"traceutil/trace.go:171","msg":"trace[639275924] transaction","detail":"{read_only:false; response_revision:1165; number_of_response:1; }","duration":"109.13422ms","start":"2026-09-18T05:19:43.24812Z","end":"2026-09-18T05:19:43.357254Z","steps":["trace[639275924] 'process raft request' (duration: 108.797431ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:19:44.172231Z","caller":"traceutil/trace.go:171","msg":"trace[28793886] transaction","detail":"{read_only:false; response_revision:1170; number_of_response:1; }","duration":"150.89594ms","start":"2026-09-18T05:19:44.021285Z","end":"2026-09-18T05:19:44.172181Z","steps":["trace[28793886] 'process raft request' (duration: 150.614653ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:19:44.407306Z","caller":"traceutil/trace.go:171","msg":"trace[1976932322] transaction","detail":"{read_only:false; response_revision:1171; number_of_response:1; }","duration":"230.48179ms","start":"2026-09-18T05:19:44.176797Z","end":"2026-09-18T05:19:44.407279Z","steps":["trace[1976932322] 'process raft request' (duration: 181.948582ms)","trace[1976932322] 'compare' (duration: 48.23828ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T05:19:45.599442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.602167ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-18T05:19:45.599865Z","caller":"traceutil/trace.go:171","msg":"trace[761609583] transaction","detail":"{read_only:false; response_revision:1178; number_of_response:1; }","duration":"204.289863ms","start":"2026-09-18T05:19:45.395509Z","end":"2026-09-18T05:19:45.599799Z","steps":["trace[761609583] 'process raft request' (duration: 100.209623ms)","trace[761609583] 'compare' (duration: 103.39627ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:19:45.599938Z","caller":"traceutil/trace.go:171","msg":"trace[852046369] transaction","detail":"{read_only:false; response_revision:1179; number_of_response:1; }","duration":"203.706798ms","start":"2026-09-18T05:19:45.396199Z","end":"2026-09-18T05:19:45.599906Z","steps":["trace[852046369] 'process raft request' (duration: 203.42213ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:19:45.600128Z","caller":"traceutil/trace.go:171","msg":"trace[1838589400] transaction","detail":"{read_only:false; response_revision:1180; number_of_response:1; }","duration":"182.760084ms","start":"2026-09-18T05:19:45.417357Z","end":"2026-09-18T05:19:45.600117Z","steps":["trace[1838589400] 'process raft request' (duration: 182.371534ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:19:59.622354Z","caller":"traceutil/trace.go:171","msg":"trace[1586739904] linearizableReadLoop","detail":"{readStateIndex:1309; appliedIndex:1308; }","duration":"108.628196ms","start":"2026-09-18T05:19:59.513704Z","end":"2026-09-18T05:19:59.622332Z","steps":["trace[1586739904] 'read index received' (duration: 108.429501ms)","trace[1586739904] 'applied index is now lower than readState.Index' (duration: 197.615µs)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:19:59.622833Z","caller":"traceutil/trace.go:171","msg":"trace[53388338] transaction","detail":"{read_only:false; response_revision:1266; number_of_response:1; }","duration":"253.247851ms","start":"2026-09-18T05:19:59.369574Z","end":"2026-09-18T05:19:59.622822Z","steps":["trace[53388338] 'process raft request' (duration: 252.544672ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:19:59.623163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.44765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-09-18T05:19:59.623318Z","caller":"traceutil/trace.go:171","msg":"trace[2026608308] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1266; }","duration":"109.658546ms","start":"2026-09-18T05:19:59.513652Z","end":"2026-09-18T05:19:59.62331Z","steps":["trace[2026608308] 'agreement among raft nodes before linearized reading' (duration: 109.327977ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:22:04.667078Z","caller":"traceutil/trace.go:171","msg":"trace[995519100] transaction","detail":"{read_only:false; response_revision:1843; number_of_response:1; }","duration":"127.254261ms","start":"2026-09-18T05:22:04.539775Z","end":"2026-09-18T05:22:04.667029Z","steps":["trace[995519100] 'process raft request' (duration: 29.523369ms)","trace[995519100] 'compare' (duration: 97.569537ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T05:24:17.583988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.527318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-09-18T05:24:17.584269Z","caller":"traceutil/trace.go:171","msg":"trace[1784233506] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2448; }","duration":"134.868867ms","start":"2026-09-18T05:24:17.449378Z","end":"2026-09-18T05:24:17.584247Z","steps":["trace[1784233506] 'range keys from in-memory index tree' (duration: 134.358602ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:24:23.856828Z","caller":"traceutil/trace.go:171","msg":"trace[331506612] transaction","detail":"{read_only:false; response_revision:2481; number_of_response:1; }","duration":"148.847286ms","start":"2026-09-18T05:24:23.707949Z","end":"2026-09-18T05:24:23.856796Z","steps":["trace[331506612] 'process raft request' (duration: 73.905653ms)","trace[331506612] 'compare' (duration: 74.790529ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T05:24:41.825853Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16148677579304052336,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-09-18T05:24:41.922098Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.37316083s","expected-duration":"1s"} {"level":"info","ts":"2026-09-18T05:24:41.922994Z","caller":"traceutil/trace.go:171","msg":"trace[471590652] transaction","detail":"{read_only:false; response_revision:2581; number_of_response:1; }","duration":"1.374173419s","start":"2026-09-18T05:24:40.548791Z","end":"2026-09-18T05:24:41.922964Z","steps":["trace[471590652] 'process raft request' (duration: 1.373949383s)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:24:41.923358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:24:40.548761Z","time spent":"1.374327122s","remote":"127.0.0.1:54108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":511,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T05:24:42.013538Z","caller":"traceutil/trace.go:171","msg":"trace[1566658433] linearizableReadLoop","detail":"{readStateIndex:2682; appliedIndex:2680; }","duration":"688.212539ms","start":"2026-09-18T05:24:41.325296Z","end":"2026-09-18T05:24:42.013508Z","steps":["trace[1566658433] 'read index received' (duration: 597.360925ms)","trace[1566658433] 'applied index is now lower than readState.Index' (duration: 90.850634ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:24:42.013666Z","caller":"traceutil/trace.go:171","msg":"trace[496841883] transaction","detail":"{read_only:false; response_revision:2582; number_of_response:1; }","duration":"960.240988ms","start":"2026-09-18T05:24:41.053354Z","end":"2026-09-18T05:24:42.013595Z","steps":["trace[496841883] 'process raft request' (duration: 890.892212ms)","trace[496841883] 'compare' (duration: 69.115649ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T05:24:42.013794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"688.467807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-18T05:24:42.013833Z","caller":"traceutil/trace.go:171","msg":"trace[1488281295] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:2582; }","duration":"688.536399ms","start":"2026-09-18T05:24:41.325286Z","end":"2026-09-18T05:24:42.013822Z","steps":["trace[1488281295] 'agreement among raft nodes before linearized reading' (duration: 688.380165ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:24:42.013874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:24:41.325219Z","time spent":"688.643101ms","remote":"127.0.0.1:53950","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":17,"response size":31,"request content":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true "} {"level":"warn","ts":"2026-09-18T05:24:42.013916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:24:41.053314Z","time spent":"960.472014ms","remote":"127.0.0.1:54108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T05:24:42.014286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.158828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-09-18T05:24:42.014353Z","caller":"traceutil/trace.go:171","msg":"trace[162378997] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2582; }","duration":"111.22993ms","start":"2026-09-18T05:24:41.903112Z","end":"2026-09-18T05:24:42.014342Z","steps":["trace[162378997] 'agreement among raft nodes before linearized reading' (duration: 111.052775ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:24:42.014325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"479.626066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-18T05:24:42.014502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.621767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-09-18T05:24:42.014531Z","caller":"traceutil/trace.go:171","msg":"trace[1503126611] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2582; }","duration":"221.694319ms","start":"2026-09-18T05:24:41.792828Z","end":"2026-09-18T05:24:42.014522Z","steps":["trace[1503126611] 'agreement among raft nodes before linearized reading' (duration: 221.613557ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:24:42.014548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.997148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-09-18T05:24:42.014553Z","caller":"traceutil/trace.go:171","msg":"trace[1058557749] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2582; }","duration":"480.171431ms","start":"2026-09-18T05:24:41.534349Z","end":"2026-09-18T05:24:42.01452Z","steps":["trace[1058557749] 'agreement among raft nodes before linearized reading' (duration: 479.629206ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:24:42.014386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.760071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-09-18T05:24:42.014779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.237051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-09-18T05:24:42.014796Z","caller":"traceutil/trace.go:171","msg":"trace[1138849804] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2582; }","duration":"186.214364ms","start":"2026-09-18T05:24:41.828568Z","end":"2026-09-18T05:24:42.014783Z","steps":["trace[1138849804] 'agreement among raft nodes before linearized reading' (duration: 185.584017ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:24:42.014802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:24:41.534322Z","time spent":"480.462468ms","remote":"127.0.0.1:53838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-09-18T05:24:42.014618Z","caller":"traceutil/trace.go:171","msg":"trace[1800854445] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2582; }","duration":"186.100901ms","start":"2026-09-18T05:24:41.828493Z","end":"2026-09-18T05:24:42.014594Z","steps":["trace[1800854445] 'agreement among raft nodes before linearized reading' (duration: 185.971958ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:24:42.014822Z","caller":"traceutil/trace.go:171","msg":"trace[245695951] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2582; }","duration":"476.310123ms","start":"2026-09-18T05:24:41.538501Z","end":"2026-09-18T05:24:42.014811Z","steps":["trace[245695951] 'agreement among raft nodes before linearized reading' (duration: 476.109778ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:24:42.015021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.061507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.148\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-09-18T05:24:42.015148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:24:41.538484Z","time spent":"476.647172ms","remote":"127.0.0.1:54108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-09-18T05:24:42.015237Z","caller":"traceutil/trace.go:171","msg":"trace[1463719284] range","detail":"{range_begin:/registry/masterleases/162.253.55.148; range_end:; response_count:1; response_revision:2582; }","duration":"426.249672ms","start":"2026-09-18T05:24:41.588921Z","end":"2026-09-18T05:24:42.015171Z","steps":["trace[1463719284] 'agreement among raft nodes before linearized reading' (duration: 425.656896ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:24:42.015322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:24:41.588898Z","time spent":"426.410277ms","remote":"127.0.0.1:53886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/162.253.55.148\" "} {"level":"warn","ts":"2026-09-18T05:24:42.015258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.870482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:2569"} {"level":"info","ts":"2026-09-18T05:24:42.015445Z","caller":"traceutil/trace.go:171","msg":"trace[1211844333] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:2582; }","duration":"178.14105ms","start":"2026-09-18T05:24:41.837285Z","end":"2026-09-18T05:24:42.015426Z","steps":["trace[1211844333] 'agreement among raft nodes before linearized reading' (duration: 177.171643ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:24:42.17505Z","caller":"traceutil/trace.go:171","msg":"trace[1415162202] transaction","detail":"{read_only:false; response_revision:2583; number_of_response:1; }","duration":"155.918472ms","start":"2026-09-18T05:24:42.019076Z","end":"2026-09-18T05:24:42.174994Z","steps":["trace[1415162202] 'process raft request' (duration: 106.033796ms)","trace[1415162202] 'compare' (duration: 49.549177ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:24:42.175139Z","caller":"traceutil/trace.go:171","msg":"trace[1796722513] transaction","detail":"{read_only:false; response_revision:2584; number_of_response:1; }","duration":"155.806009ms","start":"2026-09-18T05:24:42.019283Z","end":"2026-09-18T05:24:42.175089Z","steps":["trace[1796722513] 'process raft request' (duration: 155.616704ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:24:42.175162Z","caller":"traceutil/trace.go:171","msg":"trace[305063891] transaction","detail":"{read_only:false; response_revision:2588; number_of_response:1; }","duration":"153.68334ms","start":"2026-09-18T05:24:42.021442Z","end":"2026-09-18T05:24:42.175125Z","steps":["trace[305063891] 'process raft request' (duration: 153.621078ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:24:42.175256Z","caller":"traceutil/trace.go:171","msg":"trace[1860469375] transaction","detail":"{read_only:false; response_revision:2585; number_of_response:1; }","duration":"155.556091ms","start":"2026-09-18T05:24:42.019688Z","end":"2026-09-18T05:24:42.175244Z","steps":["trace[1860469375] 'process raft request' (duration: 155.262543ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:24:42.175209Z","caller":"traceutil/trace.go:171","msg":"trace[1000629035] transaction","detail":"{read_only:false; response_revision:2587; number_of_response:1; }","duration":"154.981036ms","start":"2026-09-18T05:24:42.020212Z","end":"2026-09-18T05:24:42.175193Z","steps":["trace[1000629035] 'process raft request' (duration: 154.810091ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:24:42.175388Z","caller":"traceutil/trace.go:171","msg":"trace[717982891] transaction","detail":"{read_only:false; response_revision:2586; number_of_response:1; }","duration":"155.077868ms","start":"2026-09-18T05:24:42.020102Z","end":"2026-09-18T05:24:42.17518Z","steps":["trace[717982891] 'process raft request' (duration: 154.883693ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:24:42.252433Z","caller":"traceutil/trace.go:171","msg":"trace[1483521800] transaction","detail":"{read_only:false; response_revision:2590; number_of_response:1; }","duration":"140.01519ms","start":"2026-09-18T05:24:42.112387Z","end":"2026-09-18T05:24:42.252403Z","steps":["trace[1483521800] 'process raft request' (duration: 139.956929ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:24:42.252525Z","caller":"traceutil/trace.go:171","msg":"trace[116036707] transaction","detail":"{read_only:false; response_revision:2589; number_of_response:1; }","duration":"217.198195ms","start":"2026-09-18T05:24:42.035276Z","end":"2026-09-18T05:24:42.252474Z","steps":["trace[116036707] 'process raft request' (duration: 216.920487ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:24:42.635185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.219805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-09-18T05:24:42.635282Z","caller":"traceutil/trace.go:171","msg":"trace[1201021588] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2590; }","duration":"199.388009ms","start":"2026-09-18T05:24:42.435868Z","end":"2026-09-18T05:24:42.635256Z","steps":["trace[1201021588] 'range keys from in-memory index tree' (duration: 198.538607ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:24:43.943905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.235929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T05:24:43.944004Z","caller":"traceutil/trace.go:171","msg":"trace[1883688300] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2593; }","duration":"409.400154ms","start":"2026-09-18T05:24:43.534582Z","end":"2026-09-18T05:24:43.943982Z","steps":["trace[1883688300] 'range keys from in-memory index tree' (duration: 409.073025ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:24:43.944051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:24:43.534561Z","time spent":"409.479946ms","remote":"127.0.0.1:53838","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-09-18T05:24:43.944137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:24:43.264066Z","time spent":"680.062144ms","remote":"127.0.0.1:56256","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-09-18T05:24:43.944319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.519135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T05:24:43.944365Z","caller":"traceutil/trace.go:171","msg":"trace[55040094] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:2593; }","duration":"415.632418ms","start":"2026-09-18T05:24:43.528718Z","end":"2026-09-18T05:24:43.94435Z","steps":["trace[55040094] 'count revisions from in-memory index tree' (duration: 415.381911ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:24:43.944394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:24:43.528674Z","time spent":"415.71297ms","remote":"127.0.0.1:53976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":29,"request content":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true "} {"level":"info","ts":"2026-09-18T05:24:44.184017Z","caller":"traceutil/trace.go:171","msg":"trace[1858550484] linearizableReadLoop","detail":"{readStateIndex:2695; appliedIndex:2694; }","duration":"117.575017ms","start":"2026-09-18T05:24:44.066419Z","end":"2026-09-18T05:24:44.183994Z","steps":["trace[1858550484] 'read index received' (duration: 117.337701ms)","trace[1858550484] 'applied index is now lower than readState.Index' (duration: 236.406µs)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:24:44.18411Z","caller":"traceutil/trace.go:171","msg":"trace[600655446] transaction","detail":"{read_only:false; response_revision:2594; number_of_response:1; }","duration":"162.230298ms","start":"2026-09-18T05:24:44.021849Z","end":"2026-09-18T05:24:44.184079Z","steps":["trace[600655446] 'process raft request' (duration: 161.96972ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:24:44.18426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.827414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-09-18T05:24:44.184464Z","caller":"traceutil/trace.go:171","msg":"trace[1636643145] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2594; }","duration":"118.082441ms","start":"2026-09-18T05:24:44.066363Z","end":"2026-09-18T05:24:44.184446Z","steps":["trace[1636643145] 'agreement among raft nodes before linearized reading' (duration: 117.724851ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:24:44.691306Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16148677579304052367,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-09-18T05:24:46.327786Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.137997626s","expected-duration":"1s"} {"level":"warn","ts":"2026-09-18T05:24:46.417258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.227479112s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-18T05:24:46.417844Z","caller":"traceutil/trace.go:171","msg":"trace[1017839724] linearizableReadLoop","detail":"{readStateIndex:2702; appliedIndex:2695; }","duration":"2.227251849s","start":"2026-09-18T05:24:44.190564Z","end":"2026-09-18T05:24:46.417816Z","steps":["trace[1017839724] 'read index received' (duration: 999.161099ms)","trace[1017839724] 'applied index is now lower than readState.Index' (duration: 1.22808892s)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:24:46.417951Z","caller":"traceutil/trace.go:171","msg":"trace[1423307600] transaction","detail":"{read_only:false; response_revision:2598; number_of_response:1; }","duration":"2.226338503s","start":"2026-09-18T05:24:44.191595Z","end":"2026-09-18T05:24:46.417933Z","steps":["trace[1423307600] 'process raft request' (duration: 2.225959462s)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:24:46.417957Z","caller":"traceutil/trace.go:171","msg":"trace[1368830035] transaction","detail":"{read_only:false; response_revision:2599; number_of_response:1; }","duration":"2.225923092s","start":"2026-09-18T05:24:44.192018Z","end":"2026-09-18T05:24:46.417942Z","steps":["trace[1368830035] 'process raft request' (duration: 2.225595802s)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:24:46.418033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:24:44.191583Z","time spent":"2.226404825s","remote":"127.0.0.1:54108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T05:24:46.418052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:24:44.192007Z","time spent":"2.225988434s","remote":"127.0.0.1:54108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":516,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T05:24:46.418034Z","caller":"traceutil/trace.go:171","msg":"trace[1265318415] transaction","detail":"{read_only:false; response_revision:2595; number_of_response:1; }","duration":"2.228217926s","start":"2026-09-18T05:24:44.189775Z","end":"2026-09-18T05:24:46.417993Z","steps":["trace[1265318415] 'process raft request' (duration: 999.865529ms)","trace[1265318415] 'compare' (duration: 1.227288287s)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:24:46.418183Z","caller":"traceutil/trace.go:171","msg":"trace[591264959] transaction","detail":"{read_only:false; response_revision:2600; number_of_response:1; }","duration":"2.225952523s","start":"2026-09-18T05:24:44.19221Z","end":"2026-09-18T05:24:46.418163Z","steps":["trace[591264959] 'process raft request' (duration: 2.225461078s)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:24:46.418251Z","caller":"traceutil/trace.go:171","msg":"trace[1840745175] transaction","detail":"{read_only:false; response_revision:2596; number_of_response:1; }","duration":"2.227942969s","start":"2026-09-18T05:24:44.190298Z","end":"2026-09-18T05:24:46.418241Z","steps":["trace[1840745175] 'process raft request' (duration: 2.227117335s)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:24:46.418316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:24:44.192198Z","time spent":"2.226018495s","remote":"127.0.0.1:54108","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-09-18T05:24:46.41834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:24:44.190276Z","time spent":"2.22800153s","remote":"127.0.0.1:54108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T05:24:46.418436Z","caller":"traceutil/trace.go:171","msg":"trace[1274158967] transaction","detail":"{read_only:false; response_revision:2597; number_of_response:1; }","duration":"2.227641149s","start":"2026-09-18T05:24:44.190785Z","end":"2026-09-18T05:24:46.418426Z","steps":["trace[1274158967] 'process raft request' (duration: 2.226711042s)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:24:46.418471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:24:44.190774Z","time spent":"2.227676301s","remote":"127.0.0.1:54108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T05:24:46.418465Z","caller":"traceutil/trace.go:171","msg":"trace[1658724336] transaction","detail":"{read_only:false; response_revision:2601; number_of_response:1; }","duration":"2.224958255s","start":"2026-09-18T05:24:44.193491Z","end":"2026-09-18T05:24:46.418449Z","steps":["trace[1658724336] 'process raft request' (duration: 2.224266776s)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:24:46.418244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:24:44.18973Z","time spent":"2.22838226s","remote":"127.0.0.1:54108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T05:24:46.418541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:24:44.19348Z","time spent":"2.225016446s","remote":"127.0.0.1:54108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":518,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T05:24:46.418796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.228219986s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T05:24:46.418846Z","caller":"traceutil/trace.go:171","msg":"trace[1875056785] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:2601; }","duration":"2.228295328s","start":"2026-09-18T05:24:44.190534Z","end":"2026-09-18T05:24:46.418829Z","steps":["trace[1875056785] 'agreement among raft nodes before linearized reading' (duration: 2.228135434s)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:24:46.41889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:24:44.190525Z","time spent":"2.228353419s","remote":"127.0.0.1:53994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":0,"response size":29,"request content":"key:\"/registry/persistentvolumes/test-pv\" "} {"level":"warn","ts":"2026-09-18T05:24:47.534052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000058217s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-09-18T05:24:47.534209Z","caller":"traceutil/trace.go:171","msg":"trace[1211775127] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000307443s","start":"2026-09-18T05:24:45.533873Z","end":"2026-09-18T05:24:47.534181Z","steps":["trace[1211775127] 'agreement among raft nodes before linearized reading' (duration: 2.000088407s)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:24:47.534273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:24:45.533849Z","time spent":"2.000411407s","remote":"127.0.0.1:53838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/09/18 05:24:47 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-09-18T05:24:47.949119Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.096182434s","expected-duration":"1s"} {"level":"warn","ts":"2026-09-18T05:24:48.133785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.558636173s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-18T05:24:48.134548Z","caller":"traceutil/trace.go:171","msg":"trace[359786197] transaction","detail":"{read_only:false; response_revision:2602; number_of_response:1; }","duration":"1.942567229s","start":"2026-09-18T05:24:46.191936Z","end":"2026-09-18T05:24:48.134503Z","steps":["trace[359786197] 'process raft request' (duration: 383.105144ms)","trace[359786197] 'compare' (duration: 1.55852371s)"],"step_count":2} {"level":"warn","ts":"2026-09-18T05:24:48.134782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:24:46.191912Z","time spent":"1.942719153s","remote":"127.0.0.1:54108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T05:24:48.135145Z","caller":"traceutil/trace.go:171","msg":"trace[741688980] linearizableReadLoop","detail":"{readStateIndex:2703; appliedIndex:2702; }","duration":"1.717229829s","start":"2026-09-18T05:24:46.417893Z","end":"2026-09-18T05:24:48.135123Z","steps":["trace[741688980] 'read index received' (duration: 157.195408ms)","trace[741688980] 'applied index is now lower than readState.Index' (duration: 1.560031931s)"],"step_count":2} {"level":"warn","ts":"2026-09-18T05:24:48.321889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.963152ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-09-18T05:24:48.322018Z","caller":"traceutil/trace.go:171","msg":"trace[947327160] linearizableReadLoop","detail":"{readStateIndex:2704; appliedIndex:2703; }","duration":"186.80856ms","start":"2026-09-18T05:24:48.135189Z","end":"2026-09-18T05:24:48.321997Z","steps":["trace[947327160] 'read index received' (duration: 104.243µs)","trace[947327160] 'applied index is now lower than readState.Index' (duration: 186.702886ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T05:24:48.322089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.55744589s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T05:24:48.32212Z","caller":"traceutil/trace.go:171","msg":"trace[2137322406] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:2602; }","duration":"1.557530533s","start":"2026-09-18T05:24:46.764581Z","end":"2026-09-18T05:24:48.322111Z","steps":["trace[2137322406] 'agreement among raft nodes before linearized reading' (duration: 1.557464661s)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:24:48.322173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:24:46.764553Z","time spent":"1.557610794s","remote":"127.0.0.1:54088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true "} {"level":"warn","ts":"2026-09-18T05:24:48.322318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.338155349s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-18T05:24:48.322372Z","caller":"traceutil/trace.go:171","msg":"trace[681222016] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:2602; }","duration":"2.338237711s","start":"2026-09-18T05:24:45.984118Z","end":"2026-09-18T05:24:48.322356Z","steps":["trace[681222016] 'agreement among raft nodes before linearized reading' (duration: 2.151130704s)","trace[681222016] 'count revisions from in-memory index tree' (duration: 187.033316ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T05:24:48.32234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.353737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-09-18T05:24:48.322406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:24:45.984102Z","time spent":"2.338294452s","remote":"127.0.0.1:54266","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":31,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"info","ts":"2026-09-18T05:24:48.322426Z","caller":"traceutil/trace.go:171","msg":"trace[1434441956] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:2602; }","duration":"299.4658ms","start":"2026-09-18T05:24:48.022937Z","end":"2026-09-18T05:24:48.322402Z","steps":["trace[1434441956] 'agreement among raft nodes before linearized reading' (duration: 299.152351ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:24:48.322507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"648.894427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-09-18T05:24:48.322575Z","caller":"traceutil/trace.go:171","msg":"trace[1165729378] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2602; }","duration":"648.99342ms","start":"2026-09-18T05:24:47.67356Z","end":"2026-09-18T05:24:48.322554Z","steps":["trace[1165729378] 'agreement among raft nodes before linearized reading' (duration: 648.845226ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:24:48.322618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:24:47.673539Z","time spent":"649.066112ms","remote":"127.0.0.1:54108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":533,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-09-18T05:24:48.322793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.960896521s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-18T05:24:48.322834Z","caller":"traceutil/trace.go:171","msg":"trace[578872576] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:2602; }","duration":"2.960970443s","start":"2026-09-18T05:24:45.361853Z","end":"2026-09-18T05:24:48.322824Z","steps":["trace[578872576] 'agreement among raft nodes before linearized reading' (duration: 2.773417113s)","trace[578872576] 'count revisions from in-memory index tree' (duration: 187.496349ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T05:24:48.321925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.976564216s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:473"} {"level":"warn","ts":"2026-09-18T05:24:48.322864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:24:45.361835Z","time spent":"2.961021045s","remote":"127.0.0.1:53916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":149,"response size":32,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"warn","ts":"2026-09-18T05:24:48.322895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.393459005s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-09-18T05:24:48.322939Z","caller":"traceutil/trace.go:171","msg":"trace[492467343] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2602; }","duration":"1.393548657s","start":"2026-09-18T05:24:46.929372Z","end":"2026-09-18T05:24:48.322921Z","steps":["trace[492467343] 'agreement among raft nodes before linearized reading' (duration: 1.393445354s)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:24:48.322975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:24:46.92934Z","time spent":"1.393628909s","remote":"127.0.0.1:54108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":550,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-09-18T05:24:48.322935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.149200079s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-18T05:24:48.323039Z","caller":"traceutil/trace.go:171","msg":"trace[409064148] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:2602; }","duration":"1.149384863s","start":"2026-09-18T05:24:47.173632Z","end":"2026-09-18T05:24:48.323017Z","steps":["trace[409064148] 'agreement among raft nodes before linearized reading' (duration: 1.149152887s)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:24:48.323096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:24:47.173602Z","time spent":"1.149485066s","remote":"127.0.0.1:54166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":17,"response size":31,"request content":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true "} {"level":"warn","ts":"2026-09-18T05:24:48.323113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"788.477656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T05:24:48.323153Z","caller":"traceutil/trace.go:171","msg":"trace[593009287] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2602; }","duration":"788.553969ms","start":"2026-09-18T05:24:47.534583Z","end":"2026-09-18T05:24:48.323137Z","steps":["trace[593009287] 'agreement among raft nodes before linearized reading' (duration: 788.491857ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:24:48.322895Z","caller":"traceutil/trace.go:171","msg":"trace[2003783756] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2602; }","duration":"2.977602486s","start":"2026-09-18T05:24:45.345275Z","end":"2026-09-18T05:24:48.322877Z","steps":["trace[2003783756] 'agreement among raft nodes before linearized reading' (duration: 2.790006554s)","trace[2003783756] 'range keys from in-memory index tree' (duration: 186.498141ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T05:24:48.323191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:24:47.534532Z","time spent":"788.650181ms","remote":"127.0.0.1:53842","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-09-18T05:24:48.323213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:24:45.345244Z","time spent":"2.977958966s","remote":"127.0.0.1:54108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":497,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-09-18T05:24:48.323387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.114479593s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-18T05:24:48.323428Z","caller":"traceutil/trace.go:171","msg":"trace[1217357644] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:2602; }","duration":"1.114576865s","start":"2026-09-18T05:24:47.20884Z","end":"2026-09-18T05:24:48.323417Z","steps":["trace[1217357644] 'agreement among raft nodes before linearized reading' (duration: 1.114502303s)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:24:48.323455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:24:47.208806Z","time spent":"1.114641607s","remote":"127.0.0.1:54354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":1,"response size":31,"request content":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true "} {"level":"info","ts":"2026-09-18T05:24:48.499662Z","caller":"traceutil/trace.go:171","msg":"trace[225915998] transaction","detail":"{read_only:false; response_revision:2605; number_of_response:1; }","duration":"170.694122ms","start":"2026-09-18T05:24:48.328935Z","end":"2026-09-18T05:24:48.499629Z","steps":["trace[225915998] 'process raft request' (duration: 170.584539ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:24:48.49983Z","caller":"traceutil/trace.go:171","msg":"trace[1090814101] linearizableReadLoop","detail":"{readStateIndex:2708; appliedIndex:2704; }","duration":"167.061992ms","start":"2026-09-18T05:24:48.332736Z","end":"2026-09-18T05:24:48.499798Z","steps":["trace[1090814101] 'read index received' (duration: 37.403009ms)","trace[1090814101] 'applied index is now lower than readState.Index' (duration: 129.657743ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:24:48.500078Z","caller":"traceutil/trace.go:171","msg":"trace[1874154204] transaction","detail":"{read_only:false; response_revision:2606; number_of_response:1; }","duration":"169.710415ms","start":"2026-09-18T05:24:48.330354Z","end":"2026-09-18T05:24:48.500064Z","steps":["trace[1874154204] 'process raft request' (duration: 169.225732ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:24:48.500389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.634287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T05:24:48.500445Z","caller":"traceutil/trace.go:171","msg":"trace[1540170139] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2606; }","duration":"167.769531ms","start":"2026-09-18T05:24:48.332661Z","end":"2026-09-18T05:24:48.500431Z","steps":["trace[1540170139] 'agreement among raft nodes before linearized reading' (duration: 167.665518ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:24:48.500367Z","caller":"traceutil/trace.go:171","msg":"trace[1512722908] transaction","detail":"{read_only:false; response_revision:2604; number_of_response:1; }","duration":"171.523905ms","start":"2026-09-18T05:24:48.328642Z","end":"2026-09-18T05:24:48.500166Z","steps":["trace[1512722908] 'process raft request' (duration: 149.307438ms)","trace[1512722908] 'compare' (duration: 21.371744ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:24:48.660148Z","caller":"traceutil/trace.go:171","msg":"trace[780649530] transaction","detail":"{read_only:false; response_revision:2607; number_of_response:1; }","duration":"153.267679ms","start":"2026-09-18T05:24:48.506846Z","end":"2026-09-18T05:24:48.660113Z","steps":["trace[780649530] 'process raft request' (duration: 118.506064ms)","trace[780649530] 'compare' (duration: 34.528158ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:24:48.847517Z","caller":"traceutil/trace.go:171","msg":"trace[1604319681] transaction","detail":"{read_only:false; response_revision:2609; number_of_response:1; }","duration":"340.270974ms","start":"2026-09-18T05:24:48.507195Z","end":"2026-09-18T05:24:48.847466Z","steps":["trace[1604319681] 'process raft request' (duration: 340.004986ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:24:48.847557Z","caller":"traceutil/trace.go:171","msg":"trace[264968864] transaction","detail":"{read_only:false; response_revision:2611; number_of_response:1; }","duration":"340.224593ms","start":"2026-09-18T05:24:48.507293Z","end":"2026-09-18T05:24:48.847518Z","steps":["trace[264968864] 'process raft request' (duration: 340.001077ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:24:48.847689Z","caller":"traceutil/trace.go:171","msg":"trace[2025362187] transaction","detail":"{read_only:false; response_revision:2612; number_of_response:1; }","duration":"340.381888ms","start":"2026-09-18T05:24:48.507295Z","end":"2026-09-18T05:24:48.847677Z","steps":["trace[2025362187] 'process raft request' (duration: 340.032708ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:24:48.847774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:24:48.507237Z","time spent":"340.50708ms","remote":"127.0.0.1:54108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T05:24:48.847783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:24:48.507275Z","time spent":"340.379317ms","remote":"127.0.0.1:54108","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-09-18T05:24:48.847677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:24:48.507183Z","time spent":"340.413398ms","remote":"127.0.0.1:54108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T05:24:48.847778Z","caller":"traceutil/trace.go:171","msg":"trace[1131522577] transaction","detail":"{read_only:false; response_revision:2610; number_of_response:1; }","duration":"340.469559ms","start":"2026-09-18T05:24:48.507276Z","end":"2026-09-18T05:24:48.847746Z","steps":["trace[1131522577] 'process raft request' (duration: 339.981666ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:24:48.847834Z","caller":"traceutil/trace.go:171","msg":"trace[909830612] transaction","detail":"{read_only:false; response_revision:2608; number_of_response:1; }","duration":"340.801849ms","start":"2026-09-18T05:24:48.507005Z","end":"2026-09-18T05:24:48.847806Z","steps":["trace[909830612] 'process raft request' (duration: 282.487959ms)","trace[909830612] 'compare' (duration: 57.56771ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T05:24:48.847926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:24:48.507253Z","time spent":"340.597333ms","remote":"127.0.0.1:54108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T05:24:48.847947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:24:48.506957Z","time spent":"340.927692ms","remote":"127.0.0.1:54108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T05:24:49.065888Z","caller":"traceutil/trace.go:171","msg":"trace[1959239375] transaction","detail":"{read_only:false; response_revision:2615; number_of_response:1; }","duration":"138.893669ms","start":"2026-09-18T05:24:48.926953Z","end":"2026-09-18T05:24:49.065846Z","steps":["trace[1959239375] 'process raft request' (duration: 112.803744ms)","trace[1959239375] 'compare' (duration: 25.398486ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T05:24:49.30893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.967088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:2769"} {"level":"info","ts":"2026-09-18T05:24:49.309019Z","caller":"traceutil/trace.go:171","msg":"trace[1912300986] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:2615; }","duration":"140.145954ms","start":"2026-09-18T05:24:49.168853Z","end":"2026-09-18T05:24:49.308999Z","steps":["trace[1912300986] 'range keys from in-memory index tree' (duration: 139.848275ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:24:52.582102Z","caller":"traceutil/trace.go:171","msg":"trace[10634140] transaction","detail":"{read_only:false; response_revision:2628; number_of_response:1; }","duration":"192.985392ms","start":"2026-09-18T05:24:52.389092Z","end":"2026-09-18T05:24:52.582077Z","steps":["trace[10634140] 'process raft request' (duration: 192.767366ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T05:24:53.05681Z","caller":"traceutil/trace.go:171","msg":"trace[148168758] linearizableReadLoop","detail":"{readStateIndex:2733; appliedIndex:2732; }","duration":"173.513201ms","start":"2026-09-18T05:24:52.883265Z","end":"2026-09-18T05:24:53.056778Z","steps":["trace[148168758] 'read index received' (duration: 173.192322ms)","trace[148168758] 'applied index is now lower than readState.Index' (duration: 319.039µs)"],"step_count":2} {"level":"info","ts":"2026-09-18T05:24:53.05692Z","caller":"traceutil/trace.go:171","msg":"trace[1913105557] transaction","detail":"{read_only:false; response_revision:2630; number_of_response:1; }","duration":"251.576789ms","start":"2026-09-18T05:24:52.805308Z","end":"2026-09-18T05:24:53.056885Z","steps":["trace[1913105557] 'process raft request' (duration: 251.202439ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:24:53.057091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.806039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-09-18T05:24:53.057175Z","caller":"traceutil/trace.go:171","msg":"trace[1353686057] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2630; }","duration":"173.959414ms","start":"2026-09-18T05:24:52.883197Z","end":"2026-09-18T05:24:53.057157Z","steps":["trace[1353686057] 'agreement among raft nodes before linearized reading' (duration: 173.693176ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:24:53.346476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.591414ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-18T05:24:53.346595Z","caller":"traceutil/trace.go:171","msg":"trace[1052260503] transaction","detail":"{read_only:false; response_revision:2636; number_of_response:1; }","duration":"221.777251ms","start":"2026-09-18T05:24:53.12479Z","end":"2026-09-18T05:24:53.346568Z","steps":["trace[1052260503] 'process raft request' (duration: 116.002842ms)","trace[1052260503] 'compare' (duration: 105.415259ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T05:24:53.801814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.286405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-node-lease/\" range_end:\"/registry/secrets/kube-node-lease0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T05:24:53.80198Z","caller":"traceutil/trace.go:171","msg":"trace[1034493140] range","detail":"{range_begin:/registry/secrets/kube-node-lease/; range_end:/registry/secrets/kube-node-lease0; response_count:0; response_revision:2640; }","duration":"132.495381ms","start":"2026-09-18T05:24:53.66941Z","end":"2026-09-18T05:24:53.801905Z","steps":["trace[1034493140] 'range keys from in-memory index tree' (duration: 132.018688ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:24:55.410346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.836611ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-18T05:24:55.412324Z","caller":"traceutil/trace.go:171","msg":"trace[1789493633] transaction","detail":"{read_only:false; response_revision:2644; number_of_response:1; }","duration":"321.42194ms","start":"2026-09-18T05:24:55.089219Z","end":"2026-09-18T05:24:55.410641Z","steps":["trace[1789493633] 'process raft request' (duration: 143.180198ms)","trace[1789493633] 'compare' (duration: 177.436339ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T05:24:55.412578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:24:55.08919Z","time spent":"323.285832ms","remote":"127.0.0.1:54108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T05:24:55.412635Z","caller":"traceutil/trace.go:171","msg":"trace[1002619189] transaction","detail":"{read_only:false; response_revision:2645; number_of_response:1; }","duration":"323.153997ms","start":"2026-09-18T05:24:55.089412Z","end":"2026-09-18T05:24:55.412566Z","steps":["trace[1002619189] 'process raft request' (duration: 321.151202ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:24:55.412915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:24:55.089394Z","time spent":"323.390854ms","remote":"127.0.0.1:54108","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-09-18T05:24:55.413066Z","caller":"traceutil/trace.go:171","msg":"trace[147934356] transaction","detail":"{read_only:false; response_revision:2646; number_of_response:1; }","duration":"322.377567ms","start":"2026-09-18T05:24:55.09067Z","end":"2026-09-18T05:24:55.413048Z","steps":["trace[147934356] 'process raft request' (duration: 321.354018ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:24:55.413191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:24:55.09065Z","time spent":"322.48735ms","remote":"127.0.0.1:54108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":516,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T05:24:55.413668Z","caller":"traceutil/trace.go:171","msg":"trace[1181129437] transaction","detail":"{read_only:false; response_revision:2647; number_of_response:1; }","duration":"322.569341ms","start":"2026-09-18T05:24:55.091068Z","end":"2026-09-18T05:24:55.413638Z","steps":["trace[1181129437] 'process raft request' (duration: 321.072079ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T05:24:55.413832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T05:24:55.091054Z","time spent":"322.724955ms","remote":"127.0.0.1:54108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"}