{"level":"warn","ts":"2026-08-11T11:04:35.094124Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-11T11:04:35.094304Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.21: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://199.19.213.21:2380","--initial-cluster=instance=https://199.19.213.21:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.21:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.21: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-08-11T11:04:35.09445Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-11T11:04:35.094503Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.21:2380"]} {"level":"info","ts":"2026-08-11T11:04:35.094545Z","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-08-11T11:04:35.095402Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.21:2379"]} {"level":"info","ts":"2026-08-11T11:04:35.095696Z","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://199.19.213.21:2380"],"listen-peer-urls":["https://199.19.213.21:2380"],"advertise-client-urls":["https://199.19.213.21:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.21:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.21: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-08-11T11:04:35.100322Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.330581ms"} {"level":"info","ts":"2026-08-11T11:04:35.109919Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"c39f03fd9ad57fdc","cluster-id":"3c95a04d00bbaaa4"} {"level":"info","ts":"2026-08-11T11:04:35.110034Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c39f03fd9ad57fdc switched to configuration voters=()"} {"level":"info","ts":"2026-08-11T11:04:35.11007Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c39f03fd9ad57fdc became follower at term 0"} {"level":"info","ts":"2026-08-11T11:04:35.110091Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft c39f03fd9ad57fdc [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-11T11:04:35.110113Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c39f03fd9ad57fdc became follower at term 1"} {"level":"info","ts":"2026-08-11T11:04:35.110161Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c39f03fd9ad57fdc switched to configuration voters=(14095989746452234204)"} {"level":"warn","ts":"2026-08-11T11:04:35.121117Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-11T11:04:35.122338Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-11T11:04:35.123925Z","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-08-11T11:04:35.127328Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"c39f03fd9ad57fdc","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-11T11:04:35.12778Z","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-08-11T11:04:35.127892Z","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-08-11T11:04:35.127906Z","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-08-11T11:04:35.128033Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"c39f03fd9ad57fdc","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-11T11:04:35.131396Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c39f03fd9ad57fdc switched to configuration voters=(14095989746452234204)"} {"level":"info","ts":"2026-08-11T11:04:35.13169Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"3c95a04d00bbaaa4","local-member-id":"c39f03fd9ad57fdc","added-peer-id":"c39f03fd9ad57fdc","added-peer-peer-urls":["https://199.19.213.21:2380"]} {"level":"info","ts":"2026-08-11T11:04:35.137604Z","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-08-11T11:04:35.137698Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.21:2380"} {"level":"info","ts":"2026-08-11T11:04:35.137762Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.21:2380"} {"level":"info","ts":"2026-08-11T11:04:35.138024Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"c39f03fd9ad57fdc","initial-advertise-peer-urls":["https://199.19.213.21:2380"],"listen-peer-urls":["https://199.19.213.21:2380"],"advertise-client-urls":["https://199.19.213.21:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.21:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-11T11:04:35.138094Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-11T11:04:35.912112Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c39f03fd9ad57fdc is starting a new election at term 1"} {"level":"info","ts":"2026-08-11T11:04:35.912168Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c39f03fd9ad57fdc became pre-candidate at term 1"} {"level":"info","ts":"2026-08-11T11:04:35.912185Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c39f03fd9ad57fdc received MsgPreVoteResp from c39f03fd9ad57fdc at term 1"} {"level":"info","ts":"2026-08-11T11:04:35.912198Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c39f03fd9ad57fdc became candidate at term 2"} {"level":"info","ts":"2026-08-11T11:04:35.912206Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c39f03fd9ad57fdc received MsgVoteResp from c39f03fd9ad57fdc at term 2"} {"level":"info","ts":"2026-08-11T11:04:35.912216Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c39f03fd9ad57fdc became leader at term 2"} {"level":"info","ts":"2026-08-11T11:04:35.912224Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: c39f03fd9ad57fdc elected leader c39f03fd9ad57fdc at term 2"} {"level":"info","ts":"2026-08-11T11:04:35.915543Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-11T11:04:35.92236Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"c39f03fd9ad57fdc","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.21:2379]}","request-path":"/0/members/c39f03fd9ad57fdc/attributes","cluster-id":"3c95a04d00bbaaa4","publish-timeout":"7s"} {"level":"info","ts":"2026-08-11T11:04:35.922599Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-11T11:04:35.923224Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-11T11:04:35.923527Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-11T11:04:35.923554Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-11T11:04:35.926163Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-11T11:04:35.926326Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.21:2379"} {"level":"info","ts":"2026-08-11T11:04:35.935111Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"3c95a04d00bbaaa4","local-member-id":"c39f03fd9ad57fdc","cluster-version":"3.5"} {"level":"info","ts":"2026-08-11T11:04:35.935344Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-11T11:04:35.935424Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-11T11:04:37.719269Z","caller":"traceutil/trace.go:171","msg":"trace[1082823635] linearizableReadLoop","detail":"{readStateIndex:42; appliedIndex:41; }","duration":"119.073044ms","start":"2026-08-11T11:04:37.60015Z","end":"2026-08-11T11:04:37.719223Z","steps":["trace[1082823635] 'read index received' (duration: 47.619431ms)","trace[1082823635] 'applied index is now lower than readState.Index' (duration: 71.452333ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:04:37.719483Z","caller":"traceutil/trace.go:171","msg":"trace[187335162] transaction","detail":"{read_only:false; response_revision:38; number_of_response:1; }","duration":"137.923392ms","start":"2026-08-11T11:04:37.581502Z","end":"2026-08-11T11:04:37.719425Z","steps":["trace[187335162] 'process raft request' (duration: 66.222904ms)","trace[187335162] 'compare' (duration: 71.31625ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T11:04:37.719797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.599676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/default/\" range_end:\"/registry/resourcequotas/default0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-11T11:04:37.720314Z","caller":"traceutil/trace.go:171","msg":"trace[2125369371] range","detail":"{range_begin:/registry/resourcequotas/default/; range_end:/registry/resourcequotas/default0; response_count:0; response_revision:38; }","duration":"120.263158ms","start":"2026-08-11T11:04:37.600001Z","end":"2026-08-11T11:04:37.720265Z","steps":["trace[2125369371] 'agreement among raft nodes before linearized reading' (duration: 119.471922ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:04:37.720656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.049349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-11T11:04:37.720745Z","caller":"traceutil/trace.go:171","msg":"trace[708088492] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:0; response_revision:38; }","duration":"113.162842ms","start":"2026-08-11T11:04:37.607535Z","end":"2026-08-11T11:04:37.720698Z","steps":["trace[708088492] 'agreement among raft nodes before linearized reading' (duration: 112.493189ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:04:38.012159Z","caller":"traceutil/trace.go:171","msg":"trace[189333760] linearizableReadLoop","detail":"{readStateIndex:72; appliedIndex:70; }","duration":"112.395624ms","start":"2026-08-11T11:04:37.899718Z","end":"2026-08-11T11:04:38.012114Z","steps":["trace[189333760] 'read index received' (duration: 101.270766ms)","trace[189333760] 'applied index is now lower than readState.Index' (duration: 11.123448ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T11:04:38.012341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.60605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/default/instance.18cabb6b7e9bec67\" ","response":"range_response_count:1 size:654"} {"level":"info","ts":"2026-08-11T11:04:38.012391Z","caller":"traceutil/trace.go:171","msg":"trace[1482109605] range","detail":"{range_begin:/registry/events/default/instance.18cabb6b7e9bec67; range_end:; response_count:1; response_revision:66; }","duration":"112.716262ms","start":"2026-08-11T11:04:37.899657Z","end":"2026-08-11T11:04:38.012373Z","steps":["trace[1482109605] 'agreement among raft nodes before linearized reading' (duration: 112.60628ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:04:38.01251Z","caller":"traceutil/trace.go:171","msg":"trace[1393896368] transaction","detail":"{read_only:false; response_revision:65; number_of_response:1; }","duration":"123.960874ms","start":"2026-08-11T11:04:37.888398Z","end":"2026-08-11T11:04:38.012359Z","steps":["trace[1393896368] 'process raft request' (duration: 112.630102ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:04:38.012626Z","caller":"traceutil/trace.go:171","msg":"trace[1626354964] transaction","detail":"{read_only:false; response_revision:66; number_of_response:1; }","duration":"121.977912ms","start":"2026-08-11T11:04:37.890594Z","end":"2026-08-11T11:04:38.012572Z","steps":["trace[1626354964] 'process raft request' (duration: 121.41883ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:04:42.754669Z","caller":"traceutil/trace.go:171","msg":"trace[1853551454] linearizableReadLoop","detail":"{readStateIndex:331; appliedIndex:330; }","duration":"143.4771ms","start":"2026-08-11T11:04:42.611153Z","end":"2026-08-11T11:04:42.754631Z","steps":["trace[1853551454] 'read index received' (duration: 76.82743ms)","trace[1853551454] 'applied index is now lower than readState.Index' (duration: 66.64799ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T11:04:42.754874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.683994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-08-11T11:04:42.754924Z","caller":"traceutil/trace.go:171","msg":"trace[1939262030] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:319; }","duration":"143.795866ms","start":"2026-08-11T11:04:42.61111Z","end":"2026-08-11T11:04:42.754906Z","steps":["trace[1939262030] 'agreement among raft nodes before linearized reading' (duration: 143.672044ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:04:42.755187Z","caller":"traceutil/trace.go:171","msg":"trace[1552250842] transaction","detail":"{read_only:false; response_revision:319; number_of_response:1; }","duration":"168.802701ms","start":"2026-08-11T11:04:42.586329Z","end":"2026-08-11T11:04:42.755132Z","steps":["trace[1552250842] 'process raft request' (duration: 101.798124ms)","trace[1552250842] 'compare' (duration: 66.331324ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T11:04:43.145308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.537316ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-11T11:04:43.145508Z","caller":"traceutil/trace.go:171","msg":"trace[1127931074] transaction","detail":"{read_only:false; response_revision:321; number_of_response:1; }","duration":"379.156951ms","start":"2026-08-11T11:04:42.766333Z","end":"2026-08-11T11:04:43.14549Z","steps":["trace[1127931074] 'process raft request' (duration: 379.08851ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:04:43.14557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:04:42.766324Z","time spent":"379.218852ms","remote":"127.0.0.1:55492","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":182,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-11T11:04:43.145583Z","caller":"traceutil/trace.go:171","msg":"trace[1848151791] transaction","detail":"{read_only:false; response_revision:320; number_of_response:1; }","duration":"379.62794ms","start":"2026-08-11T11:04:42.765916Z","end":"2026-08-11T11:04:43.145544Z","steps":["trace[1848151791] 'process raft request' (duration: 179.155195ms)","trace[1848151791] 'compare' (duration: 199.377764ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T11:04:43.145773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:04:42.765889Z","time spent":"379.788303ms","remote":"127.0.0.1:55468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7279,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T11:04:43.341586Z","caller":"traceutil/trace.go:171","msg":"trace[817327840] transaction","detail":"{read_only:false; response_revision:324; number_of_response:1; }","duration":"135.804336ms","start":"2026-08-11T11:04:43.205735Z","end":"2026-08-11T11:04:43.34154Z","steps":["trace[817327840] 'process raft request' (duration: 50.43757ms)","trace[817327840] 'compare' (duration: 85.178293ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:04:43.347567Z","caller":"traceutil/trace.go:171","msg":"trace[493886591] transaction","detail":"{read_only:false; response_revision:325; number_of_response:1; }","duration":"138.327466ms","start":"2026-08-11T11:04:43.209106Z","end":"2026-08-11T11:04:43.347433Z","steps":["trace[493886591] 'process raft request' (duration: 138.174853ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:04:44.48298Z","caller":"traceutil/trace.go:171","msg":"trace[1085410697] linearizableReadLoop","detail":"{readStateIndex:350; appliedIndex:349; }","duration":"122.260241ms","start":"2026-08-11T11:04:44.360676Z","end":"2026-08-11T11:04:44.482936Z","steps":["trace[1085410697] 'read index received' (duration: 122.014906ms)","trace[1085410697] 'applied index is now lower than readState.Index' (duration: 244.535µs)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:04:44.4831Z","caller":"traceutil/trace.go:171","msg":"trace[1639238924] transaction","detail":"{read_only:false; response_revision:338; number_of_response:1; }","duration":"134.697113ms","start":"2026-08-11T11:04:44.348363Z","end":"2026-08-11T11:04:44.483061Z","steps":["trace[1639238924] 'process raft request' (duration: 134.414668ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:04:44.483154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.447485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-08-11T11:04:44.483286Z","caller":"traceutil/trace.go:171","msg":"trace[270504508] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:338; }","duration":"122.624178ms","start":"2026-08-11T11:04:44.360652Z","end":"2026-08-11T11:04:44.483276Z","steps":["trace[270504508] 'agreement among raft nodes before linearized reading' (duration: 122.419784ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:04:45.441548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.601044ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-11T11:04:45.441706Z","caller":"traceutil/trace.go:171","msg":"trace[132378430] linearizableReadLoop","detail":"{readStateIndex:376; appliedIndex:375; }","duration":"137.966627ms","start":"2026-08-11T11:04:45.303719Z","end":"2026-08-11T11:04:45.441685Z","steps":["trace[132378430] 'read index received' (duration: 35.143859ms)","trace[132378430] 'applied index is now lower than readState.Index' (duration: 102.821018ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T11:04:45.441967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.242772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4017"} {"level":"info","ts":"2026-08-11T11:04:45.441903Z","caller":"traceutil/trace.go:171","msg":"trace[2022516788] transaction","detail":"{read_only:false; response_revision:364; number_of_response:1; }","duration":"139.384624ms","start":"2026-08-11T11:04:45.302335Z","end":"2026-08-11T11:04:45.44172Z","steps":["trace[2022516788] 'process raft request' (duration: 36.522185ms)","trace[2022516788] 'compare' (duration: 102.43888ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:04:45.442012Z","caller":"traceutil/trace.go:171","msg":"trace[1015679983] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:364; }","duration":"138.295633ms","start":"2026-08-11T11:04:45.303705Z","end":"2026-08-11T11:04:45.442001Z","steps":["trace[1015679983] 'agreement among raft nodes before linearized reading' (duration: 138.079559ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:04:45.442093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.860719ms","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-08-11T11:04:45.442216Z","caller":"traceutil/trace.go:171","msg":"trace[1503266719] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:364; }","duration":"131.013491ms","start":"2026-08-11T11:04:45.311158Z","end":"2026-08-11T11:04:45.442171Z","steps":["trace[1503266719] 'agreement among raft nodes before linearized reading' (duration: 130.666295ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:04:51.722662Z","caller":"traceutil/trace.go:171","msg":"trace[795412728] linearizableReadLoop","detail":"{readStateIndex:423; appliedIndex:422; }","duration":"144.692403ms","start":"2026-08-11T11:04:51.577947Z","end":"2026-08-11T11:04:51.722639Z","steps":["trace[795412728] 'read index received' (duration: 144.627342ms)","trace[795412728] 'applied index is now lower than readState.Index' (duration: 63.941µs)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:04:51.72293Z","caller":"traceutil/trace.go:171","msg":"trace[794258253] transaction","detail":"{read_only:false; response_revision:409; number_of_response:1; }","duration":"197.517738ms","start":"2026-08-11T11:04:51.525404Z","end":"2026-08-11T11:04:51.722922Z","steps":["trace[794258253] 'process raft request' (duration: 197.08899ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:04:51.723215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.238082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-11T11:04:51.72325Z","caller":"traceutil/trace.go:171","msg":"trace[9342292] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:409; }","duration":"145.353605ms","start":"2026-08-11T11:04:51.577887Z","end":"2026-08-11T11:04:51.723241Z","steps":["trace[9342292] 'agreement among raft nodes before linearized reading' (duration: 145.261523ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:04:51.762197Z","caller":"traceutil/trace.go:171","msg":"trace[1897673304] transaction","detail":"{read_only:false; response_revision:410; number_of_response:1; }","duration":"165.902445ms","start":"2026-08-11T11:04:51.596262Z","end":"2026-08-11T11:04:51.762164Z","steps":["trace[1897673304] 'process raft request' (duration: 165.376025ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:04:51.887403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.334514ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-11T11:04:51.887751Z","caller":"traceutil/trace.go:171","msg":"trace[1056308346] transaction","detail":"{read_only:false; response_revision:412; number_of_response:1; }","duration":"120.648709ms","start":"2026-08-11T11:04:51.767058Z","end":"2026-08-11T11:04:51.887707Z","steps":["trace[1056308346] 'process raft request' (duration: 120.560588ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:04:51.887898Z","caller":"traceutil/trace.go:171","msg":"trace[532447647] transaction","detail":"{read_only:false; response_revision:411; number_of_response:1; }","duration":"160.523745ms","start":"2026-08-11T11:04:51.727317Z","end":"2026-08-11T11:04:51.887841Z","steps":["trace[532447647] 'process raft request' (duration: 47.381355ms)","trace[532447647] 'compare' (duration: 112.217542ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:04:52.208651Z","caller":"traceutil/trace.go:171","msg":"trace[1684292127] transaction","detail":"{read_only:false; response_revision:414; number_of_response:1; }","duration":"100.796597ms","start":"2026-08-11T11:04:52.107783Z","end":"2026-08-11T11:04:52.20858Z","steps":["trace[1684292127] 'process raft request' (duration: 100.573204ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:05:00.890131Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9213414792962910678,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-11T11:05:01.008616Z","caller":"traceutil/trace.go:171","msg":"trace[1208209596] linearizableReadLoop","detail":"{readStateIndex:449; appliedIndex:448; }","duration":"619.100302ms","start":"2026-08-11T11:05:00.389452Z","end":"2026-08-11T11:05:01.008553Z","steps":["trace[1208209596] 'read index received' (duration: 618.848118ms)","trace[1208209596] 'applied index is now lower than readState.Index' (duration: 250.744µs)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:05:01.008746Z","caller":"traceutil/trace.go:171","msg":"trace[1900166538] transaction","detail":"{read_only:false; response_revision:433; number_of_response:1; }","duration":"758.018319ms","start":"2026-08-11T11:05:00.250659Z","end":"2026-08-11T11:05:01.008677Z","steps":["trace[1900166538] 'process raft request' (duration: 757.728514ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:05:01.008877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"619.406317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-08-11T11:05:01.008953Z","caller":"traceutil/trace.go:171","msg":"trace[667245561] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:433; }","duration":"619.51711ms","start":"2026-08-11T11:05:00.389423Z","end":"2026-08-11T11:05:01.00894Z","steps":["trace[667245561] 'agreement among raft nodes before linearized reading' (duration: 619.306966ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:05:01.008997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:05:00.250625Z","time spent":"758.271082ms","remote":"127.0.0.1:55560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T11:05:01.009022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:05:00.389406Z","time spent":"619.579451ms","remote":"127.0.0.1:55560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-11T11:05:06.471589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.042013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4374"} {"level":"info","ts":"2026-08-11T11:05:06.471685Z","caller":"traceutil/trace.go:171","msg":"trace[118356693] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:446; }","duration":"291.160595ms","start":"2026-08-11T11:05:06.180504Z","end":"2026-08-11T11:05:06.471664Z","steps":["trace[118356693] 'range keys from in-memory index tree' (duration: 290.864451ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:05:10.077205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.231361ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-11T11:05:10.077336Z","caller":"traceutil/trace.go:171","msg":"trace[869096776] linearizableReadLoop","detail":"{readStateIndex:500; appliedIndex:499; }","duration":"549.585096ms","start":"2026-08-11T11:05:09.527729Z","end":"2026-08-11T11:05:10.077314Z","steps":["trace[869096776] 'read index received' (duration: 202.12402ms)","trace[869096776] 'applied index is now lower than readState.Index' (duration: 347.459816ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T11:05:10.077461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"549.730318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T11:05:10.077494Z","caller":"traceutil/trace.go:171","msg":"trace[695735283] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:482; }","duration":"549.810379ms","start":"2026-08-11T11:05:09.527672Z","end":"2026-08-11T11:05:10.077483Z","steps":["trace[695735283] 'agreement among raft nodes before linearized reading' (duration: 549.755098ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:05:10.077529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:05:09.527642Z","time spent":"549.880101ms","remote":"127.0.0.1:55238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-11T11:05:10.077665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.557994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.21\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-08-11T11:05:10.077449Z","caller":"traceutil/trace.go:171","msg":"trace[1674356977] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"946.519435ms","start":"2026-08-11T11:05:09.1309Z","end":"2026-08-11T11:05:10.077419Z","steps":["trace[1674356977] 'process raft request' (duration: 598.978519ms)","trace[1674356977] 'compare' (duration: 347.056439ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:05:10.077763Z","caller":"traceutil/trace.go:171","msg":"trace[1531124590] range","detail":"{range_begin:/registry/masterleases/199.19.213.21; range_end:; response_count:1; response_revision:482; }","duration":"391.680146ms","start":"2026-08-11T11:05:09.686058Z","end":"2026-08-11T11:05:10.077738Z","steps":["trace[1531124590] 'agreement among raft nodes before linearized reading' (duration: 391.467943ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:05:10.077814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:05:09.685977Z","time spent":"391.827009ms","remote":"127.0.0.1:55286","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.19.213.21\" "} {"level":"warn","ts":"2026-08-11T11:05:10.077886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:05:09.130875Z","time spent":"946.894161ms","remote":"127.0.0.1:55560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T11:05:23.054882Z","caller":"traceutil/trace.go:171","msg":"trace[177018587] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"461.37334ms","start":"2026-08-11T11:05:22.593472Z","end":"2026-08-11T11:05:23.054845Z","steps":["trace[177018587] 'process raft request' (duration: 461.132337ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:05:23.055078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:05:22.593448Z","time spent":"461.527833ms","remote":"127.0.0.1:55290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-11T11:05:23.067985Z","caller":"traceutil/trace.go:171","msg":"trace[58190342] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"466.954482ms","start":"2026-08-11T11:05:22.600993Z","end":"2026-08-11T11:05:23.067947Z","steps":["trace[58190342] 'process raft request' (duration: 466.722229ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:05:23.068179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:05:22.600965Z","time spent":"467.127125ms","remote":"127.0.0.1:55290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-11T11:05:23.068218Z","caller":"traceutil/trace.go:171","msg":"trace[388526600] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"465.115675ms","start":"2026-08-11T11:05:22.603059Z","end":"2026-08-11T11:05:23.068175Z","steps":["trace[388526600] 'process raft request' (duration: 464.80526ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:05:23.068394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:05:22.603042Z","time spent":"465.271997ms","remote":"127.0.0.1:55290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-11T11:05:23.068834Z","caller":"traceutil/trace.go:171","msg":"trace[436207848] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"443.358116ms","start":"2026-08-11T11:05:22.625422Z","end":"2026-08-11T11:05:23.06878Z","steps":["trace[436207848] 'process raft request' (duration: 442.688126ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:05:23.068899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:05:22.625403Z","time spent":"443.466908ms","remote":"127.0.0.1:55290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-11T11:05:23.069181Z","caller":"traceutil/trace.go:171","msg":"trace[201511164] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"443.651201ms","start":"2026-08-11T11:05:22.625516Z","end":"2026-08-11T11:05:23.069167Z","steps":["trace[201511164] 'process raft request' (duration: 443.096153ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:05:23.069244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:05:22.625497Z","time spent":"443.721992ms","remote":"127.0.0.1:55290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-11T11:05:23.070123Z","caller":"traceutil/trace.go:171","msg":"trace[1339371317] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"299.565132ms","start":"2026-08-11T11:05:22.770542Z","end":"2026-08-11T11:05:23.070107Z","steps":["trace[1339371317] 'process raft request' (duration: 299.495531ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:05:23.070439Z","caller":"traceutil/trace.go:171","msg":"trace[1890094258] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"443.020251ms","start":"2026-08-11T11:05:22.627407Z","end":"2026-08-11T11:05:23.070427Z","steps":["trace[1890094258] 'process raft request' (duration: 441.61125ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:05:23.07053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:05:22.627399Z","time spent":"443.076412ms","remote":"127.0.0.1:55560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-11T11:05:23.071671Z","caller":"traceutil/trace.go:171","msg":"trace[298728733] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"442.924201ms","start":"2026-08-11T11:05:22.628731Z","end":"2026-08-11T11:05:23.071656Z","steps":["trace[298728733] 'process raft request' (duration: 440.90317ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:05:23.071803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:05:22.628724Z","time spent":"443.047312ms","remote":"127.0.0.1:55290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-11T11:05:23.071953Z","caller":"traceutil/trace.go:171","msg":"trace[207370359] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"440.894879ms","start":"2026-08-11T11:05:22.631048Z","end":"2026-08-11T11:05:23.071943Z","steps":["trace[207370359] 'process raft request' (duration: 438.655505ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:05:23.072001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:05:22.631029Z","time spent":"440.948389ms","remote":"127.0.0.1:55290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-11T11:05:23.072225Z","caller":"traceutil/trace.go:171","msg":"trace[1535856152] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"401.123387ms","start":"2026-08-11T11:05:22.671075Z","end":"2026-08-11T11:05:23.072198Z","steps":["trace[1535856152] 'process raft request' (duration: 398.883854ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:05:23.07234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:05:22.671058Z","time spent":"401.224838ms","remote":"127.0.0.1:55290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-11T11:05:23.072465Z","caller":"traceutil/trace.go:171","msg":"trace[1492229911] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"419.239261ms","start":"2026-08-11T11:05:22.653212Z","end":"2026-08-11T11:05:23.072451Z","steps":["trace[1492229911] 'process raft request' (duration: 416.593091ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:05:23.072526Z","caller":"traceutil/trace.go:171","msg":"trace[578463152] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"413.595496ms","start":"2026-08-11T11:05:22.658915Z","end":"2026-08-11T11:05:23.072511Z","steps":["trace[578463152] 'process raft request' (duration: 410.939707ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:05:23.072527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:05:22.653192Z","time spent":"419.306712ms","remote":"127.0.0.1:55290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-11T11:05:23.072577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:05:22.658879Z","time spent":"413.673536ms","remote":"127.0.0.1:55290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-11T11:05:23.072649Z","caller":"traceutil/trace.go:171","msg":"trace[1397751058] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"439.444947ms","start":"2026-08-11T11:05:22.633192Z","end":"2026-08-11T11:05:23.072637Z","steps":["trace[1397751058] 'process raft request' (duration: 436.553962ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:05:23.072731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:05:22.633175Z","time spent":"439.498748ms","remote":"127.0.0.1:55290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-11T11:05:24.335905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.19779ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-11T11:05:24.336037Z","caller":"traceutil/trace.go:171","msg":"trace[1708009920] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"283.607571ms","start":"2026-08-11T11:05:24.052408Z","end":"2026-08-11T11:05:24.336015Z","steps":["trace[1708009920] 'process raft request' (duration: 114.228868ms)","trace[1708009920] 'compare' (duration: 168.51423ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:05:24.33844Z","caller":"traceutil/trace.go:171","msg":"trace[1865120886] linearizableReadLoop","detail":"{readStateIndex:640; appliedIndex:638; }","duration":"191.682196ms","start":"2026-08-11T11:05:24.146732Z","end":"2026-08-11T11:05:24.338414Z","steps":["trace[1865120886] 'read index received' (duration: 19.469971ms)","trace[1865120886] 'applied index is now lower than readState.Index' (duration: 172.211445ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:05:24.338514Z","caller":"traceutil/trace.go:171","msg":"trace[351487546] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"248.486207ms","start":"2026-08-11T11:05:24.09001Z","end":"2026-08-11T11:05:24.338497Z","steps":["trace[351487546] 'process raft request' (duration: 248.296493ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:05:24.338594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.845019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-08-11T11:05:24.338625Z","caller":"traceutil/trace.go:171","msg":"trace[1288208017] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:616; }","duration":"191.93054ms","start":"2026-08-11T11:05:24.146686Z","end":"2026-08-11T11:05:24.338617Z","steps":["trace[1288208017] 'agreement among raft nodes before linearized reading' (duration: 191.850409ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:05:24.338655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.088261ms","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-08-11T11:05:24.338679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.244497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-11T11:05:24.338713Z","caller":"traceutil/trace.go:171","msg":"trace[1001326754] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:616; }","duration":"145.034789ms","start":"2026-08-11T11:05:24.193666Z","end":"2026-08-11T11:05:24.338701Z","steps":["trace[1001326754] 'agreement among raft nodes before linearized reading' (duration: 144.973019ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:05:24.338712Z","caller":"traceutil/trace.go:171","msg":"trace[107790052] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:616; }","duration":"145.184971ms","start":"2026-08-11T11:05:24.193512Z","end":"2026-08-11T11:05:24.338697Z","steps":["trace[107790052] 'agreement among raft nodes before linearized reading' (duration: 145.08968ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:05:25.250129Z","caller":"traceutil/trace.go:171","msg":"trace[181712552] linearizableReadLoop","detail":"{readStateIndex:651; appliedIndex:650; }","duration":"158.454476ms","start":"2026-08-11T11:05:25.091647Z","end":"2026-08-11T11:05:25.250102Z","steps":["trace[181712552] 'read index received' (duration: 158.06644ms)","trace[181712552] 'applied index is now lower than readState.Index' (duration: 386.486µs)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:05:25.25027Z","caller":"traceutil/trace.go:171","msg":"trace[1184291857] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"208.615332ms","start":"2026-08-11T11:05:25.041643Z","end":"2026-08-11T11:05:25.250258Z","steps":["trace[1184291857] 'process raft request' (duration: 208.081273ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:05:25.250585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.893923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/xbackendtrafficpolicies.gateway.networking.x-k8s.io\" ","response":"range_response_count:1 size:23081"} {"level":"info","ts":"2026-08-11T11:05:25.250652Z","caller":"traceutil/trace.go:171","msg":"trace[428051422] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/xbackendtrafficpolicies.gateway.networking.x-k8s.io; range_end:; response_count:1; response_revision:627; }","duration":"159.008314ms","start":"2026-08-11T11:05:25.091627Z","end":"2026-08-11T11:05:25.250635Z","steps":["trace[428051422] 'agreement among raft nodes before linearized reading' (duration: 158.677299ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:05:30.635189Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9213414792962911095,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-11T11:05:30.647794Z","caller":"traceutil/trace.go:171","msg":"trace[308522989] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"552.204889ms","start":"2026-08-11T11:05:30.09556Z","end":"2026-08-11T11:05:30.647765Z","steps":["trace[308522989] 'process raft request' (duration: 551.929655ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:05:30.647965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:05:30.095541Z","time spent":"552.357151ms","remote":"127.0.0.1:55320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":688,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-11T11:05:30.65988Z","caller":"traceutil/trace.go:171","msg":"trace[1868865522] linearizableReadLoop","detail":"{readStateIndex:682; appliedIndex:680; }","duration":"524.79388ms","start":"2026-08-11T11:05:30.135054Z","end":"2026-08-11T11:05:30.659848Z","steps":["trace[1868865522] 'read index received' (duration: 512.563364ms)","trace[1868865522] 'applied index is now lower than readState.Index' (duration: 12.229146ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:05:30.660132Z","caller":"traceutil/trace.go:171","msg":"trace[882263310] transaction","detail":"{read_only:false; response_revision:656; number_of_response:1; }","duration":"241.805512ms","start":"2026-08-11T11:05:30.418284Z","end":"2026-08-11T11:05:30.66009Z","steps":["trace[882263310] 'process raft request' (duration: 237.995927ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:05:30.660222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"525.165167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-08-11T11:05:30.660287Z","caller":"traceutil/trace.go:171","msg":"trace[1949594986] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:656; }","duration":"525.290528ms","start":"2026-08-11T11:05:30.13498Z","end":"2026-08-11T11:05:30.660271Z","steps":["trace[1949594986] 'agreement among raft nodes before linearized reading' (duration: 525.120006ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:05:30.660344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:05:30.134937Z","time spent":"525.39476ms","remote":"127.0.0.1:55372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":374,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-08-11T11:05:30.660428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.618717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T11:05:30.660565Z","caller":"traceutil/trace.go:171","msg":"trace[996537220] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:656; }","duration":"238.86526ms","start":"2026-08-11T11:05:30.421606Z","end":"2026-08-11T11:05:30.660471Z","steps":["trace[996537220] 'agreement among raft nodes before linearized reading' (duration: 238.621256ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:05:30.660647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.772341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-08-11T11:05:30.660779Z","caller":"traceutil/trace.go:171","msg":"trace[312800727] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:656; }","duration":"261.940053ms","start":"2026-08-11T11:05:30.398798Z","end":"2026-08-11T11:05:30.660738Z","steps":["trace[312800727] 'agreement among raft nodes before linearized reading' (duration: 261.544308ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:05:30.662982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.953562ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-08-11T11:05:30.661815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.064348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-08-11T11:05:30.665248Z","caller":"traceutil/trace.go:171","msg":"trace[1614043229] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:656; }","duration":"270.515368ms","start":"2026-08-11T11:05:30.394686Z","end":"2026-08-11T11:05:30.665201Z","steps":["trace[1614043229] 'agreement among raft nodes before linearized reading' (duration: 265.478715ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:05:30.665096Z","caller":"traceutil/trace.go:171","msg":"trace[34503716] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:656; }","duration":"269.359192ms","start":"2026-08-11T11:05:30.39499Z","end":"2026-08-11T11:05:30.664349Z","steps":["trace[34503716] 'agreement among raft nodes before linearized reading' (duration: 265.702839ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:05:31.801897Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9213414792962911110,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-11T11:05:31.806052Z","caller":"traceutil/trace.go:171","msg":"trace[86414595] linearizableReadLoop","detail":"{readStateIndex:688; appliedIndex:687; }","duration":"505.227401ms","start":"2026-08-11T11:05:31.300787Z","end":"2026-08-11T11:05:31.806014Z","steps":["trace[86414595] 'read index received' (duration: 504.111944ms)","trace[86414595] 'applied index is now lower than readState.Index' (duration: 1.113817ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:05:31.806094Z","caller":"traceutil/trace.go:171","msg":"trace[270055894] transaction","detail":"{read_only:false; response_revision:662; number_of_response:1; }","duration":"581.478209ms","start":"2026-08-11T11:05:31.224589Z","end":"2026-08-11T11:05:31.806067Z","steps":["trace[270055894] 'process raft request' (duration: 580.709379ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:05:31.806293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.780065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-11T11:05:31.806312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"505.518925ms","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-08-11T11:05:31.806335Z","caller":"traceutil/trace.go:171","msg":"trace[1716562882] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:662; }","duration":"382.870367ms","start":"2026-08-11T11:05:31.423456Z","end":"2026-08-11T11:05:31.806326Z","steps":["trace[1716562882] 'agreement among raft nodes before linearized reading' (duration: 382.795296ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:05:31.80636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:05:31.423427Z","time spent":"382.927627ms","remote":"127.0.0.1:55240","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-08-11T11:05:31.806365Z","caller":"traceutil/trace.go:171","msg":"trace[1522335738] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:662; }","duration":"505.614626ms","start":"2026-08-11T11:05:31.300736Z","end":"2026-08-11T11:05:31.80635Z","steps":["trace[1522335738] 'agreement among raft nodes before linearized reading' (duration: 505.418914ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:05:31.806377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:05:31.224565Z","time spent":"581.654472ms","remote":"127.0.0.1:55344","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036305,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T11:05:31.8064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:05:31.300713Z","time spent":"505.680087ms","remote":"127.0.0.1:55560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-11T11:05:32.924369Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9213414792962911113,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-11T11:05:33.425621Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9213414792962911113,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-11T11:05:33.552518Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.675794198s","expected-duration":"1s"} {"level":"info","ts":"2026-08-11T11:05:33.553091Z","caller":"traceutil/trace.go:171","msg":"trace[285874076] transaction","detail":"{read_only:false; response_revision:664; number_of_response:1; }","duration":"1.676455009s","start":"2026-08-11T11:05:31.876595Z","end":"2026-08-11T11:05:33.55305Z","steps":["trace[285874076] 'process raft request' (duration: 1.676228736s)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:05:33.553298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:05:31.876573Z","time spent":"1.676622801s","remote":"127.0.0.1:55522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-11T11:05:33.590411Z","caller":"traceutil/trace.go:171","msg":"trace[48848006] linearizableReadLoop","detail":"{readStateIndex:691; appliedIndex:689; }","duration":"1.166641277s","start":"2026-08-11T11:05:32.423737Z","end":"2026-08-11T11:05:33.590379Z","steps":["trace[48848006] 'read index received' (duration: 1.129107161s)","trace[48848006] 'applied index is now lower than readState.Index' (duration: 37.532816ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T11:05:33.59063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.166867239s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T11:05:33.590678Z","caller":"traceutil/trace.go:171","msg":"trace[487500202] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:665; }","duration":"1.166998102s","start":"2026-08-11T11:05:32.423666Z","end":"2026-08-11T11:05:33.590664Z","steps":["trace[487500202] 'agreement among raft nodes before linearized reading' (duration: 1.16688431s)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:05:33.590761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:05:32.4236Z","time spent":"1.167151704s","remote":"127.0.0.1:55238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-11T11:05:33.590816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"905.38246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-11T11:05:33.590872Z","caller":"traceutil/trace.go:171","msg":"trace[1771231149] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:665; }","duration":"905.505552ms","start":"2026-08-11T11:05:32.685353Z","end":"2026-08-11T11:05:33.590858Z","steps":["trace[1771231149] 'agreement among raft nodes before linearized reading' (duration: 905.31376ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:05:33.590906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:05:32.685311Z","time spent":"905.589963ms","remote":"127.0.0.1:55560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-11T11:05:33.59101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"911.185323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-08-11T11:05:33.591125Z","caller":"traceutil/trace.go:171","msg":"trace[475739799] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:665; }","duration":"911.358296ms","start":"2026-08-11T11:05:32.679747Z","end":"2026-08-11T11:05:33.591106Z","steps":["trace[475739799] 'agreement among raft nodes before linearized reading' (duration: 910.897899ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:05:33.591226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:05:32.679725Z","time spent":"911.486997ms","remote":"127.0.0.1:55560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-11T11:05:33.591541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"905.751866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-08-11T11:05:33.591646Z","caller":"traceutil/trace.go:171","msg":"trace[1409184717] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:665; }","duration":"905.935698ms","start":"2026-08-11T11:05:32.685681Z","end":"2026-08-11T11:05:33.591617Z","steps":["trace[1409184717] 'agreement among raft nodes before linearized reading' (duration: 904.966904ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:05:33.591583Z","caller":"traceutil/trace.go:171","msg":"trace[1750016631] transaction","detail":"{read_only:false; response_revision:665; number_of_response:1; }","duration":"509.197315ms","start":"2026-08-11T11:05:33.081292Z","end":"2026-08-11T11:05:33.590489Z","steps":["trace[1750016631] 'process raft request' (duration: 508.819079ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:05:33.591699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:05:32.685653Z","time spent":"906.03438ms","remote":"127.0.0.1:55560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-11T11:05:33.591837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:05:33.081259Z","time spent":"510.502713ms","remote":"127.0.0.1:55560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T11:05:33.698034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.821135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T11:05:33.698099Z","caller":"traceutil/trace.go:171","msg":"trace[1259196145] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:665; }","duration":"104.916966ms","start":"2026-08-11T11:05:33.593167Z","end":"2026-08-11T11:05:33.698084Z","steps":["trace[1259196145] 'range keys from in-memory index tree' (duration: 104.746124ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:05:33.698226Z","caller":"traceutil/trace.go:171","msg":"trace[1039308526] linearizableReadLoop","detail":"{readStateIndex:692; appliedIndex:691; }","duration":"101.722512ms","start":"2026-08-11T11:05:33.596472Z","end":"2026-08-11T11:05:33.698194Z","steps":["trace[1039308526] 'read index received' (duration: 61.383746ms)","trace[1039308526] 'applied index is now lower than readState.Index' (duration: 40.337736ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:05:33.698414Z","caller":"traceutil/trace.go:171","msg":"trace[1014070644] transaction","detail":"{read_only:false; response_revision:666; number_of_response:1; }","duration":"101.918165ms","start":"2026-08-11T11:05:33.596415Z","end":"2026-08-11T11:05:33.698333Z","steps":["trace[1014070644] 'process raft request' (duration: 61.442327ms)","trace[1014070644] 'compare' (duration: 40.132452ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:05:33.69858Z","caller":"traceutil/trace.go:171","msg":"trace[1742568436] transaction","detail":"{read_only:false; response_revision:667; number_of_response:1; }","duration":"101.933605ms","start":"2026-08-11T11:05:33.596627Z","end":"2026-08-11T11:05:33.69856Z","steps":["trace[1742568436] 'process raft request' (duration: 101.532898ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:05:33.698462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.986085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-08-11T11:05:33.698687Z","caller":"traceutil/trace.go:171","msg":"trace[699033731] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:667; }","duration":"102.265369ms","start":"2026-08-11T11:05:33.596408Z","end":"2026-08-11T11:05:33.698674Z","steps":["trace[699033731] 'agreement among raft nodes before linearized reading' (duration: 101.884934ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:05:33.698638Z","caller":"traceutil/trace.go:171","msg":"trace[1366074471] transaction","detail":"{read_only:false; response_revision:668; number_of_response:1; }","duration":"100.520015ms","start":"2026-08-11T11:05:33.5981Z","end":"2026-08-11T11:05:33.69862Z","steps":["trace[1366074471] 'process raft request' (duration: 100.321652ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:05:50.576649Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9213414792962911530,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-11T11:05:50.653697Z","caller":"traceutil/trace.go:171","msg":"trace[538747970] linearizableReadLoop","detail":"{readStateIndex:866; appliedIndex:865; }","duration":"577.676843ms","start":"2026-08-11T11:05:50.075992Z","end":"2026-08-11T11:05:50.653669Z","steps":["trace[538747970] 'read index received' (duration: 577.44922ms)","trace[538747970] 'applied index is now lower than readState.Index' (duration: 226.413µs)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:05:50.653974Z","caller":"traceutil/trace.go:171","msg":"trace[1166618029] transaction","detail":"{read_only:false; response_revision:834; number_of_response:1; }","duration":"699.68062ms","start":"2026-08-11T11:05:49.954262Z","end":"2026-08-11T11:05:50.653943Z","steps":["trace[1166618029] 'process raft request' (duration: 699.212293ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:05:50.654165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:05:49.954245Z","time spent":"699.829351ms","remote":"127.0.0.1:55560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T11:05:50.65453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"578.507175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T11:05:50.654666Z","caller":"traceutil/trace.go:171","msg":"trace[1757362497] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:834; }","duration":"578.716647ms","start":"2026-08-11T11:05:50.075919Z","end":"2026-08-11T11:05:50.654635Z","steps":["trace[1757362497] 'agreement among raft nodes before linearized reading' (duration: 578.487094ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:05:50.654742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:05:50.07589Z","time spent":"578.834179ms","remote":"127.0.0.1:55676","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":28,"request content":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true "} {"level":"warn","ts":"2026-08-11T11:05:50.654724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.531205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-11T11:05:50.654839Z","caller":"traceutil/trace.go:171","msg":"trace[190784951] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:834; }","duration":"230.720239ms","start":"2026-08-11T11:05:50.42408Z","end":"2026-08-11T11:05:50.6548Z","steps":["trace[190784951] 'agreement among raft nodes before linearized reading' (duration: 230.536207ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:06:41.422118Z","caller":"traceutil/trace.go:171","msg":"trace[1087813923] transaction","detail":"{read_only:false; response_revision:1130; number_of_response:1; }","duration":"452.784056ms","start":"2026-08-11T11:06:40.969308Z","end":"2026-08-11T11:06:41.422092Z","steps":["trace[1087813923] 'process raft request' (duration: 452.621645ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:06:41.422294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-11T11:06:40.969275Z","time spent":"452.930679ms","remote":"127.0.0.1:55560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-11T11:06:42.206603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.007442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-08-11T11:06:42.206809Z","caller":"traceutil/trace.go:171","msg":"trace[569612708] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1131; }","duration":"125.291366ms","start":"2026-08-11T11:06:42.081498Z","end":"2026-08-11T11:06:42.206789Z","steps":["trace[569612708] 'range keys from in-memory index tree' (duration: 124.783519ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:06:42.206667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.447119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-11T11:06:42.207078Z","caller":"traceutil/trace.go:171","msg":"trace[1503042101] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1131; }","duration":"111.887065ms","start":"2026-08-11T11:06:42.095166Z","end":"2026-08-11T11:06:42.207053Z","steps":["trace[1503042101] 'range keys from in-memory index tree' (duration: 111.298638ms)"],"step_count":1} {"level":"warn","ts":"2026-08-11T11:06:42.206623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.05991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-08-11T11:06:42.207628Z","caller":"traceutil/trace.go:171","msg":"trace[159803129] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1131; }","duration":"108.120481ms","start":"2026-08-11T11:06:42.099455Z","end":"2026-08-11T11:06:42.207575Z","steps":["trace[159803129] 'range keys from in-memory index tree' (duration: 106.779127ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:07:24.665008Z","caller":"traceutil/trace.go:171","msg":"trace[1586189151] transaction","detail":"{read_only:false; response_revision:1272; number_of_response:1; }","duration":"107.309744ms","start":"2026-08-11T11:07:24.557663Z","end":"2026-08-11T11:07:24.664973Z","steps":["trace[1586189151] 'process raft request' (duration: 67.28938ms)","trace[1586189151] 'compare' (duration: 39.874921ms)"],"step_count":2} {"level":"info","ts":"2026-08-11T11:07:25.644661Z","caller":"traceutil/trace.go:171","msg":"trace[578441400] transaction","detail":"{read_only:false; response_revision:1274; number_of_response:1; }","duration":"161.11359ms","start":"2026-08-11T11:07:25.483487Z","end":"2026-08-11T11:07:25.6446Z","steps":["trace[578441400] 'process raft request' (duration: 160.825287ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:07:46.835508Z","caller":"traceutil/trace.go:171","msg":"trace[123857211] transaction","detail":"{read_only:false; response_revision:1374; number_of_response:1; }","duration":"256.731084ms","start":"2026-08-11T11:07:46.578749Z","end":"2026-08-11T11:07:46.83548Z","steps":["trace[123857211] 'process raft request' (duration: 256.507821ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:07:46.836929Z","caller":"traceutil/trace.go:171","msg":"trace[1190727101] linearizableReadLoop","detail":"{readStateIndex:1433; appliedIndex:1431; }","duration":"107.43106ms","start":"2026-08-11T11:07:46.729472Z","end":"2026-08-11T11:07:46.836903Z","steps":["trace[1190727101] 'read index received' (duration: 105.833934ms)","trace[1190727101] 'applied index is now lower than readState.Index' (duration: 1.595976ms)"],"step_count":2} {"level":"warn","ts":"2026-08-11T11:07:46.837266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.775754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-11T11:07:46.837286Z","caller":"traceutil/trace.go:171","msg":"trace[1762649529] transaction","detail":"{read_only:false; response_revision:1375; number_of_response:1; }","duration":"111.186618ms","start":"2026-08-11T11:07:46.726049Z","end":"2026-08-11T11:07:46.837235Z","steps":["trace[1762649529] 'process raft request' (duration: 110.781124ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:07:46.837349Z","caller":"traceutil/trace.go:171","msg":"trace[925683034] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1375; }","duration":"107.910296ms","start":"2026-08-11T11:07:46.729417Z","end":"2026-08-11T11:07:46.837327Z","steps":["trace[925683034] 'agreement among raft nodes before linearized reading' (duration: 107.669173ms)"],"step_count":1} {"level":"info","ts":"2026-08-11T11:07:56.325383Z","caller":"traceutil/trace.go:171","msg":"trace[1815273682] transaction","detail":"{read_only:false; response_revision:1412; number_of_response:1; }","duration":"262.081706ms","start":"2026-08-11T11:07:56.063262Z","end":"2026-08-11T11:07:56.325343Z","steps":["trace[1815273682] 'process raft request' (duration: 261.862499ms)"],"step_count":1}