{"level":"warn","ts":"2026-08-06T01:06:12.163984Z","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-06T01:06:12.164098Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.65: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.65:2380","--initial-cluster=instance=https://199.19.213.65:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.65:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.65: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-06T01:06:12.164182Z","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-06T01:06:12.164198Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.65:2380"]} {"level":"info","ts":"2026-08-06T01:06:12.164228Z","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-06T01:06:12.165041Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.65:2379"]} {"level":"info","ts":"2026-08-06T01:06:12.165111Z","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.65:2380"],"listen-peer-urls":["https://199.19.213.65:2380"],"advertise-client-urls":["https://199.19.213.65:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.65:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.65: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-06T01:06:12.167916Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.595523ms"} {"level":"info","ts":"2026-08-06T01:06:12.173882Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"a071a454a8148542","cluster-id":"aa233e43aeaf7fe5"} {"level":"info","ts":"2026-08-06T01:06:12.17396Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a071a454a8148542 switched to configuration voters=()"} {"level":"info","ts":"2026-08-06T01:06:12.173983Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a071a454a8148542 became follower at term 0"} {"level":"info","ts":"2026-08-06T01:06:12.173994Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft a071a454a8148542 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-06T01:06:12.174009Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a071a454a8148542 became follower at term 1"} {"level":"info","ts":"2026-08-06T01:06:12.174067Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a071a454a8148542 switched to configuration voters=(11561202401940899138)"} {"level":"warn","ts":"2026-08-06T01:06:12.186651Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-06T01:06:12.189035Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-06T01:06:12.190145Z","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-06T01:06:12.194199Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"a071a454a8148542","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-06T01:06:12.194302Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"a071a454a8148542","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-06T01:06:12.194776Z","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-06T01:06:12.19487Z","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-06T01:06:12.194884Z","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-06T01:06:12.195601Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a071a454a8148542 switched to configuration voters=(11561202401940899138)"} {"level":"info","ts":"2026-08-06T01:06:12.195752Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"aa233e43aeaf7fe5","local-member-id":"a071a454a8148542","added-peer-id":"a071a454a8148542","added-peer-peer-urls":["https://199.19.213.65:2380"]} {"level":"info","ts":"2026-08-06T01:06:12.201765Z","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-06T01:06:12.20193Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.65:2380"} {"level":"info","ts":"2026-08-06T01:06:12.201968Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.65:2380"} {"level":"info","ts":"2026-08-06T01:06:12.20201Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"a071a454a8148542","initial-advertise-peer-urls":["https://199.19.213.65:2380"],"listen-peer-urls":["https://199.19.213.65:2380"],"advertise-client-urls":["https://199.19.213.65:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.65:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-06T01:06:12.202066Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-06T01:06:12.474482Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a071a454a8148542 is starting a new election at term 1"} {"level":"info","ts":"2026-08-06T01:06:12.474578Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a071a454a8148542 became pre-candidate at term 1"} {"level":"info","ts":"2026-08-06T01:06:12.474618Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a071a454a8148542 received MsgPreVoteResp from a071a454a8148542 at term 1"} {"level":"info","ts":"2026-08-06T01:06:12.474633Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a071a454a8148542 became candidate at term 2"} {"level":"info","ts":"2026-08-06T01:06:12.474645Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a071a454a8148542 received MsgVoteResp from a071a454a8148542 at term 2"} {"level":"info","ts":"2026-08-06T01:06:12.474655Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a071a454a8148542 became leader at term 2"} {"level":"info","ts":"2026-08-06T01:06:12.474668Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: a071a454a8148542 elected leader a071a454a8148542 at term 2"} {"level":"info","ts":"2026-08-06T01:06:12.47536Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-06T01:06:12.476599Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-06T01:06:12.476597Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"a071a454a8148542","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.65:2379]}","request-path":"/0/members/a071a454a8148542/attributes","cluster-id":"aa233e43aeaf7fe5","publish-timeout":"7s"} {"level":"info","ts":"2026-08-06T01:06:12.476664Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-06T01:06:12.476876Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-06T01:06:12.476921Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-06T01:06:12.478194Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"aa233e43aeaf7fe5","local-member-id":"a071a454a8148542","cluster-version":"3.5"} {"level":"info","ts":"2026-08-06T01:06:12.478308Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-06T01:06:12.478346Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-06T01:06:12.479Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.65:2379"} {"level":"info","ts":"2026-08-06T01:06:12.479422Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-06T01:06:19.937382Z","caller":"traceutil/trace.go:171","msg":"trace[1175508849] transaction","detail":"{read_only:false; response_revision:318; number_of_response:1; }","duration":"150.301043ms","start":"2026-08-06T01:06:19.787057Z","end":"2026-08-06T01:06:19.937358Z","steps":["trace[1175508849] 'process raft request' (duration: 146.1571ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:06:22.42279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.479931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/disruption-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-08-06T01:06:22.422936Z","caller":"traceutil/trace.go:171","msg":"trace[530949036] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/disruption-controller; range_end:; response_count:1; response_revision:336; }","duration":"136.833412ms","start":"2026-08-06T01:06:22.286078Z","end":"2026-08-06T01:06:22.422912Z","steps":["trace[530949036] 'range keys from in-memory index tree' (duration: 136.277035ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:06:27.938015Z","caller":"traceutil/trace.go:171","msg":"trace[579969326] transaction","detail":"{read_only:false; response_revision:407; number_of_response:1; }","duration":"110.391557ms","start":"2026-08-06T01:06:27.827597Z","end":"2026-08-06T01:06:27.937989Z","steps":["trace[579969326] 'process raft request' (duration: 110.245842ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:06:29.4259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.103968104s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T01:06:29.426006Z","caller":"traceutil/trace.go:171","msg":"trace[1818110897] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:407; }","duration":"1.104116869s","start":"2026-08-06T01:06:28.321864Z","end":"2026-08-06T01:06:29.425981Z","steps":["trace[1818110897] 'range keys from in-memory index tree' (duration: 1.103890602s)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:06:29.426061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:06:28.321845Z","time spent":"1.104205122s","remote":"127.0.0.1:36998","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-08-06T01:06:29.426263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"951.652466ms","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-06T01:06:29.426357Z","caller":"traceutil/trace.go:171","msg":"trace[850471722] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:407; }","duration":"951.826462ms","start":"2026-08-06T01:06:28.474508Z","end":"2026-08-06T01:06:29.426334Z","steps":["trace[850471722] 'range keys from in-memory index tree' (duration: 951.509462ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:06:29.426497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:06:28.474487Z","time spent":"951.995038ms","remote":"127.0.0.1:37234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-06T01:06:29.427582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"923.038413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-08-06T01:06:29.428555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"863.179799ms","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-06T01:06:29.428636Z","caller":"traceutil/trace.go:171","msg":"trace[573546596] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:407; }","duration":"863.236532ms","start":"2026-08-06T01:06:28.565362Z","end":"2026-08-06T01:06:29.428598Z","steps":["trace[573546596] 'range keys from in-memory index tree' (duration: 863.068166ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:06:29.428834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:06:28.565315Z","time spent":"863.5069ms","remote":"127.0.0.1:37234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-06T01:06:29.429601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"835.465725ms","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-06T01:06:29.429669Z","caller":"traceutil/trace.go:171","msg":"trace[2028823147] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:407; }","duration":"835.542797ms","start":"2026-08-06T01:06:28.594094Z","end":"2026-08-06T01:06:29.429637Z","steps":["trace[2028823147] 'range keys from in-memory index tree' (duration: 835.360391ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:06:29.429706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:06:28.594075Z","time spent":"835.622669ms","remote":"127.0.0.1:37234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-08-06T01:06:29.427786Z","caller":"traceutil/trace.go:171","msg":"trace[1790621753] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:407; }","duration":"924.074846ms","start":"2026-08-06T01:06:28.503697Z","end":"2026-08-06T01:06:29.427772Z","steps":["trace[1790621753] 'range keys from in-memory index tree' (duration: 922.860087ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:06:29.430728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:06:28.503674Z","time spent":"927.038261ms","remote":"127.0.0.1:37234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-06T01:06:29.939554Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9602413090993885591,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-06T01:06:30.1052Z","caller":"traceutil/trace.go:171","msg":"trace[1601316062] transaction","detail":"{read_only:false; response_revision:408; number_of_response:1; }","duration":"668.275813ms","start":"2026-08-06T01:06:29.436881Z","end":"2026-08-06T01:06:30.105157Z","steps":["trace[1601316062] 'process raft request' (duration: 667.977383ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:06:30.105994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:06:29.436858Z","time spent":"668.448908ms","remote":"127.0.0.1:37234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-06T01:06:30.798918Z","caller":"traceutil/trace.go:171","msg":"trace[374621341] transaction","detail":"{read_only:false; response_revision:409; number_of_response:1; }","duration":"1.361677917s","start":"2026-08-06T01:06:29.437163Z","end":"2026-08-06T01:06:30.798841Z","steps":["trace[374621341] 'process raft request' (duration: 1.3614617s)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:06:30.7993Z","caller":"traceutil/trace.go:171","msg":"trace[485969905] transaction","detail":"{read_only:false; response_revision:410; number_of_response:1; }","duration":"1.362013017s","start":"2026-08-06T01:06:29.437259Z","end":"2026-08-06T01:06:30.799272Z","steps":["trace[485969905] 'process raft request' (duration: 1.361533022s)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:06:30.799407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:06:29.437116Z","time spent":"1.361918095s","remote":"127.0.0.1:37234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-06T01:06:30.799415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:06:29.437229Z","time spent":"1.362123331s","remote":"127.0.0.1:37234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-06T01:06:30.799787Z","caller":"traceutil/trace.go:171","msg":"trace[1237852356] linearizableReadLoop","detail":"{readStateIndex:426; appliedIndex:422; }","duration":"1.360684715s","start":"2026-08-06T01:06:29.439044Z","end":"2026-08-06T01:06:30.799728Z","steps":["trace[1237852356] 'read index received' (duration: 665.759412ms)","trace[1237852356] 'applied index is now lower than readState.Index' (duration: 694.922503ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T01:06:30.799977Z","caller":"traceutil/trace.go:171","msg":"trace[61471299] transaction","detail":"{read_only:false; response_revision:411; number_of_response:1; }","duration":"1.362467653s","start":"2026-08-06T01:06:29.437493Z","end":"2026-08-06T01:06:30.799961Z","steps":["trace[61471299] 'process raft request' (duration: 1.361506332s)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:06:30.800063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"550.166437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6961"} {"level":"warn","ts":"2026-08-06T01:06:30.800126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:06:29.437483Z","time spent":"1.362542415s","remote":"127.0.0.1:37234","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-08-06T01:06:30.80014Z","caller":"traceutil/trace.go:171","msg":"trace[515495017] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:411; }","duration":"550.270981ms","start":"2026-08-06T01:06:30.249848Z","end":"2026-08-06T01:06:30.800119Z","steps":["trace[515495017] 'agreement among raft nodes before linearized reading' (duration: 550.140316ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:06:30.80021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:06:30.249823Z","time spent":"550.373634ms","remote":"127.0.0.1:37156","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":6985,"request content":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" "} {"level":"warn","ts":"2026-08-06T01:06:30.800274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.361229423s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T01:06:30.800334Z","caller":"traceutil/trace.go:171","msg":"trace[1626939087] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:411; }","duration":"1.361319506s","start":"2026-08-06T01:06:29.438996Z","end":"2026-08-06T01:06:30.800315Z","steps":["trace[1626939087] 'agreement among raft nodes before linearized reading' (duration: 1.361234143s)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:06:30.800376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:06:29.438984Z","time spent":"1.361381378s","remote":"127.0.0.1:36998","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-08-06T01:06:30.946576Z","caller":"traceutil/trace.go:171","msg":"trace[1505036555] transaction","detail":"{read_only:false; response_revision:412; number_of_response:1; }","duration":"136.193741ms","start":"2026-08-06T01:06:30.810353Z","end":"2026-08-06T01:06:30.946547Z","steps":["trace[1505036555] 'process raft request' (duration: 135.997745ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:06:54.715779Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9602413090993885846,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-06T01:06:54.795547Z","caller":"traceutil/trace.go:171","msg":"trace[1801265353] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"890.316007ms","start":"2026-08-06T01:06:53.905205Z","end":"2026-08-06T01:06:54.795521Z","steps":["trace[1801265353] 'process raft request' (duration: 890.177013ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:06:54.795691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:06:53.905183Z","time spent":"890.442671ms","remote":"127.0.0.1:37024","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-06T01:06:54.802439Z","caller":"traceutil/trace.go:171","msg":"trace[1239146987] linearizableReadLoop","detail":"{readStateIndex:561; appliedIndex:550; }","duration":"587.293655ms","start":"2026-08-06T01:06:54.215125Z","end":"2026-08-06T01:06:54.802418Z","steps":["trace[1239146987] 'read index received' (duration: 580.407605ms)","trace[1239146987] 'applied index is now lower than readState.Index' (duration: 6.8852ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T01:06:54.802674Z","caller":"traceutil/trace.go:171","msg":"trace[1238697760] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"880.153203ms","start":"2026-08-06T01:06:53.922511Z","end":"2026-08-06T01:06:54.802664Z","steps":["trace[1238697760] 'process raft request' (duration: 879.595775ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:06:54.802677Z","caller":"traceutil/trace.go:171","msg":"trace[132358521] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"882.405354ms","start":"2026-08-06T01:06:53.92024Z","end":"2026-08-06T01:06:54.802645Z","steps":["trace[132358521] 'process raft request' (duration: 881.826145ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:06:54.802769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:06:53.922501Z","time spent":"880.211264ms","remote":"127.0.0.1:37024","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-06T01:06:54.802782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:06:53.920233Z","time spent":"882.495797ms","remote":"127.0.0.1:37024","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-06T01:06:54.802884Z","caller":"traceutil/trace.go:171","msg":"trace[847128134] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"867.738765ms","start":"2026-08-06T01:06:53.935139Z","end":"2026-08-06T01:06:54.802878Z","steps":["trace[847128134] 'process raft request' (duration: 866.997522ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:06:54.80292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:06:53.935119Z","time spent":"867.784587ms","remote":"127.0.0.1:37024","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-06T01:06:54.802973Z","caller":"traceutil/trace.go:171","msg":"trace[779521702] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"848.271163ms","start":"2026-08-06T01:06:53.954696Z","end":"2026-08-06T01:06:54.802967Z","steps":["trace[779521702] 'process raft request' (duration: 847.480458ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:06:54.802928Z","caller":"traceutil/trace.go:171","msg":"trace[279901182] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"883.934483ms","start":"2026-08-06T01:06:53.91895Z","end":"2026-08-06T01:06:54.802884Z","steps":["trace[279901182] 'process raft request' (duration: 883.016913ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:06:54.803002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:06:53.954672Z","time spent":"848.316525ms","remote":"127.0.0.1:37024","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-06T01:06:54.803047Z","caller":"traceutil/trace.go:171","msg":"trace[1995622625] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"834.795233ms","start":"2026-08-06T01:06:53.968246Z","end":"2026-08-06T01:06:54.803041Z","steps":["trace[1995622625] 'process raft request' (duration: 833.988587ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:06:54.80307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:06:53.968237Z","time spent":"834.821424ms","remote":"127.0.0.1:37024","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-06T01:06:54.803116Z","caller":"traceutil/trace.go:171","msg":"trace[1533809622] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"723.739585ms","start":"2026-08-06T01:06:54.07937Z","end":"2026-08-06T01:06:54.803109Z","steps":["trace[1533809622] 'process raft request' (duration: 722.95576ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:06:54.803109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:06:53.91893Z","time spent":"884.094788ms","remote":"127.0.0.1:37024","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-06T01:06:54.803092Z","caller":"traceutil/trace.go:171","msg":"trace[1745328075] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"883.51826ms","start":"2026-08-06T01:06:53.919527Z","end":"2026-08-06T01:06:54.803045Z","steps":["trace[1745328075] 'process raft request' (duration: 882.479317ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:06:54.803195Z","caller":"traceutil/trace.go:171","msg":"trace[710909814] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"883.482929ms","start":"2026-08-06T01:06:53.919695Z","end":"2026-08-06T01:06:54.803177Z","steps":["trace[710909814] 'process raft request' (duration: 882.342203ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:06:54.803241Z","caller":"traceutil/trace.go:171","msg":"trace[1194155266] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"897.855958ms","start":"2026-08-06T01:06:53.905376Z","end":"2026-08-06T01:06:54.803232Z","steps":["trace[1194155266] 'process raft request' (duration: 896.468303ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:06:54.803263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:06:53.919688Z","time spent":"883.540481ms","remote":"127.0.0.1:37024","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-06T01:06:54.803283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:06:53.905369Z","time spent":"897.891819ms","remote":"127.0.0.1:37024","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-06T01:06:54.80328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:06:53.919498Z","time spent":"883.686255ms","remote":"127.0.0.1:37024","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-06T01:06:54.803404Z","caller":"traceutil/trace.go:171","msg":"trace[556842088] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"897.830267ms","start":"2026-08-06T01:06:53.905426Z","end":"2026-08-06T01:06:54.803256Z","steps":["trace[556842088] 'process raft request' (duration: 896.503995ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:06:54.803525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"588.39967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-08-06T01:06:54.803557Z","caller":"traceutil/trace.go:171","msg":"trace[1743723831] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:541; }","duration":"588.474603ms","start":"2026-08-06T01:06:54.215071Z","end":"2026-08-06T01:06:54.803546Z","steps":["trace[1743723831] 'agreement among raft nodes before linearized reading' (duration: 588.327528ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:06:54.803575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:06:54.215051Z","time spent":"588.519664ms","remote":"127.0.0.1:37234","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-06T01:06:54.803693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:06:53.90542Z","time spent":"898.127317ms","remote":"127.0.0.1:37024","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-06T01:06:54.803745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:06:54.079336Z","time spent":"723.792626ms","remote":"127.0.0.1:37024","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-06T01:06:54.80989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"488.169038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T01:06:54.809967Z","caller":"traceutil/trace.go:171","msg":"trace[1168946656] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:542; }","duration":"488.292851ms","start":"2026-08-06T01:06:54.321655Z","end":"2026-08-06T01:06:54.809948Z","steps":["trace[1168946656] 'agreement among raft nodes before linearized reading' (duration: 488.148627ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:06:54.809998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:06:54.321528Z","time spent":"488.464737ms","remote":"127.0.0.1:36998","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-08-06T01:06:56.252594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.531483ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-06T01:06:56.252731Z","caller":"traceutil/trace.go:171","msg":"trace[2044990722] linearizableReadLoop","detail":"{readStateIndex:633; appliedIndex:632; }","duration":"195.052842ms","start":"2026-08-06T01:06:56.057662Z","end":"2026-08-06T01:06:56.252715Z","steps":["trace[2044990722] 'read index received' (duration: 77.960091ms)","trace[2044990722] 'applied index is now lower than readState.Index' (duration: 117.091461ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T01:06:56.252807Z","caller":"traceutil/trace.go:171","msg":"trace[709582517] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"202.384356ms","start":"2026-08-06T01:06:56.050401Z","end":"2026-08-06T01:06:56.252785Z","steps":["trace[709582517] 'process raft request' (duration: 85.386538ms)","trace[709582517] 'compare' (duration: 116.382989ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T01:06:56.252895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.228118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/backendtrafficpolicies.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:98713"} {"level":"info","ts":"2026-08-06T01:06:56.252933Z","caller":"traceutil/trace.go:171","msg":"trace[486353872] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/backendtrafficpolicies.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:609; }","duration":"195.29036ms","start":"2026-08-06T01:06:56.057632Z","end":"2026-08-06T01:06:56.252922Z","steps":["trace[486353872] 'agreement among raft nodes before linearized reading' (duration: 195.135685ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:07:01.343585Z","caller":"traceutil/trace.go:171","msg":"trace[408699408] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"110.559482ms","start":"2026-08-06T01:07:01.232996Z","end":"2026-08-06T01:07:01.343555Z","steps":["trace[408699408] 'process raft request' (duration: 108.684272ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:07:01.446182Z","caller":"traceutil/trace.go:171","msg":"trace[1933067372] linearizableReadLoop","detail":"{readStateIndex:666; appliedIndex:664; }","duration":"123.388452ms","start":"2026-08-06T01:07:01.32273Z","end":"2026-08-06T01:07:01.446118Z","steps":["trace[1933067372] 'read index received' (duration: 15.759334ms)","trace[1933067372] 'applied index is now lower than readState.Index' (duration: 107.627738ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T01:07:01.446309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.557758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T01:07:01.446391Z","caller":"traceutil/trace.go:171","msg":"trace[960869896] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:641; }","duration":"123.680582ms","start":"2026-08-06T01:07:01.322689Z","end":"2026-08-06T01:07:01.44637Z","steps":["trace[960869896] 'agreement among raft nodes before linearized reading' (duration: 123.537028ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:07:01.887233Z","caller":"traceutil/trace.go:171","msg":"trace[1037215815] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"187.717158ms","start":"2026-08-06T01:07:01.699497Z","end":"2026-08-06T01:07:01.887214Z","steps":["trace[1037215815] 'process raft request' (duration: 187.571783ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:07:02.822441Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9602413090993886042,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-06T01:07:02.938363Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.019350499s","expected-duration":"1s"} {"level":"warn","ts":"2026-08-06T01:07:03.051568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.693631ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-06T01:07:03.051684Z","caller":"traceutil/trace.go:171","msg":"trace[1690881635] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"1.134600981s","start":"2026-08-06T01:07:01.917059Z","end":"2026-08-06T01:07:03.05166Z","steps":["trace[1690881635] 'process raft request' (duration: 1.021721674s)","trace[1690881635] 'compare' (duration: 112.319239ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T01:07:03.051749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:07:01.917022Z","time spent":"1.134694415s","remote":"127.0.0.1:37066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036313,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-06T01:07:03.051739Z","caller":"traceutil/trace.go:171","msg":"trace[1613217208] linearizableReadLoop","detail":"{readStateIndex:669; appliedIndex:668; }","duration":"729.682764ms","start":"2026-08-06T01:07:02.32198Z","end":"2026-08-06T01:07:03.051663Z","steps":["trace[1613217208] 'read index received' (duration: 616.534179ms)","trace[1613217208] 'applied index is now lower than readState.Index' (duration: 113.147175ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T01:07:03.051892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"729.900131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T01:07:03.051987Z","caller":"traceutil/trace.go:171","msg":"trace[1106122194] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:644; }","duration":"729.996114ms","start":"2026-08-06T01:07:02.321975Z","end":"2026-08-06T01:07:03.051971Z","steps":["trace[1106122194] 'agreement among raft nodes before linearized reading' (duration: 729.796758ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:07:03.05208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:07:02.32193Z","time spent":"730.101367ms","remote":"127.0.0.1:36998","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-08-06T01:07:03.052182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.219922ms","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-06T01:07:03.052291Z","caller":"traceutil/trace.go:171","msg":"trace[2104768113] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:644; }","duration":"197.369456ms","start":"2026-08-06T01:07:02.85487Z","end":"2026-08-06T01:07:03.052239Z","steps":["trace[2104768113] 'agreement among raft nodes before linearized reading' (duration: 197.062227ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:07:03.645747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.314036ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-06T01:07:03.64609Z","caller":"traceutil/trace.go:171","msg":"trace[775644979] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"588.245235ms","start":"2026-08-06T01:07:03.057824Z","end":"2026-08-06T01:07:03.646069Z","steps":["trace[775644979] 'process raft request' (duration: 588.117751ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:07:03.646094Z","caller":"traceutil/trace.go:171","msg":"trace[955381078] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"588.6916ms","start":"2026-08-06T01:07:03.057387Z","end":"2026-08-06T01:07:03.646079Z","steps":["trace[955381078] 'process raft request' (duration: 588.480943ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:07:03.646155Z","caller":"traceutil/trace.go:171","msg":"trace[412132544] linearizableReadLoop","detail":"{readStateIndex:674; appliedIndex:669; }","duration":"323.692833ms","start":"2026-08-06T01:07:03.322446Z","end":"2026-08-06T01:07:03.646139Z","steps":["trace[412132544] 'read index received' (duration: 292.30571ms)","trace[412132544] 'applied index is now lower than readState.Index' (duration: 31.386543ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T01:07:03.646205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:07:03.05781Z","time spent":"588.345179ms","remote":"127.0.0.1:37234","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-08-06T01:07:03.646249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.797456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T01:07:03.64627Z","caller":"traceutil/trace.go:171","msg":"trace[210349364] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:649; }","duration":"323.864238ms","start":"2026-08-06T01:07:03.322398Z","end":"2026-08-06T01:07:03.646262Z","steps":["trace[210349364] 'agreement among raft nodes before linearized reading' (duration: 323.806726ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:07:03.646301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:07:03.322379Z","time spent":"323.91394ms","remote":"127.0.0.1:36998","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-08-06T01:07:03.645977Z","caller":"traceutil/trace.go:171","msg":"trace[1127602386] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"589.713343ms","start":"2026-08-06T01:07:03.056234Z","end":"2026-08-06T01:07:03.645947Z","steps":["trace[1127602386] 'process raft request' (duration: 171.097137ms)","trace[1127602386] 'compare' (duration: 418.176571ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T01:07:03.646434Z","caller":"traceutil/trace.go:171","msg":"trace[396827074] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"527.515175ms","start":"2026-08-06T01:07:03.118904Z","end":"2026-08-06T01:07:03.646419Z","steps":["trace[396827074] 'process raft request' (duration: 527.184114ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:07:03.646488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:07:03.118885Z","time spent":"527.573057ms","remote":"127.0.0.1:37200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-06T01:07:03.646555Z","caller":"traceutil/trace.go:171","msg":"trace[302361595] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"588.242636ms","start":"2026-08-06T01:07:03.058304Z","end":"2026-08-06T01:07:03.646547Z","steps":["trace[302361595] 'process raft request' (duration: 587.688348ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:07:03.646548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:07:03.056203Z","time spent":"590.234949ms","remote":"127.0.0.1:37234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-06T01:07:03.646618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:07:03.058286Z","time spent":"588.293357ms","remote":"127.0.0.1:37234","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-08-06T01:07:03.646724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:07:03.057366Z","time spent":"588.801864ms","remote":"127.0.0.1:37234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-06T01:07:04.116734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.253928ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-06T01:07:04.116804Z","caller":"traceutil/trace.go:171","msg":"trace[1872633860] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"454.906655ms","start":"2026-08-06T01:07:03.661884Z","end":"2026-08-06T01:07:04.116791Z","steps":["trace[1872633860] 'process raft request' (duration: 183.552135ms)","trace[1872633860] 'compare' (duration: 271.110342ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T01:07:04.116841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:07:03.661871Z","time spent":"454.954777ms","remote":"127.0.0.1:37156","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-08-06T01:07:04.821478Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9602413090993886056,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-06T01:07:05.322613Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9602413090993886056,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-06T01:07:05.427872Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.30666323s","expected-duration":"1s"} {"level":"warn","ts":"2026-08-06T01:07:06.050277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"622.069146ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-06T01:07:06.050569Z","caller":"traceutil/trace.go:171","msg":"trace[786099620] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"1.929420868s","start":"2026-08-06T01:07:04.121123Z","end":"2026-08-06T01:07:06.050544Z","steps":["trace[786099620] 'process raft request' (duration: 1.306994551s)","trace[786099620] 'compare' (duration: 621.910681ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T01:07:06.050712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:07:04.121108Z","time spent":"1.929553442s","remote":"127.0.0.1:37044","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-08-06T01:07:06.161288Z","caller":"traceutil/trace.go:171","msg":"trace[77563125] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"2.039600607s","start":"2026-08-06T01:07:04.121669Z","end":"2026-08-06T01:07:06.161269Z","steps":["trace[77563125] 'process raft request' (duration: 2.039480553s)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:07:06.161288Z","caller":"traceutil/trace.go:171","msg":"trace[1909003738] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"2.039815335s","start":"2026-08-06T01:07:04.121437Z","end":"2026-08-06T01:07:06.161252Z","steps":["trace[1909003738] 'process raft request' (duration: 2.033160212s)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:07:06.161422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:07:04.121427Z","time spent":"2.039933218s","remote":"127.0.0.1:37044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":905,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-06T01:07:06.161467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.840763544s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T01:07:06.1615Z","caller":"traceutil/trace.go:171","msg":"trace[1512336138] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:655; }","duration":"1.840887068s","start":"2026-08-06T01:07:04.320604Z","end":"2026-08-06T01:07:06.161491Z","steps":["trace[1512336138] 'agreement among raft nodes before linearized reading' (duration: 1.840731403s)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:07:06.161294Z","caller":"traceutil/trace.go:171","msg":"trace[1537667725] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"423.241872ms","start":"2026-08-06T01:07:05.738035Z","end":"2026-08-06T01:07:06.161277Z","steps":["trace[1537667725] 'process raft request' (duration: 423.207511ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:07:06.161512Z","caller":"traceutil/trace.go:171","msg":"trace[95974349] linearizableReadLoop","detail":"{readStateIndex:679; appliedIndex:675; }","duration":"1.840723683s","start":"2026-08-06T01:07:04.320609Z","end":"2026-08-06T01:07:06.161332Z","steps":["trace[95974349] 'read index received' (duration: 1.107521697s)","trace[95974349] 'applied index is now lower than readState.Index' (duration: 733.201086ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T01:07:06.161585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:07:04.121659Z","time spent":"2.03969133s","remote":"127.0.0.1:37156","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3209,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-06T01:07:06.161443Z","caller":"traceutil/trace.go:171","msg":"trace[1243934001] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"2.038090429s","start":"2026-08-06T01:07:04.123242Z","end":"2026-08-06T01:07:06.161332Z","steps":["trace[1243934001] 'process raft request' (duration: 2.037962645s)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:07:06.161734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:07:04.123235Z","time spent":"2.03844602s","remote":"127.0.0.1:37200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-06T01:07:06.161535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:07:04.320566Z","time spent":"1.84096313s","remote":"127.0.0.1:37000","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-08-06T01:07:06.161851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"511.279665ms","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-06T01:07:06.161856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"510.726968ms","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-06T01:07:06.16196Z","caller":"traceutil/trace.go:171","msg":"trace[1675067996] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:655; }","duration":"511.42945ms","start":"2026-08-06T01:07:05.650497Z","end":"2026-08-06T01:07:06.161927Z","steps":["trace[1675067996] 'agreement among raft nodes before linearized reading' (duration: 511.211353ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:07:06.161966Z","caller":"traceutil/trace.go:171","msg":"trace[935878479] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:655; }","duration":"510.893933ms","start":"2026-08-06T01:07:05.651052Z","end":"2026-08-06T01:07:06.161946Z","steps":["trace[935878479] 'agreement among raft nodes before linearized reading' (duration: 510.663185ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:07:06.162012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.564224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.65\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-08-06T01:07:06.162034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:07:05.651032Z","time spent":"510.992766ms","remote":"127.0.0.1:37234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-06T01:07:06.161852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"510.440958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-08-06T01:07:06.162048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:07:05.650475Z","time spent":"511.532964ms","remote":"127.0.0.1:37234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-06T01:07:06.162054Z","caller":"traceutil/trace.go:171","msg":"trace[319841286] range","detail":"{range_begin:/registry/masterleases/199.19.213.65; range_end:; response_count:1; response_revision:655; }","duration":"352.681618ms","start":"2026-08-06T01:07:05.809357Z","end":"2026-08-06T01:07:06.162038Z","steps":["trace[319841286] 'agreement among raft nodes before linearized reading' (duration: 352.563734ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:07:06.162129Z","caller":"traceutil/trace.go:171","msg":"trace[1510920870] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:655; }","duration":"510.689127ms","start":"2026-08-06T01:07:05.651383Z","end":"2026-08-06T01:07:06.162072Z","steps":["trace[1510920870] 'agreement among raft nodes before linearized reading' (duration: 510.329705ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:07:06.162253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:07:05.738013Z","time spent":"423.569993ms","remote":"127.0.0.1:37234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-06T01:07:06.162293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:07:05.651377Z","time spent":"510.853632ms","remote":"127.0.0.1:37234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-06T01:07:06.162077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"511.378279ms","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-08-06T01:07:06.163101Z","caller":"traceutil/trace.go:171","msg":"trace[792172303] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:655; }","duration":"512.495224ms","start":"2026-08-06T01:07:05.65059Z","end":"2026-08-06T01:07:06.163085Z","steps":["trace[792172303] 'agreement among raft nodes before linearized reading' (duration: 511.41753ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:07:06.163159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:07:05.650561Z","time spent":"512.579627ms","remote":"127.0.0.1:37234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-06T01:07:06.162139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:07:05.809336Z","time spent":"352.790412ms","remote":"127.0.0.1:37010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.19.213.65\" "} {"level":"warn","ts":"2026-08-06T01:07:22.574062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"855.636369ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-08-06T01:07:22.574314Z","caller":"traceutil/trace.go:171","msg":"trace[1972243007] linearizableReadLoop","detail":"{readStateIndex:847; appliedIndex:846; }","duration":"1.251708973s","start":"2026-08-06T01:07:21.322583Z","end":"2026-08-06T01:07:22.574292Z","steps":["trace[1972243007] 'read index received' (duration: 395.743903ms)","trace[1972243007] 'applied index is now lower than readState.Index' (duration: 855.96373ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T01:07:22.574723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.252121826s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T01:07:22.574782Z","caller":"traceutil/trace.go:171","msg":"trace[1927010235] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:814; }","duration":"1.252192098s","start":"2026-08-06T01:07:21.322575Z","end":"2026-08-06T01:07:22.574768Z","steps":["trace[1927010235] 'agreement among raft nodes before linearized reading' (duration: 1.251834467s)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:07:22.574828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:07:21.322475Z","time spent":"1.252340853s","remote":"127.0.0.1:36998","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-08-06T01:07:22.57535Z","caller":"traceutil/trace.go:171","msg":"trace[1251913489] transaction","detail":"{read_only:false; response_revision:815; number_of_response:1; }","duration":"768.193213ms","start":"2026-08-06T01:07:21.807138Z","end":"2026-08-06T01:07:22.575331Z","steps":["trace[1251913489] 'process raft request' (duration: 767.053966ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:07:22.575484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:07:21.80712Z","time spent":"768.290306ms","remote":"127.0.0.1:37200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-06T01:07:22.577815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.021728ms","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-06T01:07:22.577872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.663008ms","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-06T01:07:22.577884Z","caller":"traceutil/trace.go:171","msg":"trace[1576089834] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:815; }","duration":"304.119192ms","start":"2026-08-06T01:07:22.273749Z","end":"2026-08-06T01:07:22.577869Z","steps":["trace[1576089834] 'agreement among raft nodes before linearized reading' (duration: 301.668282ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:07:22.577923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:07:22.273739Z","time spent":"304.175003ms","remote":"127.0.0.1:37234","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-06T01:07:22.578013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.877902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-08-06T01:07:22.578085Z","caller":"traceutil/trace.go:171","msg":"trace[641966590] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:815; }","duration":"311.958215ms","start":"2026-08-06T01:07:22.266101Z","end":"2026-08-06T01:07:22.578059Z","steps":["trace[641966590] 'agreement among raft nodes before linearized reading' (duration: 309.34733ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:07:22.578087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.113803135s","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":"warn","ts":"2026-08-06T01:07:22.578138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:07:22.266088Z","time spent":"312.035727ms","remote":"127.0.0.1:37234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-08-06T01:07:22.578155Z","caller":"traceutil/trace.go:171","msg":"trace[2055542584] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:815; }","duration":"1.113885228s","start":"2026-08-06T01:07:21.464241Z","end":"2026-08-06T01:07:22.578127Z","steps":["trace[2055542584] 'agreement among raft nodes before linearized reading' (duration: 1.111217362s)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:07:22.57792Z","caller":"traceutil/trace.go:171","msg":"trace[1236163113] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:815; }","duration":"317.773852ms","start":"2026-08-06T01:07:22.260132Z","end":"2026-08-06T01:07:22.577906Z","steps":["trace[1236163113] 'agreement among raft nodes before linearized reading' (duration: 315.318242ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:07:22.578188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:07:21.464221Z","time spent":"1.11395835s","remote":"127.0.0.1:37234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-06T01:07:22.578155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.573748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-08-06T01:07:22.578191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:07:22.260111Z","time spent":"318.071642ms","remote":"127.0.0.1:37234","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-08-06T01:07:22.578278Z","caller":"traceutil/trace.go:171","msg":"trace[131585455] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:815; }","duration":"314.728814ms","start":"2026-08-06T01:07:22.263534Z","end":"2026-08-06T01:07:22.578263Z","steps":["trace[131585455] 'agreement among raft nodes before linearized reading' (duration: 311.850531ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:07:22.578326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:07:22.263515Z","time spent":"314.797576ms","remote":"127.0.0.1:37234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-06T01:07:22.578474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.168610257s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-06T01:07:22.578546Z","caller":"traceutil/trace.go:171","msg":"trace[888957987] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:815; }","duration":"1.168697319s","start":"2026-08-06T01:07:21.409811Z","end":"2026-08-06T01:07:22.578508Z","steps":["trace[888957987] 'agreement among raft nodes before linearized reading' (duration: 1.165497126s)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:07:22.578586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:07:21.409787Z","time spent":"1.168787952s","remote":"127.0.0.1:37492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":29,"response size":31,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true "} {"level":"info","ts":"2026-08-06T01:07:57.083095Z","caller":"traceutil/trace.go:171","msg":"trace[2033981273] transaction","detail":"{read_only:false; response_revision:1026; number_of_response:1; }","duration":"306.492428ms","start":"2026-08-06T01:07:56.776578Z","end":"2026-08-06T01:07:57.08307Z","steps":["trace[2033981273] 'process raft request' (duration: 306.300481ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:07:57.083258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:07:56.776561Z","time spent":"306.618681ms","remote":"127.0.0.1:37234","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-08-06T01:07:57.083439Z","caller":"traceutil/trace.go:171","msg":"trace[269484002] linearizableReadLoop","detail":"{readStateIndex:1066; appliedIndex:1066; }","duration":"306.662273ms","start":"2026-08-06T01:07:56.776757Z","end":"2026-08-06T01:07:57.083419Z","steps":["trace[269484002] 'read index received' (duration: 306.652193ms)","trace[269484002] 'applied index is now lower than readState.Index' (duration: 8.5µs)"],"step_count":2} {"level":"warn","ts":"2026-08-06T01:07:57.083768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.982663ms","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-06T01:07:57.083865Z","caller":"traceutil/trace.go:171","msg":"trace[1898349134] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1026; }","duration":"307.126428ms","start":"2026-08-06T01:07:56.776717Z","end":"2026-08-06T01:07:57.083843Z","steps":["trace[1898349134] 'agreement among raft nodes before linearized reading' (duration: 306.825888ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:07:57.083916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:07:56.776701Z","time spent":"307.205911ms","remote":"127.0.0.1:37234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-06T01:07:57.103207Z","caller":"traceutil/trace.go:171","msg":"trace[1202696414] transaction","detail":"{read_only:false; response_revision:1027; number_of_response:1; }","duration":"324.117607ms","start":"2026-08-06T01:07:56.779066Z","end":"2026-08-06T01:07:57.103183Z","steps":["trace[1202696414] 'process raft request' (duration: 323.828348ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:07:57.103372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:07:56.779053Z","time spent":"324.236831ms","remote":"127.0.0.1:37234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-06T01:07:57.103386Z","caller":"traceutil/trace.go:171","msg":"trace[611566662] transaction","detail":"{read_only:false; response_revision:1028; number_of_response:1; }","duration":"323.631482ms","start":"2026-08-06T01:07:56.779734Z","end":"2026-08-06T01:07:57.103366Z","steps":["trace[611566662] 'process raft request' (duration: 323.390624ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:07:57.103511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:07:56.779725Z","time spent":"323.716214ms","remote":"127.0.0.1:37234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-06T01:07:57.103703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.520045ms","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-06T01:07:57.10378Z","caller":"traceutil/trace.go:171","msg":"trace[1464260217] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1029; }","duration":"326.600408ms","start":"2026-08-06T01:07:56.77716Z","end":"2026-08-06T01:07:57.10376Z","steps":["trace[1464260217] 'agreement among raft nodes before linearized reading' (duration: 326.409351ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:07:57.103817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:07:56.777139Z","time spent":"326.67035ms","remote":"127.0.0.1:37234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-08-06T01:07:57.221344Z","caller":"traceutil/trace.go:171","msg":"trace[942478979] linearizableReadLoop","detail":"{readStateIndex:1071; appliedIndex:1069; }","duration":"117.832219ms","start":"2026-08-06T01:07:57.103489Z","end":"2026-08-06T01:07:57.221321Z","steps":["trace[942478979] 'read index received' (duration: 117.701004ms)","trace[942478979] 'applied index is now lower than readState.Index' (duration: 130.235µs)"],"step_count":2} {"level":"warn","ts":"2026-08-06T01:07:57.221475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.12701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T01:07:57.221513Z","caller":"traceutil/trace.go:171","msg":"trace[690490778] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:1031; }","duration":"123.229944ms","start":"2026-08-06T01:07:57.098274Z","end":"2026-08-06T01:07:57.221503Z","steps":["trace[690490778] 'agreement among raft nodes before linearized reading' (duration: 123.135561ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:07:57.221518Z","caller":"traceutil/trace.go:171","msg":"trace[620394668] transaction","detail":"{read_only:false; response_revision:1031; number_of_response:1; }","duration":"121.862259ms","start":"2026-08-06T01:07:57.0996Z","end":"2026-08-06T01:07:57.221462Z","steps":["trace[620394668] 'process raft request' (duration: 121.679243ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:07:57.221669Z","caller":"traceutil/trace.go:171","msg":"trace[1415823664] transaction","detail":"{read_only:false; response_revision:1030; number_of_response:1; }","duration":"134.149167ms","start":"2026-08-06T01:07:57.087484Z","end":"2026-08-06T01:07:57.221633Z","steps":["trace[1415823664] 'process raft request' (duration: 133.64535ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:07:57.250447Z","caller":"traceutil/trace.go:171","msg":"trace[921534334] transaction","detail":"{read_only:false; response_revision:1032; number_of_response:1; }","duration":"143.2417ms","start":"2026-08-06T01:07:57.107172Z","end":"2026-08-06T01:07:57.250414Z","steps":["trace[921534334] 'process raft request' (duration: 143.093106ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:07:59.213441Z","caller":"traceutil/trace.go:171","msg":"trace[1359384089] transaction","detail":"{read_only:false; response_revision:1033; number_of_response:1; }","duration":"122.833141ms","start":"2026-08-06T01:07:59.090589Z","end":"2026-08-06T01:07:59.213422Z","steps":["trace[1359384089] 'process raft request' (duration: 122.584643ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:07:59.213358Z","caller":"traceutil/trace.go:171","msg":"trace[987249961] linearizableReadLoop","detail":"{readStateIndex:1073; appliedIndex:1072; }","duration":"105.400958ms","start":"2026-08-06T01:07:59.107937Z","end":"2026-08-06T01:07:59.213338Z","steps":["trace[987249961] 'read index received' (duration: 105.266973ms)","trace[987249961] 'applied index is now lower than readState.Index' (duration: 133.284µs)"],"step_count":2} {"level":"warn","ts":"2026-08-06T01:07:59.213854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.890823ms","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-06T01:07:59.214002Z","caller":"traceutil/trace.go:171","msg":"trace[1560881591] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1033; }","duration":"106.059139ms","start":"2026-08-06T01:07:59.107903Z","end":"2026-08-06T01:07:59.213962Z","steps":["trace[1560881591] 'agreement among raft nodes before linearized reading' (duration: 105.685587ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:07:59.704971Z","caller":"traceutil/trace.go:171","msg":"trace[919513586] transaction","detail":"{read_only:false; response_revision:1034; number_of_response:1; }","duration":"596.546034ms","start":"2026-08-06T01:07:59.1084Z","end":"2026-08-06T01:07:59.704946Z","steps":["trace[919513586] 'process raft request' (duration: 593.869208ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:07:59.70506Z","caller":"traceutil/trace.go:171","msg":"trace[828317581] linearizableReadLoop","detail":"{readStateIndex:1074; appliedIndex:1073; }","duration":"491.280741ms","start":"2026-08-06T01:07:59.213763Z","end":"2026-08-06T01:07:59.705044Z","steps":["trace[828317581] 'read index received' (duration: 488.520152ms)","trace[828317581] 'applied index is now lower than readState.Index' (duration: 2.759669ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T01:07:59.705096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:07:59.108378Z","time spent":"596.652448ms","remote":"127.0.0.1:37234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-06T01:07:59.705077Z","caller":"traceutil/trace.go:171","msg":"trace[1855342925] transaction","detail":"{read_only:false; response_revision:1035; number_of_response:1; }","duration":"487.299322ms","start":"2026-08-06T01:07:59.217743Z","end":"2026-08-06T01:07:59.705043Z","steps":["trace[1855342925] 'process raft request' (duration: 487.187619ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:07:59.705256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"597.213246ms","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-08-06T01:07:59.705299Z","caller":"traceutil/trace.go:171","msg":"trace[1350058028] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1035; }","duration":"597.304158ms","start":"2026-08-06T01:07:59.107983Z","end":"2026-08-06T01:07:59.705287Z","steps":["trace[1350058028] 'agreement among raft nodes before linearized reading' (duration: 597.167024ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:07:59.705295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:07:59.217724Z","time spent":"487.478419ms","remote":"127.0.0.1:37234","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-08-06T01:07:59.705332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:07:59.107956Z","time spent":"597.36901ms","remote":"127.0.0.1:37234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-06T01:07:59.705374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"479.311994ms","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-06T01:07:59.705406Z","caller":"traceutil/trace.go:171","msg":"trace[1873308249] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1035; }","duration":"479.343155ms","start":"2026-08-06T01:07:59.226055Z","end":"2026-08-06T01:07:59.705398Z","steps":["trace[1873308249] 'agreement among raft nodes before linearized reading' (duration: 479.270903ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:07:59.705436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:07:59.226008Z","time spent":"479.422528ms","remote":"127.0.0.1:37234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-06T01:07:59.705532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.65298ms","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-06T01:07:59.705561Z","caller":"traceutil/trace.go:171","msg":"trace[1604098190] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1035; }","duration":"451.702382ms","start":"2026-08-06T01:07:59.253852Z","end":"2026-08-06T01:07:59.705554Z","steps":["trace[1604098190] 'agreement among raft nodes before linearized reading' (duration: 451.63145ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:07:59.705582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:07:59.253838Z","time spent":"451.738913ms","remote":"127.0.0.1:37234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-06T01:08:21.892178Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9602413090993887092,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-06T01:08:22.250104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.061408ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-08-06T01:08:22.250881Z","caller":"traceutil/trace.go:171","msg":"trace[361051574] linearizableReadLoop","detail":"{readStateIndex:1151; appliedIndex:1149; }","duration":"859.798967ms","start":"2026-08-06T01:08:21.391063Z","end":"2026-08-06T01:08:22.250862Z","steps":["trace[361051574] 'read index received' (duration: 629.86036ms)","trace[361051574] 'applied index is now lower than readState.Index' (duration: 229.937147ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T01:08:22.250986Z","caller":"traceutil/trace.go:171","msg":"trace[1883751298] transaction","detail":"{read_only:false; response_revision:1106; number_of_response:1; }","duration":"457.187186ms","start":"2026-08-06T01:08:21.793787Z","end":"2026-08-06T01:08:22.250974Z","steps":["trace[1883751298] 'process raft request' (duration: 456.450223ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:08:22.251282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.005823ms","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-06T01:08:22.25136Z","caller":"traceutil/trace.go:171","msg":"trace[508978921] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1106; }","duration":"440.121837ms","start":"2026-08-06T01:08:21.811218Z","end":"2026-08-06T01:08:22.25134Z","steps":["trace[508978921] 'agreement among raft nodes before linearized reading' (duration: 439.923661ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:08:22.251183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"860.107656ms","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":"warn","ts":"2026-08-06T01:08:22.2514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:08:21.811199Z","time spent":"440.191059ms","remote":"127.0.0.1:37234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-08-06T01:08:22.251429Z","caller":"traceutil/trace.go:171","msg":"trace[1360174200] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1106; }","duration":"860.395125ms","start":"2026-08-06T01:08:21.391017Z","end":"2026-08-06T01:08:22.251412Z","steps":["trace[1360174200] 'agreement among raft nodes before linearized reading' (duration: 859.971912ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:08:22.251469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:08:21.390997Z","time spent":"860.460638ms","remote":"127.0.0.1:37234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-06T01:08:22.251215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.337439ms","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-06T01:08:22.251528Z","caller":"traceutil/trace.go:171","msg":"trace[1611595573] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1106; }","duration":"438.705221ms","start":"2026-08-06T01:08:21.812815Z","end":"2026-08-06T01:08:22.25152Z","steps":["trace[1611595573] 'agreement among raft nodes before linearized reading' (duration: 438.309838ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:08:22.251215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.630156ms","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-06T01:08:22.251567Z","caller":"traceutil/trace.go:171","msg":"trace[1396403064] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1106; }","duration":"447.000388ms","start":"2026-08-06T01:08:21.804559Z","end":"2026-08-06T01:08:22.251559Z","steps":["trace[1396403064] 'agreement among raft nodes before linearized reading' (duration: 446.540863ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:08:22.251577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:08:21.812797Z","time spent":"438.771383ms","remote":"127.0.0.1:37234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-06T01:08:22.251332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:08:21.793741Z","time spent":"457.512996ms","remote":"127.0.0.1:37234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-06T01:08:22.251603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:08:21.804494Z","time spent":"447.102671ms","remote":"127.0.0.1:37234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-06T01:08:22.251789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.229179ms","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-08-06T01:08:22.251932Z","caller":"traceutil/trace.go:171","msg":"trace[2065884047] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1106; }","duration":"435.399085ms","start":"2026-08-06T01:08:21.816522Z","end":"2026-08-06T01:08:22.251921Z","steps":["trace[2065884047] 'agreement among raft nodes before linearized reading' (duration: 435.209069ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:08:22.252068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:08:21.816507Z","time spent":"435.55213ms","remote":"127.0.0.1:37234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-08-06T01:08:22.575519Z","caller":"traceutil/trace.go:171","msg":"trace[365874719] linearizableReadLoop","detail":"{readStateIndex:1156; appliedIndex:1152; }","duration":"252.600235ms","start":"2026-08-06T01:08:22.322905Z","end":"2026-08-06T01:08:22.575505Z","steps":["trace[365874719] 'read index received' (duration: 252.344747ms)","trace[365874719] 'applied index is now lower than readState.Index' (duration: 254.818µs)"],"step_count":2} {"level":"warn","ts":"2026-08-06T01:08:22.57563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.713418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T01:08:22.57565Z","caller":"traceutil/trace.go:171","msg":"trace[1647382511] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1111; }","duration":"252.78511ms","start":"2026-08-06T01:08:22.322859Z","end":"2026-08-06T01:08:22.575644Z","steps":["trace[1647382511] 'agreement among raft nodes before linearized reading' (duration: 252.707698ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:08:22.575891Z","caller":"traceutil/trace.go:171","msg":"trace[1918527601] transaction","detail":"{read_only:false; response_revision:1108; number_of_response:1; }","duration":"319.742385ms","start":"2026-08-06T01:08:22.256142Z","end":"2026-08-06T01:08:22.575884Z","steps":["trace[1918527601] 'process raft request' (duration: 319.162376ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:08:22.575953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:08:22.256117Z","time spent":"319.788817ms","remote":"127.0.0.1:37234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-06T01:08:22.576066Z","caller":"traceutil/trace.go:171","msg":"trace[1060766877] transaction","detail":"{read_only:false; response_revision:1109; number_of_response:1; }","duration":"319.342322ms","start":"2026-08-06T01:08:22.256718Z","end":"2026-08-06T01:08:22.57606Z","steps":["trace[1060766877] 'process raft request' (duration: 318.709321ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:08:22.576093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:08:22.256707Z","time spent":"319.369243ms","remote":"127.0.0.1:37234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-06T01:08:22.576167Z","caller":"traceutil/trace.go:171","msg":"trace[137979280] transaction","detail":"{read_only:false; response_revision:1110; number_of_response:1; }","duration":"319.073113ms","start":"2026-08-06T01:08:22.257089Z","end":"2026-08-06T01:08:22.576162Z","steps":["trace[137979280] 'process raft request' (duration: 318.36602ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:08:22.576192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:08:22.257083Z","time spent":"319.094744ms","remote":"127.0.0.1:37234","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-08-06T01:08:22.576278Z","caller":"traceutil/trace.go:171","msg":"trace[1504062719] transaction","detail":"{read_only:false; response_revision:1111; number_of_response:1; }","duration":"318.63356ms","start":"2026-08-06T01:08:22.257637Z","end":"2026-08-06T01:08:22.576271Z","steps":["trace[1504062719] 'process raft request' (duration: 317.842354ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:08:22.576308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:08:22.257627Z","time spent":"318.66275ms","remote":"127.0.0.1:37234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-06T01:08:24.927545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.32452ms","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-08-06T01:08:24.92766Z","caller":"traceutil/trace.go:171","msg":"trace[479928546] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1113; }","duration":"346.492835ms","start":"2026-08-06T01:08:24.58114Z","end":"2026-08-06T01:08:24.927632Z","steps":["trace[479928546] 'range keys from in-memory index tree' (duration: 346.100502ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:08:24.927694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.516349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-08-06T01:08:24.92772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:08:24.58113Z","time spent":"346.577348ms","remote":"127.0.0.1:37234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-08-06T01:08:24.927777Z","caller":"traceutil/trace.go:171","msg":"trace[501229818] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1113; }","duration":"347.655743ms","start":"2026-08-06T01:08:24.5801Z","end":"2026-08-06T01:08:24.927756Z","steps":["trace[501229818] 'range keys from in-memory index tree' (duration: 347.234449ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:08:24.927772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.183698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-08-06T01:08:24.927821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:08:24.58008Z","time spent":"347.733125ms","remote":"127.0.0.1:37234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-08-06T01:08:24.927838Z","caller":"traceutil/trace.go:171","msg":"trace[1982873814] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1113; }","duration":"347.27186ms","start":"2026-08-06T01:08:24.580549Z","end":"2026-08-06T01:08:24.92782Z","steps":["trace[1982873814] 'range keys from in-memory index tree' (duration: 347.045643ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:08:24.927919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:08:24.58054Z","time spent":"347.347213ms","remote":"127.0.0.1:37234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-06T01:08:24.928054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.340053ms","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-06T01:08:24.928133Z","caller":"traceutil/trace.go:171","msg":"trace[1262847618] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1113; }","duration":"347.473717ms","start":"2026-08-06T01:08:24.580643Z","end":"2026-08-06T01:08:24.928117Z","steps":["trace[1262847618] 'range keys from in-memory index tree' (duration: 346.986372ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:08:24.92819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:08:24.58062Z","time spent":"347.56053ms","remote":"127.0.0.1:37234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-06T01:08:25.502196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.57493ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-06T01:08:25.502548Z","caller":"traceutil/trace.go:171","msg":"trace[1704410835] transaction","detail":"{read_only:false; response_revision:1115; number_of_response:1; }","duration":"569.865471ms","start":"2026-08-06T01:08:24.932579Z","end":"2026-08-06T01:08:25.502445Z","steps":["trace[1704410835] 'process raft request' (duration: 101.915138ms)","trace[1704410835] 'compare' (duration: 467.355613ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T01:08:25.50258Z","caller":"traceutil/trace.go:171","msg":"trace[1368078888] linearizableReadLoop","detail":"{readStateIndex:1162; appliedIndex:1159; }","duration":"180.347051ms","start":"2026-08-06T01:08:25.322171Z","end":"2026-08-06T01:08:25.502518Z","steps":["trace[1368078888] 'read index received' (duration: 57.242µs)","trace[1368078888] 'applied index is now lower than readState.Index' (duration: 180.285109ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T01:08:25.502695Z","caller":"traceutil/trace.go:171","msg":"trace[626117397] transaction","detail":"{read_only:false; response_revision:1116; number_of_response:1; }","duration":"570.036216ms","start":"2026-08-06T01:08:24.932626Z","end":"2026-08-06T01:08:25.502662Z","steps":["trace[626117397] 'process raft request' (duration: 569.696635ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:08:25.502771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.59113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T01:08:25.50283Z","caller":"traceutil/trace.go:171","msg":"trace[2067319071] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1117; }","duration":"180.700112ms","start":"2026-08-06T01:08:25.322111Z","end":"2026-08-06T01:08:25.502811Z","steps":["trace[2067319071] 'agreement among raft nodes before linearized reading' (duration: 180.572088ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:08:25.502789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:08:24.932552Z","time spent":"570.071107ms","remote":"127.0.0.1:37234","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-08-06T01:08:25.502866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:08:24.932605Z","time spent":"570.17775ms","remote":"127.0.0.1:37234","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-08-06T01:08:25.502695Z","caller":"traceutil/trace.go:171","msg":"trace[1044142533] transaction","detail":"{read_only:false; response_revision:1117; number_of_response:1; }","duration":"569.505379ms","start":"2026-08-06T01:08:24.933168Z","end":"2026-08-06T01:08:25.502673Z","steps":["trace[1044142533] 'process raft request' (duration: 569.22869ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:08:25.503113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:08:24.933157Z","time spent":"569.905941ms","remote":"127.0.0.1:37234","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-08-06T01:08:40.52403Z","caller":"traceutil/trace.go:171","msg":"trace[796868700] linearizableReadLoop","detail":"{readStateIndex:1266; appliedIndex:1265; }","duration":"113.678446ms","start":"2026-08-06T01:08:40.410324Z","end":"2026-08-06T01:08:40.524002Z","steps":["trace[796868700] 'read index received' (duration: 113.513951ms)","trace[796868700] 'applied index is now lower than readState.Index' (duration: 163.515µs)"],"step_count":2} {"level":"info","ts":"2026-08-06T01:08:40.524151Z","caller":"traceutil/trace.go:171","msg":"trace[16959768] transaction","detail":"{read_only:false; response_revision:1216; number_of_response:1; }","duration":"182.963175ms","start":"2026-08-06T01:08:40.341141Z","end":"2026-08-06T01:08:40.524104Z","steps":["trace[16959768] 'process raft request' (duration: 182.714327ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:08:40.524549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.025428ms","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-08-06T01:08:40.524598Z","caller":"traceutil/trace.go:171","msg":"trace[143636009] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1216; }","duration":"114.311297ms","start":"2026-08-06T01:08:40.410274Z","end":"2026-08-06T01:08:40.524586Z","steps":["trace[143636009] 'agreement among raft nodes before linearized reading' (duration: 113.865123ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:08:42.075336Z","caller":"traceutil/trace.go:171","msg":"trace[171617310] transaction","detail":"{read_only:false; response_revision:1220; number_of_response:1; }","duration":"497.810803ms","start":"2026-08-06T01:08:41.577506Z","end":"2026-08-06T01:08:42.075317Z","steps":["trace[171617310] 'process raft request' (duration: 497.687539ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:08:42.075464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:08:41.577494Z","time spent":"497.906265ms","remote":"127.0.0.1:37234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-06T01:08:42.48068Z","caller":"traceutil/trace.go:171","msg":"trace[1545891580] transaction","detail":"{read_only:false; response_revision:1221; number_of_response:1; }","duration":"902.383145ms","start":"2026-08-06T01:08:41.578269Z","end":"2026-08-06T01:08:42.480652Z","steps":["trace[1545891580] 'process raft request' (duration: 902.237101ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:08:42.480818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:08:41.57826Z","time spent":"902.490018ms","remote":"127.0.0.1:37234","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-08-06T01:08:42.480808Z","caller":"traceutil/trace.go:171","msg":"trace[1499423162] linearizableReadLoop","detail":"{readStateIndex:1273; appliedIndex:1271; }","duration":"158.210909ms","start":"2026-08-06T01:08:42.322578Z","end":"2026-08-06T01:08:42.480789Z","steps":["trace[1499423162] 'read index received' (duration: 157.866828ms)","trace[1499423162] 'applied index is now lower than readState.Index' (duration: 342.461µs)"],"step_count":2} {"level":"warn","ts":"2026-08-06T01:08:42.480903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.321833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T01:08:42.480928Z","caller":"traceutil/trace.go:171","msg":"trace[522300567] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1222; }","duration":"158.416105ms","start":"2026-08-06T01:08:42.322503Z","end":"2026-08-06T01:08:42.480919Z","steps":["trace[522300567] 'agreement among raft nodes before linearized reading' (duration: 158.349873ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:08:42.481069Z","caller":"traceutil/trace.go:171","msg":"trace[1187064948] transaction","detail":"{read_only:false; response_revision:1222; number_of_response:1; }","duration":"900.785944ms","start":"2026-08-06T01:08:41.580274Z","end":"2026-08-06T01:08:42.48106Z","steps":["trace[1187064948] 'process raft request' (duration: 900.35011ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:08:42.481111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:08:41.580249Z","time spent":"900.838626ms","remote":"127.0.0.1:37234","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-08-06T01:08:58.032446Z","caller":"traceutil/trace.go:171","msg":"trace[579099103] transaction","detail":"{read_only:false; response_revision:1272; number_of_response:1; }","duration":"635.75283ms","start":"2026-08-06T01:08:57.396668Z","end":"2026-08-06T01:08:58.032421Z","steps":["trace[579099103] 'process raft request' (duration: 635.635486ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:08:58.03261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:08:57.396649Z","time spent":"635.871743ms","remote":"127.0.0.1:37044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":872,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-06T01:08:58.351745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.441885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-08-06T01:08:58.351799Z","caller":"traceutil/trace.go:171","msg":"trace[632777000] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1272; }","duration":"208.551979ms","start":"2026-08-06T01:08:58.143235Z","end":"2026-08-06T01:08:58.351787Z","steps":["trace[632777000] 'range keys from in-memory index tree' (duration: 208.251789ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:08:59.145214Z","caller":"traceutil/trace.go:171","msg":"trace[117031485] transaction","detail":"{read_only:false; response_revision:1280; number_of_response:1; }","duration":"132.532323ms","start":"2026-08-06T01:08:59.012649Z","end":"2026-08-06T01:08:59.145182Z","steps":["trace[117031485] 'process raft request' (duration: 132.405839ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:08:59.789818Z","caller":"traceutil/trace.go:171","msg":"trace[34895175] linearizableReadLoop","detail":"{readStateIndex:1336; appliedIndex:1335; }","duration":"470.486073ms","start":"2026-08-06T01:08:59.319309Z","end":"2026-08-06T01:08:59.789795Z","steps":["trace[34895175] 'read index received' (duration: 455.571293ms)","trace[34895175] 'applied index is now lower than readState.Index' (duration: 14.91332ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T01:08:59.789974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"470.659289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T01:08:59.789998Z","caller":"traceutil/trace.go:171","msg":"trace[613024755] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1282; }","duration":"470.731641ms","start":"2026-08-06T01:08:59.319258Z","end":"2026-08-06T01:08:59.78999Z","steps":["trace[613024755] 'agreement among raft nodes before linearized reading' (duration: 470.657359ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:08:59.790023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:08:59.319238Z","time spent":"470.781003ms","remote":"127.0.0.1:36998","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-08-06T01:08:59.79001Z","caller":"traceutil/trace.go:171","msg":"trace[1134802145] transaction","detail":"{read_only:false; response_revision:1282; number_of_response:1; }","duration":"639.037435ms","start":"2026-08-06T01:08:59.150782Z","end":"2026-08-06T01:08:59.78982Z","steps":["trace[1134802145] 'process raft request' (duration: 624.144376ms)","trace[1134802145] 'compare' (duration: 14.745754ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T01:08:59.790172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.285826ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T01:08:59.790284Z","caller":"traceutil/trace.go:171","msg":"trace[401680211] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:1282; }","duration":"345.457612ms","start":"2026-08-06T01:08:59.444799Z","end":"2026-08-06T01:08:59.790257Z","steps":["trace[401680211] 'agreement among raft nodes before linearized reading' (duration: 345.264316ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:08:59.790341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:08:59.443992Z","time spent":"346.33758ms","remote":"127.0.0.1:44476","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true "} {"level":"warn","ts":"2026-08-06T01:08:59.791012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:08:59.15077Z","time spent":"639.430177ms","remote":"127.0.0.1:37044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":843,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-06T01:09:00.791453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.864025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T01:09:00.79152Z","caller":"traceutil/trace.go:171","msg":"trace[1884771044] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1284; }","duration":"175.97434ms","start":"2026-08-06T01:09:00.615531Z","end":"2026-08-06T01:09:00.791505Z","steps":["trace[1884771044] 'count revisions from in-memory index tree' (duration: 175.772433ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:09:00.791594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.929326ms","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-08-06T01:09:00.791666Z","caller":"traceutil/trace.go:171","msg":"trace[1818891236] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1284; }","duration":"175.03977ms","start":"2026-08-06T01:09:00.616609Z","end":"2026-08-06T01:09:00.791649Z","steps":["trace[1818891236] 'range keys from in-memory index tree' (duration: 174.792921ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:09:00.791977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.625603ms","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-06T01:09:00.792004Z","caller":"traceutil/trace.go:171","msg":"trace[799911089] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1284; }","duration":"214.726426ms","start":"2026-08-06T01:09:00.57727Z","end":"2026-08-06T01:09:00.791996Z","steps":["trace[799911089] 'range keys from in-memory index tree' (duration: 214.477708ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:09:00.792118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.81586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-06T01:09:00.792137Z","caller":"traceutil/trace.go:171","msg":"trace[905476600] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:1284; }","duration":"187.862592ms","start":"2026-08-06T01:09:00.604268Z","end":"2026-08-06T01:09:00.792131Z","steps":["trace[905476600] 'count revisions from in-memory index tree' (duration: 187.723647ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:09:00.792363Z","caller":"traceutil/trace.go:171","msg":"trace[699898465] linearizableReadLoop","detail":"{readStateIndex:1339; appliedIndex:1338; }","duration":"142.767261ms","start":"2026-08-06T01:09:00.649583Z","end":"2026-08-06T01:09:00.79235Z","steps":["trace[699898465] 'read index received' (duration: 141.453739ms)","trace[699898465] 'applied index is now lower than readState.Index' (duration: 1.312542ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T01:09:00.792497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.909776ms","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-08-06T01:09:00.792526Z","caller":"traceutil/trace.go:171","msg":"trace[1766190466] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1285; }","duration":"142.976668ms","start":"2026-08-06T01:09:00.64954Z","end":"2026-08-06T01:09:00.792517Z","steps":["trace[1766190466] 'agreement among raft nodes before linearized reading' (duration: 142.854674ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:09:00.792634Z","caller":"traceutil/trace.go:171","msg":"trace[100252670] transaction","detail":"{read_only:false; response_revision:1285; number_of_response:1; }","duration":"173.488229ms","start":"2026-08-06T01:09:00.619136Z","end":"2026-08-06T01:09:00.792624Z","steps":["trace[100252670] 'process raft request' (duration: 171.96156ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:09:01.129474Z","caller":"traceutil/trace.go:171","msg":"trace[1868700317] transaction","detail":"{read_only:false; response_revision:1288; number_of_response:1; }","duration":"331.40243ms","start":"2026-08-06T01:09:00.798043Z","end":"2026-08-06T01:09:01.129445Z","steps":["trace[1868700317] 'process raft request' (duration: 331.313487ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:09:01.12966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:09:00.798036Z","time spent":"331.522784ms","remote":"127.0.0.1:37234","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-08-06T01:09:01.129824Z","caller":"traceutil/trace.go:171","msg":"trace[1315483237] transaction","detail":"{read_only:false; response_revision:1287; number_of_response:1; }","duration":"331.994059ms","start":"2026-08-06T01:09:00.797814Z","end":"2026-08-06T01:09:01.129808Z","steps":["trace[1315483237] 'process raft request' (duration: 330.986686ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:09:01.129938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:09:00.797803Z","time spent":"332.066611ms","remote":"127.0.0.1:37234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-06T01:09:13.031205Z","caller":"traceutil/trace.go:171","msg":"trace[1263937912] linearizableReadLoop","detail":"{readStateIndex:1439; appliedIndex:1438; }","duration":"116.323302ms","start":"2026-08-06T01:09:12.914853Z","end":"2026-08-06T01:09:13.031176Z","steps":["trace[1263937912] 'read index received' (duration: 116.067103ms)","trace[1263937912] 'applied index is now lower than readState.Index' (duration: 254.739µs)"],"step_count":2} {"level":"info","ts":"2026-08-06T01:09:13.031402Z","caller":"traceutil/trace.go:171","msg":"trace[1499605939] transaction","detail":"{read_only:false; response_revision:1382; number_of_response:1; }","duration":"187.532662ms","start":"2026-08-06T01:09:12.843742Z","end":"2026-08-06T01:09:13.031275Z","steps":["trace[1499605939] 'process raft request' (duration: 187.217102ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:09:13.031644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.778346ms","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-06T01:09:13.031714Z","caller":"traceutil/trace.go:171","msg":"trace[64670387] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1382; }","duration":"116.89589ms","start":"2026-08-06T01:09:12.914806Z","end":"2026-08-06T01:09:13.031701Z","steps":["trace[64670387] 'agreement among raft nodes before linearized reading' (duration: 116.556429ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:09:13.73692Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9602413090993887704,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-06T01:09:14.032462Z","caller":"traceutil/trace.go:171","msg":"trace[1494544231] transaction","detail":"{read_only:false; response_revision:1384; number_of_response:1; }","duration":"850.118105ms","start":"2026-08-06T01:09:13.182317Z","end":"2026-08-06T01:09:14.032435Z","steps":["trace[1494544231] 'process raft request' (duration: 847.77129ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:09:14.032617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:09:13.182275Z","time spent":"850.256719ms","remote":"127.0.0.1:37234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-06T01:09:14.05675Z","caller":"traceutil/trace.go:171","msg":"trace[1175159253] linearizableReadLoop","detail":"{readStateIndex:1442; appliedIndex:1440; }","duration":"820.168442ms","start":"2026-08-06T01:09:13.236558Z","end":"2026-08-06T01:09:14.056727Z","steps":["trace[1175159253] 'read index received' (duration: 793.758212ms)","trace[1175159253] 'applied index is now lower than readState.Index' (duration: 26.409049ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T01:09:14.056876Z","caller":"traceutil/trace.go:171","msg":"trace[1782692932] transaction","detail":"{read_only:false; response_revision:1385; number_of_response:1; }","duration":"873.817047ms","start":"2026-08-06T01:09:13.183007Z","end":"2026-08-06T01:09:14.056824Z","steps":["trace[1782692932] 'process raft request' (duration: 873.61861ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:09:14.056992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"795.287121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-06T01:09:14.057023Z","caller":"traceutil/trace.go:171","msg":"trace[582275319] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:1385; }","duration":"795.385174ms","start":"2026-08-06T01:09:13.26163Z","end":"2026-08-06T01:09:14.057015Z","steps":["trace[582275319] 'agreement among raft nodes before linearized reading' (duration: 795.220499ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:09:14.057049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:09:13.261595Z","time spent":"795.447426ms","remote":"127.0.0.1:37170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":5,"response size":31,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true "} {"level":"warn","ts":"2026-08-06T01:09:14.057185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:09:13.182991Z","time spent":"874.015814ms","remote":"127.0.0.1:37234","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-08-06T01:09:14.057185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.932349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-06T01:09:14.057317Z","caller":"traceutil/trace.go:171","msg":"trace[1653994776] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:1385; }","duration":"379.148566ms","start":"2026-08-06T01:09:13.678144Z","end":"2026-08-06T01:09:14.057292Z","steps":["trace[1653994776] 'agreement among raft nodes before linearized reading' (duration: 378.826585ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:09:14.057446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:09:13.678122Z","time spent":"379.279129ms","remote":"127.0.0.1:37144","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":31,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true "} {"level":"warn","ts":"2026-08-06T01:09:14.057537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"820.988617ms","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-06T01:09:14.057576Z","caller":"traceutil/trace.go:171","msg":"trace[41169634] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1385; }","duration":"821.077191ms","start":"2026-08-06T01:09:13.23649Z","end":"2026-08-06T01:09:14.057567Z","steps":["trace[41169634] 'agreement among raft nodes before linearized reading' (duration: 820.335967ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:09:14.057595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:09:13.236462Z","time spent":"821.128032ms","remote":"127.0.0.1:37234","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-06T01:09:14.057337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.111744ms","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-08-06T01:09:14.057744Z","caller":"traceutil/trace.go:171","msg":"trace[342667001] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1385; }","duration":"185.526647ms","start":"2026-08-06T01:09:13.872158Z","end":"2026-08-06T01:09:14.057684Z","steps":["trace[342667001] 'agreement among raft nodes before linearized reading' (duration: 185.124984ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:09:14.057221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"735.038793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T01:09:14.058096Z","caller":"traceutil/trace.go:171","msg":"trace[573961511] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1385; }","duration":"735.952883ms","start":"2026-08-06T01:09:13.322126Z","end":"2026-08-06T01:09:14.058079Z","steps":["trace[573961511] 'agreement among raft nodes before linearized reading' (duration: 735.047684ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:09:14.058212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:09:13.322105Z","time spent":"736.092297ms","remote":"127.0.0.1:37000","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-08-06T01:09:14.057339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.325274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-08-06T01:09:14.058406Z","caller":"traceutil/trace.go:171","msg":"trace[219598743] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1385; }","duration":"266.455491ms","start":"2026-08-06T01:09:13.791905Z","end":"2026-08-06T01:09:14.05836Z","steps":["trace[219598743] 'agreement among raft nodes before linearized reading' (duration: 264.969363ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:13:08.232779Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9602413090993890378,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-06T01:13:08.732933Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9602413090993890378,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-06T01:13:08.945062Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.371456907s","expected-duration":"1s"} {"level":"info","ts":"2026-08-06T01:13:08.945528Z","caller":"traceutil/trace.go:171","msg":"trace[1505450931] transaction","detail":"{read_only:false; response_revision:2456; number_of_response:1; }","duration":"1.371978633s","start":"2026-08-06T01:13:07.573527Z","end":"2026-08-06T01:13:08.945505Z","steps":["trace[1505450931] 'process raft request' (duration: 1.371808578s)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:13:08.945649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:13:07.573517Z","time spent":"1.372058227s","remote":"127.0.0.1:37234","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-08-06T01:13:08.951194Z","caller":"traceutil/trace.go:171","msg":"trace[1362098015] linearizableReadLoop","detail":"{readStateIndex:2563; appliedIndex:2559; }","duration":"1.2192229s","start":"2026-08-06T01:13:07.731953Z","end":"2026-08-06T01:13:08.951176Z","steps":["trace[1362098015] 'read index received' (duration: 1.213434254s)","trace[1362098015] 'applied index is now lower than readState.Index' (duration: 5.787966ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T01:13:08.9513Z","caller":"traceutil/trace.go:171","msg":"trace[1264191319] transaction","detail":"{read_only:false; response_revision:2457; number_of_response:1; }","duration":"1.377510182s","start":"2026-08-06T01:13:07.573763Z","end":"2026-08-06T01:13:08.951273Z","steps":["trace[1264191319] 'process raft request' (duration: 1.377224993s)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:13:08.951379Z","caller":"traceutil/trace.go:171","msg":"trace[1699586465] transaction","detail":"{read_only:false; response_revision:2459; number_of_response:1; }","duration":"1.101542642s","start":"2026-08-06T01:13:07.849825Z","end":"2026-08-06T01:13:08.951368Z","steps":["trace[1699586465] 'process raft request' (duration: 1.101315535s)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:13:08.95136Z","caller":"traceutil/trace.go:171","msg":"trace[2074734948] transaction","detail":"{read_only:false; response_revision:2458; number_of_response:1; }","duration":"1.370542638s","start":"2026-08-06T01:13:07.580783Z","end":"2026-08-06T01:13:08.951326Z","steps":["trace[2074734948] 'process raft request' (duration: 1.37031899s)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:13:08.951487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:13:07.849801Z","time spent":"1.101612473s","remote":"127.0.0.1:37234","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-06T01:13:08.951563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:13:07.580773Z","time spent":"1.370678813s","remote":"127.0.0.1:37234","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-08-06T01:13:08.95154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:13:07.573746Z","time spent":"1.377596584s","remote":"127.0.0.1:37234","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-08-06T01:13:08.951793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.011216041s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T01:13:08.951867Z","caller":"traceutil/trace.go:171","msg":"trace[245064717] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:2459; }","duration":"1.011309244s","start":"2026-08-06T01:13:07.940526Z","end":"2026-08-06T01:13:08.951835Z","steps":["trace[245064717] 'agreement among raft nodes before linearized reading' (duration: 1.011209121s)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:13:08.951875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"543.785942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-08-06T01:13:08.951887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.219939823s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-08-06T01:13:08.951902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:13:07.940508Z","time spent":"1.011381276s","remote":"127.0.0.1:44602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true "} {"level":"info","ts":"2026-08-06T01:13:08.951912Z","caller":"traceutil/trace.go:171","msg":"trace[946080026] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2459; }","duration":"543.854644ms","start":"2026-08-06T01:13:08.408047Z","end":"2026-08-06T01:13:08.951902Z","steps":["trace[946080026] 'agreement among raft nodes before linearized reading' (duration: 543.73555ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:13:08.951939Z","caller":"traceutil/trace.go:171","msg":"trace[2037760507] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2459; }","duration":"1.220007725s","start":"2026-08-06T01:13:07.731903Z","end":"2026-08-06T01:13:08.951911Z","steps":["trace[2037760507] 'agreement among raft nodes before linearized reading' (duration: 1.219924453s)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:13:08.951944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:13:08.40803Z","time spent":"543.907265ms","remote":"127.0.0.1:37234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-06T01:13:08.951965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:13:07.731883Z","time spent":"1.220075718s","remote":"127.0.0.1:37234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-06T01:13:08.952058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"961.098632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-08-06T01:13:08.952082Z","caller":"traceutil/trace.go:171","msg":"trace[1770374365] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2459; }","duration":"961.162434ms","start":"2026-08-06T01:13:07.990911Z","end":"2026-08-06T01:13:08.952074Z","steps":["trace[1770374365] 'agreement among raft nodes before linearized reading' (duration: 961.091401ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:13:08.952085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"792.305892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-06T01:13:08.952111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:13:07.99089Z","time spent":"961.215126ms","remote":"127.0.0.1:37234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":498,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-08-06T01:13:08.952118Z","caller":"traceutil/trace.go:171","msg":"trace[1033168105] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:2459; }","duration":"792.378384ms","start":"2026-08-06T01:13:08.15973Z","end":"2026-08-06T01:13:08.952109Z","steps":["trace[1033168105] 'agreement among raft nodes before linearized reading' (duration: 792.301091ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:13:08.952143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:13:08.159708Z","time spent":"792.426695ms","remote":"127.0.0.1:37332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":55,"response size":31,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true "}