{"level":"warn","ts":"2026-07-01T07:27:42.966337Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-01T07:27:42.966443Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.90: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.90:2380","--initial-cluster=instance=https://199.19.213.90:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.90:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.90: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-07-01T07:27:42.966525Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-01T07:27:42.966543Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.90:2380"]} {"level":"info","ts":"2026-07-01T07:27:42.966574Z","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-07-01T07:27:42.967445Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.90:2379"]} {"level":"info","ts":"2026-07-01T07:27:42.967544Z","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.90:2380"],"listen-peer-urls":["https://199.19.213.90:2380"],"advertise-client-urls":["https://199.19.213.90:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.90:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.90: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-07-01T07:27:42.972128Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.311812ms"} {"level":"info","ts":"2026-07-01T07:27:42.979883Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"2b21fdc9c735cc27","cluster-id":"77163f3b44f8f1db"} {"level":"info","ts":"2026-07-01T07:27:42.979946Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b21fdc9c735cc27 switched to configuration voters=()"} {"level":"info","ts":"2026-07-01T07:27:42.979966Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b21fdc9c735cc27 became follower at term 0"} {"level":"info","ts":"2026-07-01T07:27:42.980028Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 2b21fdc9c735cc27 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-01T07:27:42.980046Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b21fdc9c735cc27 became follower at term 1"} {"level":"info","ts":"2026-07-01T07:27:42.980084Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b21fdc9c735cc27 switched to configuration voters=(3108044260934798375)"} {"level":"warn","ts":"2026-07-01T07:27:42.987505Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-01T07:27:42.989631Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-01T07:27:42.997016Z","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-07-01T07:27:42.999898Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"2b21fdc9c735cc27","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-01T07:27:43.003464Z","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-07-01T07:27:43.00354Z","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-07-01T07:27:43.003554Z","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-07-01T07:27:43.003722Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b21fdc9c735cc27 switched to configuration voters=(3108044260934798375)"} {"level":"info","ts":"2026-07-01T07:27:43.003852Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"77163f3b44f8f1db","local-member-id":"2b21fdc9c735cc27","added-peer-id":"2b21fdc9c735cc27","added-peer-peer-urls":["https://199.19.213.90:2380"]} {"level":"info","ts":"2026-07-01T07:27:43.000661Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"2b21fdc9c735cc27","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-01T07:27:43.012968Z","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-07-01T07:27:43.013132Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.90:2380"} {"level":"info","ts":"2026-07-01T07:27:43.013158Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.90:2380"} {"level":"info","ts":"2026-07-01T07:27:43.014362Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"2b21fdc9c735cc27","initial-advertise-peer-urls":["https://199.19.213.90:2380"],"listen-peer-urls":["https://199.19.213.90:2380"],"advertise-client-urls":["https://199.19.213.90:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.90:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-01T07:27:43.014412Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-01T07:27:43.680695Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b21fdc9c735cc27 is starting a new election at term 1"} {"level":"info","ts":"2026-07-01T07:27:43.680752Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b21fdc9c735cc27 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-01T07:27:43.68077Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b21fdc9c735cc27 received MsgPreVoteResp from 2b21fdc9c735cc27 at term 1"} {"level":"info","ts":"2026-07-01T07:27:43.680782Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b21fdc9c735cc27 became candidate at term 2"} {"level":"info","ts":"2026-07-01T07:27:43.680786Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b21fdc9c735cc27 received MsgVoteResp from 2b21fdc9c735cc27 at term 2"} {"level":"info","ts":"2026-07-01T07:27:43.680795Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2b21fdc9c735cc27 became leader at term 2"} {"level":"info","ts":"2026-07-01T07:27:43.680802Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 2b21fdc9c735cc27 elected leader 2b21fdc9c735cc27 at term 2"} {"level":"info","ts":"2026-07-01T07:27:43.681654Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"2b21fdc9c735cc27","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.90:2379]}","request-path":"/0/members/2b21fdc9c735cc27/attributes","cluster-id":"77163f3b44f8f1db","publish-timeout":"7s"} {"level":"info","ts":"2026-07-01T07:27:43.681666Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-01T07:27:43.681846Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-01T07:27:43.681991Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-01T07:27:43.682022Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-01T07:27:43.68215Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-01T07:27:43.683071Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"77163f3b44f8f1db","local-member-id":"2b21fdc9c735cc27","cluster-version":"3.5"} {"level":"info","ts":"2026-07-01T07:27:43.683158Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-01T07:27:43.683188Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-01T07:27:43.684793Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.90:2379"} {"level":"info","ts":"2026-07-01T07:27:43.686041Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-01T07:27:48.42131Z","caller":"traceutil/trace.go:171","msg":"trace[1421754732] transaction","detail":"{read_only:false; response_revision:259; number_of_response:1; }","duration":"121.618031ms","start":"2026-07-01T07:27:48.299478Z","end":"2026-07-01T07:27:48.421096Z","steps":["trace[1421754732] 'process raft request' (duration: 121.112966ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:27:48.421955Z","caller":"traceutil/trace.go:171","msg":"trace[953594487] linearizableReadLoop","detail":"{readStateIndex:266; appliedIndex:264; }","duration":"117.04292ms","start":"2026-07-01T07:27:48.304886Z","end":"2026-07-01T07:27:48.421929Z","steps":["trace[953594487] 'read index received' (duration: 115.713989ms)","trace[953594487] 'applied index is now lower than readState.Index' (duration: 1.328011ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T07:27:48.422365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.460133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cronjob-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T07:27:48.422447Z","caller":"traceutil/trace.go:171","msg":"trace[1418094693] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cronjob-controller; range_end:; response_count:0; response_revision:260; }","duration":"117.574826ms","start":"2026-07-01T07:27:48.304858Z","end":"2026-07-01T07:27:48.422433Z","steps":["trace[1418094693] 'agreement among raft nodes before linearized reading' (duration: 117.159194ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:27:48.42204Z","caller":"traceutil/trace.go:171","msg":"trace[1021497637] transaction","detail":"{read_only:false; response_revision:260; number_of_response:1; }","duration":"122.457766ms","start":"2026-07-01T07:27:48.299556Z","end":"2026-07-01T07:27:48.422013Z","steps":["trace[1021497637] 'process raft request' (duration: 122.278981ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:27:56.477306Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14710901652913227637,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-01T07:27:56.575724Z","caller":"traceutil/trace.go:171","msg":"trace[1942360730] linearizableReadLoop","detail":"{readStateIndex:413; appliedIndex:412; }","duration":"598.595874ms","start":"2026-07-01T07:27:55.977112Z","end":"2026-07-01T07:27:56.575708Z","steps":["trace[1942360730] 'read index received' (duration: 598.452339ms)","trace[1942360730] 'applied index is now lower than readState.Index' (duration: 142.955µs)"],"step_count":2} {"level":"info","ts":"2026-07-01T07:27:56.575922Z","caller":"traceutil/trace.go:171","msg":"trace[1045054331] transaction","detail":"{read_only:false; response_revision:399; number_of_response:1; }","duration":"729.167249ms","start":"2026-07-01T07:27:55.846748Z","end":"2026-07-01T07:27:56.575915Z","steps":["trace[1045054331] 'process raft request' (duration: 728.86446ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:27:56.576248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:27:55.84673Z","time spent":"729.219091ms","remote":"127.0.0.1:55784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T07:27:56.576385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"599.275084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:5326"} {"level":"info","ts":"2026-07-01T07:27:56.576408Z","caller":"traceutil/trace.go:171","msg":"trace[1507407632] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:399; }","duration":"599.330796ms","start":"2026-07-01T07:27:55.97707Z","end":"2026-07-01T07:27:56.576401Z","steps":["trace[1507407632] 'agreement among raft nodes before linearized reading' (duration: 599.290565ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:27:56.576422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:27:55.977048Z","time spent":"599.371428ms","remote":"127.0.0.1:55706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":5348,"request content":"key:\"/registry/pods/kube-system/etcd-instance\" "} {"level":"warn","ts":"2026-07-01T07:27:56.576512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"599.290344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T07:27:56.57653Z","caller":"traceutil/trace.go:171","msg":"trace[520745673] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:399; }","duration":"599.346596ms","start":"2026-07-01T07:27:55.977179Z","end":"2026-07-01T07:27:56.576526Z","steps":["trace[520745673] 'agreement among raft nodes before linearized reading' (duration: 599.314355ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:27:56.576542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:27:55.977156Z","time spent":"599.383187ms","remote":"127.0.0.1:55528","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-01T07:28:02.493853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"466.830409ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-07-01T07:28:02.493962Z","caller":"traceutil/trace.go:171","msg":"trace[770433957] linearizableReadLoop","detail":"{readStateIndex:432; appliedIndex:431; }","duration":"273.495362ms","start":"2026-07-01T07:28:02.22045Z","end":"2026-07-01T07:28:02.493945Z","steps":["trace[770433957] 'read index received' (duration: 39.981µs)","trace[770433957] 'applied index is now lower than readState.Index' (duration: 273.454071ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T07:28:02.494121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.666508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T07:28:02.494158Z","caller":"traceutil/trace.go:171","msg":"trace[1811397189] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:416; }","duration":"273.747931ms","start":"2026-07-01T07:28:02.2204Z","end":"2026-07-01T07:28:02.494148Z","steps":["trace[1811397189] 'agreement among raft nodes before linearized reading' (duration: 273.644167ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:28:04.975259Z","caller":"traceutil/trace.go:171","msg":"trace[92825560] transaction","detail":"{read_only:false; response_revision:423; number_of_response:1; }","duration":"107.901229ms","start":"2026-07-01T07:28:04.867333Z","end":"2026-07-01T07:28:04.975234Z","steps":["trace[92825560] 'process raft request' (duration: 107.699763ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:28:19.506293Z","caller":"traceutil/trace.go:171","msg":"trace[275752632] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"514.894128ms","start":"2026-07-01T07:28:18.991372Z","end":"2026-07-01T07:28:19.506266Z","steps":["trace[275752632] 'process raft request' (duration: 514.739463ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:28:19.506427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:28:18.99135Z","time spent":"515.018222ms","remote":"127.0.0.1:55584","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-01T07:28:19.514635Z","caller":"traceutil/trace.go:171","msg":"trace[1621101724] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"508.45873ms","start":"2026-07-01T07:28:19.006154Z","end":"2026-07-01T07:28:19.514613Z","steps":["trace[1621101724] 'process raft request' (duration: 508.188662ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:28:19.514709Z","caller":"traceutil/trace.go:171","msg":"trace[2070855998] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"493.660425ms","start":"2026-07-01T07:28:19.02104Z","end":"2026-07-01T07:28:19.514701Z","steps":["trace[2070855998] 'process raft request' (duration: 493.343525ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:28:19.514704Z","caller":"traceutil/trace.go:171","msg":"trace[1524146864] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"493.358745ms","start":"2026-07-01T07:28:19.021321Z","end":"2026-07-01T07:28:19.514679Z","steps":["trace[1524146864] 'process raft request' (duration: 493.088547ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:28:19.514769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:28:19.02101Z","time spent":"493.725377ms","remote":"127.0.0.1:55584","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-01T07:28:19.514804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:28:19.021313Z","time spent":"493.435348ms","remote":"127.0.0.1:55584","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-01T07:28:19.514844Z","caller":"traceutil/trace.go:171","msg":"trace[785501447] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"510.998818ms","start":"2026-07-01T07:28:19.003831Z","end":"2026-07-01T07:28:19.51483Z","steps":["trace[785501447] 'process raft request' (duration: 510.41068ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:28:19.514879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:28:19.00615Z","time spent":"508.572954ms","remote":"127.0.0.1:55584","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-01T07:28:19.514975Z","caller":"traceutil/trace.go:171","msg":"trace[1246252049] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"508.868172ms","start":"2026-07-01T07:28:19.0061Z","end":"2026-07-01T07:28:19.514968Z","steps":["trace[1246252049] 'process raft request' (duration: 508.188691ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:28:19.514928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:28:19.003812Z","time spent":"511.089781ms","remote":"127.0.0.1:55584","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-01T07:28:19.515017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:28:19.006081Z","time spent":"508.919124ms","remote":"127.0.0.1:55584","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-01T07:28:19.515072Z","caller":"traceutil/trace.go:171","msg":"trace[595481411] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"492.306003ms","start":"2026-07-01T07:28:19.022761Z","end":"2026-07-01T07:28:19.515067Z","steps":["trace[595481411] 'process raft request' (duration: 491.674904ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:28:19.515101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:28:19.022745Z","time spent":"492.344215ms","remote":"127.0.0.1:55584","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-01T07:28:19.514665Z","caller":"traceutil/trace.go:171","msg":"trace[2106856894] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"521.937944ms","start":"2026-07-01T07:28:18.992709Z","end":"2026-07-01T07:28:19.514647Z","steps":["trace[2106856894] 'process raft request' (duration: 521.279174ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:28:19.51517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:28:18.992701Z","time spent":"522.458341ms","remote":"127.0.0.1:55584","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-01T07:28:19.515171Z","caller":"traceutil/trace.go:171","msg":"trace[1881375859] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"481.745418ms","start":"2026-07-01T07:28:19.033415Z","end":"2026-07-01T07:28:19.51516Z","steps":["trace[1881375859] 'process raft request' (duration: 481.054537ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:28:19.515221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:28:19.033406Z","time spent":"481.79279ms","remote":"127.0.0.1:55584","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-01T07:28:19.515343Z","caller":"traceutil/trace.go:171","msg":"trace[581095905] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"477.546479ms","start":"2026-07-01T07:28:19.037788Z","end":"2026-07-01T07:28:19.515335Z","steps":["trace[581095905] 'process raft request' (duration: 476.743395ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:28:19.515438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:28:19.037781Z","time spent":"477.636322ms","remote":"127.0.0.1:55584","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-01T07:28:19.515456Z","caller":"traceutil/trace.go:171","msg":"trace[931223350] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"449.551338ms","start":"2026-07-01T07:28:19.065896Z","end":"2026-07-01T07:28:19.515448Z","steps":["trace[931223350] 'process raft request' (duration: 448.699382ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:28:19.515493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:28:19.065881Z","time spent":"449.59496ms","remote":"127.0.0.1:55584","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-01T07:28:19.516035Z","caller":"traceutil/trace.go:171","msg":"trace[94169669] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"340.37394ms","start":"2026-07-01T07:28:19.175645Z","end":"2026-07-01T07:28:19.516019Z","steps":["trace[94169669] 'process raft request' (duration: 340.197984ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:28:19.516115Z","caller":"traceutil/trace.go:171","msg":"trace[1264125170] linearizableReadLoop","detail":"{readStateIndex:545; appliedIndex:537; }","duration":"485.563786ms","start":"2026-07-01T07:28:19.030538Z","end":"2026-07-01T07:28:19.516101Z","steps":["trace[1264125170] 'read index received' (duration: 475.693172ms)","trace[1264125170] 'applied index is now lower than readState.Index' (duration: 9.869084ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T07:28:19.516139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:28:19.175609Z","time spent":"340.481133ms","remote":"127.0.0.1:55584","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-01T07:28:19.516255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"485.7075ms","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-07-01T07:28:19.516318Z","caller":"traceutil/trace.go:171","msg":"trace[1575984643] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:531; }","duration":"485.792533ms","start":"2026-07-01T07:28:19.030516Z","end":"2026-07-01T07:28:19.516309Z","steps":["trace[1575984643] 'agreement among raft nodes before linearized reading' (duration: 485.653619ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:28:19.516317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.923982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-01T07:28:19.51639Z","caller":"traceutil/trace.go:171","msg":"trace[1413313267] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:531; }","duration":"229.026285ms","start":"2026-07-01T07:28:19.287354Z","end":"2026-07-01T07:28:19.51638Z","steps":["trace[1413313267] 'agreement among raft nodes before linearized reading' (duration: 228.937122ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:28:19.516399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:28:19.030507Z","time spent":"485.866795ms","remote":"127.0.0.1:55784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-01T07:28:19.516265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.087358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T07:28:19.516521Z","caller":"traceutil/trace.go:171","msg":"trace[227195360] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:531; }","duration":"296.383677ms","start":"2026-07-01T07:28:19.22013Z","end":"2026-07-01T07:28:19.516513Z","steps":["trace[227195360] 'agreement among raft nodes before linearized reading' (duration: 296.058837ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:28:21.013305Z","caller":"traceutil/trace.go:171","msg":"trace[571822957] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"318.492897ms","start":"2026-07-01T07:28:20.694787Z","end":"2026-07-01T07:28:21.01328Z","steps":["trace[571822957] 'process raft request' (duration: 318.315992ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:28:21.013505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:28:20.694768Z","time spent":"318.607381ms","remote":"127.0.0.1:55784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T07:28:21.013555Z","caller":"traceutil/trace.go:171","msg":"trace[728635254] linearizableReadLoop","detail":"{readStateIndex:617; appliedIndex:617; }","duration":"313.767162ms","start":"2026-07-01T07:28:20.699762Z","end":"2026-07-01T07:28:21.013529Z","steps":["trace[728635254] 'read index received' (duration: 313.757511ms)","trace[728635254] 'applied index is now lower than readState.Index' (duration: 7.471µs)"],"step_count":2} {"level":"warn","ts":"2026-07-01T07:28:21.01375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.965638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/xbackendtrafficpolicies.gateway.networking.x-k8s.io\" ","response":"range_response_count:1 size:23081"} {"level":"info","ts":"2026-07-01T07:28:21.013781Z","caller":"traceutil/trace.go:171","msg":"trace[506824723] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/xbackendtrafficpolicies.gateway.networking.x-k8s.io; range_end:; response_count:1; response_revision:596; }","duration":"314.04004ms","start":"2026-07-01T07:28:20.699732Z","end":"2026-07-01T07:28:21.013772Z","steps":["trace[506824723] 'agreement among raft nodes before linearized reading' (duration: 313.881305ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:28:21.013809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:28:20.699718Z","time spent":"314.084182ms","remote":"127.0.0.1:55584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":1,"response size":23103,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/xbackendtrafficpolicies.gateway.networking.x-k8s.io\" "} {"level":"info","ts":"2026-07-01T07:28:21.016748Z","caller":"traceutil/trace.go:171","msg":"trace[146273007] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"293.825628ms","start":"2026-07-01T07:28:20.722907Z","end":"2026-07-01T07:28:21.016733Z","steps":["trace[146273007] 'process raft request' (duration: 293.721635ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:28:25.465337Z","caller":"traceutil/trace.go:171","msg":"trace[921526590] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"198.31702ms","start":"2026-07-01T07:28:25.266959Z","end":"2026-07-01T07:28:25.465276Z","steps":["trace[921526590] 'process raft request' (duration: 133.251788ms)","trace[921526590] 'compare' (duration: 64.894457ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T07:28:26.053916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.993461ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-01T07:28:26.05402Z","caller":"traceutil/trace.go:171","msg":"trace[558261628] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"406.326609ms","start":"2026-07-01T07:28:25.647676Z","end":"2026-07-01T07:28:26.054002Z","steps":["trace[558261628] 'process raft request' (duration: 49.155013ms)","trace[558261628] 'compare' (duration: 356.841386ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T07:28:26.054106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:28:25.647661Z","time spent":"406.390171ms","remote":"127.0.0.1:55784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T07:28:26.21809Z","caller":"traceutil/trace.go:171","msg":"trace[533968112] linearizableReadLoop","detail":"{readStateIndex:649; appliedIndex:648; }","duration":"172.090334ms","start":"2026-07-01T07:28:26.045975Z","end":"2026-07-01T07:28:26.218066Z","steps":["trace[533968112] 'read index received' (duration: 171.130004ms)","trace[533968112] 'applied index is now lower than readState.Index' (duration: 959.34µs)"],"step_count":2} {"level":"info","ts":"2026-07-01T07:28:26.21815Z","caller":"traceutil/trace.go:171","msg":"trace[842198499] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"336.042276ms","start":"2026-07-01T07:28:25.882077Z","end":"2026-07-01T07:28:26.218119Z","steps":["trace[842198499] 'process raft request' (duration: 335.423567ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:28:26.218273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:28:25.882058Z","time spent":"336.14526ms","remote":"127.0.0.1:55620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036281,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T07:28:26.218414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.434404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-wqh7t\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-07-01T07:28:26.218475Z","caller":"traceutil/trace.go:171","msg":"trace[2082043874] range","detail":"{range_begin:/registry/pods/kube-system/cilium-wqh7t; range_end:; response_count:1; response_revision:627; }","duration":"172.523267ms","start":"2026-07-01T07:28:26.045933Z","end":"2026-07-01T07:28:26.218456Z","steps":["trace[2082043874] 'agreement among raft nodes before linearized reading' (duration: 172.243208ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:28:26.279698Z","caller":"traceutil/trace.go:171","msg":"trace[608961488] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"111.870951ms","start":"2026-07-01T07:28:26.167808Z","end":"2026-07-01T07:28:26.279679Z","steps":["trace[608961488] 'process raft request' (duration: 111.763188ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:28:26.781043Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14710901652913228132,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-01T07:28:27.281622Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14710901652913228132,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-01T07:28:27.761455Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.48180266s","expected-duration":"1s"} {"level":"info","ts":"2026-07-01T07:28:27.76182Z","caller":"traceutil/trace.go:171","msg":"trace[337018148] linearizableReadLoop","detail":"{readStateIndex:652; appliedIndex:650; }","duration":"1.481368026s","start":"2026-07-01T07:28:26.280431Z","end":"2026-07-01T07:28:27.761799Z","steps":["trace[337018148] 'read index received' (duration: 1.481110228s)","trace[337018148] 'applied index is now lower than readState.Index' (duration: 256.838µs)"],"step_count":2} {"level":"info","ts":"2026-07-01T07:28:27.761909Z","caller":"traceutil/trace.go:171","msg":"trace[2146053482] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"1.482822391s","start":"2026-07-01T07:28:26.27906Z","end":"2026-07-01T07:28:27.761883Z","steps":["trace[2146053482] 'process raft request' (duration: 1.482694907s)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:28:27.762007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.481557172s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-01T07:28:27.762035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.822923ms","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-07-01T07:28:27.762035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.202045634s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.90\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-07-01T07:28:27.762041Z","caller":"traceutil/trace.go:171","msg":"trace[1220420640] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:630; }","duration":"1.481625435s","start":"2026-07-01T07:28:26.280406Z","end":"2026-07-01T07:28:27.762032Z","steps":["trace[1220420640] 'agreement among raft nodes before linearized reading' (duration: 1.481516541s)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:28:27.76206Z","caller":"traceutil/trace.go:171","msg":"trace[2127567929] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:630; }","duration":"142.892425ms","start":"2026-07-01T07:28:27.619159Z","end":"2026-07-01T07:28:27.762052Z","steps":["trace[2127567929] 'agreement among raft nodes before linearized reading' (duration: 142.781992ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:28:27.762062Z","caller":"traceutil/trace.go:171","msg":"trace[1761201652] range","detail":"{range_begin:/registry/masterleases/199.19.213.90; range_end:; response_count:1; response_revision:630; }","duration":"1.202116666s","start":"2026-07-01T07:28:26.559938Z","end":"2026-07-01T07:28:27.762055Z","steps":["trace[1761201652] 'agreement among raft nodes before linearized reading' (duration: 1.202002623s)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:28:27.762038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:28:26.279026Z","time spent":"1.482951295s","remote":"127.0.0.1:56054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-01T07:28:27.762082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:28:26.559898Z","time spent":"1.202179008s","remote":"127.0.0.1:55574","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":155,"request content":"key:\"/registry/masterleases/199.19.213.90\" "} {"level":"warn","ts":"2026-07-01T07:28:27.762084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:28:26.280396Z","time spent":"1.481680086s","remote":"127.0.0.1:55528","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-01T07:28:27.761835Z","caller":"traceutil/trace.go:171","msg":"trace[1614684417] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"1.529796376s","start":"2026-07-01T07:28:26.232024Z","end":"2026-07-01T07:28:27.761821Z","steps":["trace[1614684417] 'process raft request' (duration: 1.5295994s)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:28:27.762236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"711.347ms","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-07-01T07:28:27.762309Z","caller":"traceutil/trace.go:171","msg":"trace[1404062745] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:630; }","duration":"711.440903ms","start":"2026-07-01T07:28:27.05085Z","end":"2026-07-01T07:28:27.76229Z","steps":["trace[1404062745] 'agreement among raft nodes before linearized reading' (duration: 711.211245ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:28:27.762309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.947808ms","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-07-01T07:28:27.762381Z","caller":"traceutil/trace.go:171","msg":"trace[1911152064] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:630; }","duration":"131.069362ms","start":"2026-07-01T07:28:27.631295Z","end":"2026-07-01T07:28:27.762364Z","steps":["trace[1911152064] 'agreement among raft nodes before linearized reading' (duration: 130.836315ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:28:27.762369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:28:27.050835Z","time spent":"711.520296ms","remote":"127.0.0.1:55784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-01T07:28:27.76231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:28:26.232004Z","time spent":"1.53025236s","remote":"127.0.0.1:55706","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T07:28:28.254192Z","caller":"traceutil/trace.go:171","msg":"trace[996742587] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"163.439307ms","start":"2026-07-01T07:28:28.090738Z","end":"2026-07-01T07:28:28.254177Z","steps":["trace[996742587] 'process raft request' (duration: 163.329524ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:28:28.44046Z","caller":"traceutil/trace.go:171","msg":"trace[1852262374] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"142.932856ms","start":"2026-07-01T07:28:28.297508Z","end":"2026-07-01T07:28:28.440441Z","steps":["trace[1852262374] 'process raft request' (duration: 142.143362ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:28:30.38234Z","caller":"traceutil/trace.go:171","msg":"trace[1932488264] linearizableReadLoop","detail":"{readStateIndex:704; appliedIndex:703; }","duration":"152.057043ms","start":"2026-07-01T07:28:30.23026Z","end":"2026-07-01T07:28:30.382317Z","steps":["trace[1932488264] 'read index received' (duration: 151.880678ms)","trace[1932488264] 'applied index is now lower than readState.Index' (duration: 175.635µs)"],"step_count":2} {"level":"info","ts":"2026-07-01T07:28:30.382489Z","caller":"traceutil/trace.go:171","msg":"trace[1558216809] transaction","detail":"{read_only:false; response_revision:681; number_of_response:1; }","duration":"155.871639ms","start":"2026-07-01T07:28:30.226556Z","end":"2026-07-01T07:28:30.382427Z","steps":["trace[1558216809] 'process raft request' (duration: 155.644452ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:28:30.382657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.372953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T07:28:30.382712Z","caller":"traceutil/trace.go:171","msg":"trace[661942921] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:681; }","duration":"152.483246ms","start":"2026-07-01T07:28:30.230213Z","end":"2026-07-01T07:28:30.382696Z","steps":["trace[661942921] 'agreement among raft nodes before linearized reading' (duration: 152.241839ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:28:30.382734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.166174ms","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-07-01T07:28:30.38279Z","caller":"traceutil/trace.go:171","msg":"trace[648464496] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:681; }","duration":"125.258787ms","start":"2026-07-01T07:28:30.257513Z","end":"2026-07-01T07:28:30.382772Z","steps":["trace[648464496] 'agreement among raft nodes before linearized reading' (duration: 125.021379ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:28:32.374016Z","caller":"traceutil/trace.go:171","msg":"trace[1547605199] linearizableReadLoop","detail":"{readStateIndex:727; appliedIndex:726; }","duration":"155.741275ms","start":"2026-07-01T07:28:32.218249Z","end":"2026-07-01T07:28:32.37399Z","steps":["trace[1547605199] 'read index received' (duration: 155.541789ms)","trace[1547605199] 'applied index is now lower than readState.Index' (duration: 198.526µs)"],"step_count":2} {"level":"info","ts":"2026-07-01T07:28:32.37406Z","caller":"traceutil/trace.go:171","msg":"trace[1409965628] transaction","detail":"{read_only:false; response_revision:703; number_of_response:1; }","duration":"161.036847ms","start":"2026-07-01T07:28:32.21298Z","end":"2026-07-01T07:28:32.374017Z","steps":["trace[1409965628] 'process raft request' (duration: 160.874342ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:28:32.374207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.937032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T07:28:32.374252Z","caller":"traceutil/trace.go:171","msg":"trace[966688828] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:703; }","duration":"156.030375ms","start":"2026-07-01T07:28:32.218209Z","end":"2026-07-01T07:28:32.374239Z","steps":["trace[966688828] 'agreement among raft nodes before linearized reading' (duration: 155.88617ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:28:39.979827Z","caller":"traceutil/trace.go:171","msg":"trace[975471383] transaction","detail":"{read_only:false; response_revision:770; number_of_response:1; }","duration":"112.431826ms","start":"2026-07-01T07:28:39.867375Z","end":"2026-07-01T07:28:39.979806Z","steps":["trace[975471383] 'process raft request' (duration: 112.328953ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:28:39.984799Z","caller":"traceutil/trace.go:171","msg":"trace[1275762145] linearizableReadLoop","detail":"{readStateIndex:797; appliedIndex:795; }","duration":"109.519638ms","start":"2026-07-01T07:28:39.87526Z","end":"2026-07-01T07:28:39.984779Z","steps":["trace[1275762145] 'read index received' (duration: 104.499315ms)","trace[1275762145] 'applied index is now lower than readState.Index' (duration: 5.019543ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T07:28:39.984891Z","caller":"traceutil/trace.go:171","msg":"trace[344238008] transaction","detail":"{read_only:false; response_revision:771; number_of_response:1; }","duration":"117.441658ms","start":"2026-07-01T07:28:39.867419Z","end":"2026-07-01T07:28:39.984861Z","steps":["trace[344238008] 'process raft request' (duration: 117.268873ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:28:39.985002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.729104ms","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-07-01T07:28:39.985041Z","caller":"traceutil/trace.go:171","msg":"trace[800589882] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:771; }","duration":"109.794326ms","start":"2026-07-01T07:28:39.875237Z","end":"2026-07-01T07:28:39.985031Z","steps":["trace[800589882] 'agreement among raft nodes before linearized reading' (duration: 109.663432ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:28:39.985184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.425081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-01T07:28:39.985272Z","caller":"traceutil/trace.go:171","msg":"trace[174234506] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:771; }","duration":"101.526344ms","start":"2026-07-01T07:28:39.883718Z","end":"2026-07-01T07:28:39.985244Z","steps":["trace[174234506] 'agreement among raft nodes before linearized reading' (duration: 101.345529ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:28:43.053785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"911.733123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-dh5vf\" ","response":"range_response_count:1 size:3964"} {"level":"warn","ts":"2026-07-01T07:28:43.053787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"604.310385ms","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-07-01T07:28:43.05387Z","caller":"traceutil/trace.go:171","msg":"trace[1563191397] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-dh5vf; range_end:; response_count:1; response_revision:791; }","duration":"911.857197ms","start":"2026-07-01T07:28:42.141987Z","end":"2026-07-01T07:28:43.053844Z","steps":["trace[1563191397] 'range keys from in-memory index tree' (duration: 911.539747ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:28:43.053911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:28:42.141959Z","time spent":"911.943419ms","remote":"127.0.0.1:55706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3986,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-dh5vf\" "} {"level":"info","ts":"2026-07-01T07:28:43.053904Z","caller":"traceutil/trace.go:171","msg":"trace[1320700737] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:791; }","duration":"604.46264ms","start":"2026-07-01T07:28:42.449418Z","end":"2026-07-01T07:28:43.053881Z","steps":["trace[1320700737] 'range keys from in-memory index tree' (duration: 604.12496ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:28:43.053968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:28:42.449398Z","time spent":"604.549362ms","remote":"127.0.0.1:55784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-01T07:28:54.360725Z","caller":"traceutil/trace.go:171","msg":"trace[437383394] linearizableReadLoop","detail":"{readStateIndex:944; appliedIndex:943; }","duration":"140.179911ms","start":"2026-07-01T07:28:54.220522Z","end":"2026-07-01T07:28:54.360702Z","steps":["trace[437383394] 'read index received' (duration: 97.438049ms)","trace[437383394] 'applied index is now lower than readState.Index' (duration: 42.740912ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T07:28:54.360791Z","caller":"traceutil/trace.go:171","msg":"trace[1098682185] transaction","detail":"{read_only:false; response_revision:914; number_of_response:1; }","duration":"140.524852ms","start":"2026-07-01T07:28:54.220197Z","end":"2026-07-01T07:28:54.360722Z","steps":["trace[1098682185] 'process raft request' (duration: 97.715097ms)","trace[1098682185] 'compare' (duration: 42.547157ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T07:28:54.36086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.323786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T07:28:54.360901Z","caller":"traceutil/trace.go:171","msg":"trace[181641762] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:914; }","duration":"140.398358ms","start":"2026-07-01T07:28:54.220494Z","end":"2026-07-01T07:28:54.360893Z","steps":["trace[181641762] 'agreement among raft nodes before linearized reading' (duration: 140.300265ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:28:56.744732Z","caller":"traceutil/trace.go:171","msg":"trace[1876838254] transaction","detail":"{read_only:false; response_revision:925; number_of_response:1; }","duration":"108.040702ms","start":"2026-07-01T07:28:56.636671Z","end":"2026-07-01T07:28:56.744712Z","steps":["trace[1876838254] 'process raft request' (duration: 104.917177ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:29:08.389888Z","caller":"traceutil/trace.go:171","msg":"trace[1351234107] linearizableReadLoop","detail":"{readStateIndex:1004; appliedIndex:1003; }","duration":"193.430834ms","start":"2026-07-01T07:29:08.196439Z","end":"2026-07-01T07:29:08.38987Z","steps":["trace[1351234107] 'read index received' (duration: 193.314681ms)","trace[1351234107] 'applied index is now lower than readState.Index' (duration: 115.433µs)"],"step_count":2} {"level":"info","ts":"2026-07-01T07:29:08.389997Z","caller":"traceutil/trace.go:171","msg":"trace[1504611915] transaction","detail":"{read_only:false; response_revision:970; number_of_response:1; }","duration":"195.949141ms","start":"2026-07-01T07:29:08.194017Z","end":"2026-07-01T07:29:08.389966Z","steps":["trace[1504611915] 'process raft request' (duration: 195.750105ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:29:08.390049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.598269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-07-01T07:29:08.390074Z","caller":"traceutil/trace.go:171","msg":"trace[1022179045] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:970; }","duration":"193.650271ms","start":"2026-07-01T07:29:08.196415Z","end":"2026-07-01T07:29:08.390066Z","steps":["trace[1022179045] 'agreement among raft nodes before linearized reading' (duration: 193.558158ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:29:08.390079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.030153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T07:29:08.39014Z","caller":"traceutil/trace.go:171","msg":"trace[2144225945] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:970; }","duration":"173.132456ms","start":"2026-07-01T07:29:08.216993Z","end":"2026-07-01T07:29:08.390126Z","steps":["trace[2144225945] 'agreement among raft nodes before linearized reading' (duration: 173.018253ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:29:11.542044Z","caller":"traceutil/trace.go:171","msg":"trace[2062675029] transaction","detail":"{read_only:false; response_revision:980; number_of_response:1; }","duration":"160.604154ms","start":"2026-07-01T07:29:11.381417Z","end":"2026-07-01T07:29:11.542021Z","steps":["trace[2062675029] 'process raft request' (duration: 160.46482ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:29:17.35492Z","caller":"traceutil/trace.go:171","msg":"trace[1977024959] linearizableReadLoop","detail":"{readStateIndex:1038; appliedIndex:1037; }","duration":"135.122448ms","start":"2026-07-01T07:29:17.219776Z","end":"2026-07-01T07:29:17.354899Z","steps":["trace[1977024959] 'read index received' (duration: 135.040295ms)","trace[1977024959] 'applied index is now lower than readState.Index' (duration: 81.513µs)"],"step_count":2} {"level":"info","ts":"2026-07-01T07:29:17.354953Z","caller":"traceutil/trace.go:171","msg":"trace[430327749] transaction","detail":"{read_only:false; response_revision:1002; number_of_response:1; }","duration":"184.870513ms","start":"2026-07-01T07:29:17.170068Z","end":"2026-07-01T07:29:17.354938Z","steps":["trace[430327749] 'process raft request' (duration: 184.700468ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:29:17.355056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.267452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T07:29:17.355087Z","caller":"traceutil/trace.go:171","msg":"trace[2092926995] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1002; }","duration":"135.338314ms","start":"2026-07-01T07:29:17.21974Z","end":"2026-07-01T07:29:17.355078Z","steps":["trace[2092926995] 'agreement among raft nodes before linearized reading' (duration: 135.275082ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:29:17.839537Z","caller":"traceutil/trace.go:171","msg":"trace[1053641802] transaction","detail":"{read_only:false; response_revision:1004; number_of_response:1; }","duration":"280.527158ms","start":"2026-07-01T07:29:17.558988Z","end":"2026-07-01T07:29:17.839515Z","steps":["trace[1053641802] 'process raft request' (duration: 280.482117ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:29:17.839698Z","caller":"traceutil/trace.go:171","msg":"trace[683016038] transaction","detail":"{read_only:false; response_revision:1003; number_of_response:1; }","duration":"480.065879ms","start":"2026-07-01T07:29:17.359608Z","end":"2026-07-01T07:29:17.839674Z","steps":["trace[683016038] 'process raft request' (duration: 397.661558ms)","trace[683016038] 'compare' (duration: 82.05751ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T07:29:17.839811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:29:17.359594Z","time spent":"480.150881ms","remote":"127.0.0.1:55784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T07:29:18.543753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.004403ms","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-07-01T07:29:18.543816Z","caller":"traceutil/trace.go:171","msg":"trace[1205858969] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1004; }","duration":"111.087325ms","start":"2026-07-01T07:29:18.432714Z","end":"2026-07-01T07:29:18.543801Z","steps":["trace[1205858969] 'range keys from in-memory index tree' (duration: 110.884279ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:29:18.543879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.849589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-01T07:29:18.543936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.693662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-01T07:29:18.543957Z","caller":"traceutil/trace.go:171","msg":"trace[381210985] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1004; }","duration":"323.970152ms","start":"2026-07-01T07:29:18.219968Z","end":"2026-07-01T07:29:18.543938Z","steps":["trace[381210985] 'range keys from in-memory index tree' (duration: 323.790826ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:29:18.543949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.275836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-07-01T07:29:18.543949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.24469ms","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-07-01T07:29:18.544007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:29:18.219954Z","time spent":"324.039045ms","remote":"127.0.0.1:55530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-01T07:29:18.544037Z","caller":"traceutil/trace.go:171","msg":"trace[693947433] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1004; }","duration":"398.39091ms","start":"2026-07-01T07:29:18.145623Z","end":"2026-07-01T07:29:18.544014Z","steps":["trace[693947433] 'range keys from in-memory index tree' (duration: 398.148823ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:29:18.544059Z","caller":"traceutil/trace.go:171","msg":"trace[971036281] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1004; }","duration":"111.376695ms","start":"2026-07-01T07:29:18.432646Z","end":"2026-07-01T07:29:18.544023Z","steps":["trace[971036281] 'range keys from in-memory index tree' (duration: 111.042644ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:29:18.544091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:29:18.145608Z","time spent":"398.470182ms","remote":"127.0.0.1:55784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-01T07:29:18.543993Z","caller":"traceutil/trace.go:171","msg":"trace[1734964933] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1004; }","duration":"402.766404ms","start":"2026-07-01T07:29:18.141196Z","end":"2026-07-01T07:29:18.543963Z","steps":["trace[1734964933] 'range keys from in-memory index tree' (duration: 402.448484ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:29:18.544161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:29:18.141177Z","time spent":"402.972839ms","remote":"127.0.0.1:55784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-01T07:29:39.165191Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14710901652913229079,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-01T07:29:39.449223Z","caller":"traceutil/trace.go:171","msg":"trace[1708942831] linearizableReadLoop","detail":"{readStateIndex:1111; appliedIndex:1110; }","duration":"784.213484ms","start":"2026-07-01T07:29:38.664991Z","end":"2026-07-01T07:29:39.449204Z","steps":["trace[1708942831] 'read index received' (duration: 784.034059ms)","trace[1708942831] 'applied index is now lower than readState.Index' (duration: 178.635µs)"],"step_count":2} {"level":"info","ts":"2026-07-01T07:29:39.449338Z","caller":"traceutil/trace.go:171","msg":"trace[1237237625] transaction","detail":"{read_only:false; response_revision:1071; number_of_response:1; }","duration":"784.758421ms","start":"2026-07-01T07:29:38.664568Z","end":"2026-07-01T07:29:39.449326Z","steps":["trace[1237237625] 'process raft request' (duration: 784.506713ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:29:39.449432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:29:38.664552Z","time spent":"784.808382ms","remote":"127.0.0.1:55784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T07:29:39.449512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"780.360424ms","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-07-01T07:29:39.449583Z","caller":"traceutil/trace.go:171","msg":"trace[1806783134] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1071; }","duration":"780.474907ms","start":"2026-07-01T07:29:38.66909Z","end":"2026-07-01T07:29:39.449565Z","steps":["trace[1806783134] 'agreement among raft nodes before linearized reading' (duration: 780.255941ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:29:39.449627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:29:38.669075Z","time spent":"780.541939ms","remote":"127.0.0.1:55784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-01T07:29:39.449714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"784.705979ms","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-07-01T07:29:39.449762Z","caller":"traceutil/trace.go:171","msg":"trace[573174672] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1071; }","duration":"784.774971ms","start":"2026-07-01T07:29:38.664974Z","end":"2026-07-01T07:29:39.449749Z","steps":["trace[573174672] 'agreement among raft nodes before linearized reading' (duration: 784.655027ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:29:39.449784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:29:38.664968Z","time spent":"784.811672ms","remote":"127.0.0.1:55784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-01T07:29:39.449972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"649.704021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-01T07:29:39.449958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"745.91ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-01T07:29:39.449995Z","caller":"traceutil/trace.go:171","msg":"trace[371319724] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:1071; }","duration":"649.766003ms","start":"2026-07-01T07:29:38.800223Z","end":"2026-07-01T07:29:39.449989Z","steps":["trace[371319724] 'agreement among raft nodes before linearized reading' (duration: 649.710641ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:29:39.450015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:29:38.800201Z","time spent":"649.808853ms","remote":"127.0.0.1:55914","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":27,"request content":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true "} {"level":"info","ts":"2026-07-01T07:29:39.450018Z","caller":"traceutil/trace.go:171","msg":"trace[1894631038] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1071; }","duration":"745.997083ms","start":"2026-07-01T07:29:38.704005Z","end":"2026-07-01T07:29:39.450002Z","steps":["trace[1894631038] 'agreement among raft nodes before linearized reading' (duration: 745.829928ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:29:39.450059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:29:38.70399Z","time spent":"746.058765ms","remote":"127.0.0.1:55784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-01T07:29:42.176368Z","caller":"traceutil/trace.go:171","msg":"trace[702173950] linearizableReadLoop","detail":"{readStateIndex:1123; appliedIndex:1122; }","duration":"232.380736ms","start":"2026-07-01T07:29:41.943967Z","end":"2026-07-01T07:29:42.176348Z","steps":["trace[702173950] 'read index received' (duration: 232.18455ms)","trace[702173950] 'applied index is now lower than readState.Index' (duration: 195.326µs)"],"step_count":2} {"level":"info","ts":"2026-07-01T07:29:42.176467Z","caller":"traceutil/trace.go:171","msg":"trace[273276667] transaction","detail":"{read_only:false; response_revision:1082; number_of_response:1; }","duration":"240.992995ms","start":"2026-07-01T07:29:41.935443Z","end":"2026-07-01T07:29:42.176436Z","steps":["trace[273276667] 'process raft request' (duration: 240.759438ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:29:42.176553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.564052ms","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-07-01T07:29:42.176587Z","caller":"traceutil/trace.go:171","msg":"trace[1409720754] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1082; }","duration":"232.658755ms","start":"2026-07-01T07:29:41.943918Z","end":"2026-07-01T07:29:42.176577Z","steps":["trace[1409720754] 'agreement among raft nodes before linearized reading' (duration: 232.512341ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:29:56.439657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.210047ms","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-07-01T07:29:56.43972Z","caller":"traceutil/trace.go:171","msg":"trace[1127582290] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1179; }","duration":"178.320481ms","start":"2026-07-01T07:29:56.261388Z","end":"2026-07-01T07:29:56.439708Z","steps":["trace[1127582290] 'range keys from in-memory index tree' (duration: 177.9538ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:29:56.692739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.144945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.90\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-07-01T07:29:56.692813Z","caller":"traceutil/trace.go:171","msg":"trace[718241713] range","detail":"{range_begin:/registry/masterleases/199.19.213.90; range_end:; response_count:1; response_revision:1180; }","duration":"128.269399ms","start":"2026-07-01T07:29:56.564525Z","end":"2026-07-01T07:29:56.692795Z","steps":["trace[718241713] 'range keys from in-memory index tree' (duration: 127.891047ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:29:58.939981Z","caller":"traceutil/trace.go:171","msg":"trace[919375917] linearizableReadLoop","detail":"{readStateIndex:1233; appliedIndex:1232; }","duration":"493.091886ms","start":"2026-07-01T07:29:58.446857Z","end":"2026-07-01T07:29:58.939948Z","steps":["trace[919375917] 'read index received' (duration: 492.87922ms)","trace[919375917] 'applied index is now lower than readState.Index' (duration: 211.856µs)"],"step_count":2} {"level":"info","ts":"2026-07-01T07:29:58.940236Z","caller":"traceutil/trace.go:171","msg":"trace[46343548] transaction","detail":"{read_only:false; response_revision:1187; number_of_response:1; }","duration":"643.533198ms","start":"2026-07-01T07:29:58.296691Z","end":"2026-07-01T07:29:58.940225Z","steps":["trace[46343548] 'process raft request' (duration: 643.109385ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:29:58.940367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:29:58.296673Z","time spent":"643.60148ms","remote":"127.0.0.1:55784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T07:29:58.940665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.802669ms","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-07-01T07:29:58.940698Z","caller":"traceutil/trace.go:171","msg":"trace[1965205438] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1187; }","duration":"493.867371ms","start":"2026-07-01T07:29:58.446821Z","end":"2026-07-01T07:29:58.940688Z","steps":["trace[1965205438] 'agreement among raft nodes before linearized reading' (duration: 493.752618ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:29:58.940729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:29:58.446801Z","time spent":"493.921952ms","remote":"127.0.0.1:55784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-01T07:30:11.176723Z","caller":"traceutil/trace.go:171","msg":"trace[913933599] transaction","detail":"{read_only:false; response_revision:1227; number_of_response:1; }","duration":"189.336394ms","start":"2026-07-01T07:30:10.987367Z","end":"2026-07-01T07:30:11.176704Z","steps":["trace[913933599] 'process raft request' (duration: 189.2321ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:30:15.910125Z","caller":"traceutil/trace.go:171","msg":"trace[956861880] linearizableReadLoop","detail":"{readStateIndex:1312; appliedIndex:1311; }","duration":"113.660104ms","start":"2026-07-01T07:30:15.796439Z","end":"2026-07-01T07:30:15.910099Z","steps":["trace[956861880] 'read index received' (duration: 73.520322ms)","trace[956861880] 'applied index is now lower than readState.Index' (duration: 40.138752ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T07:30:15.910299Z","caller":"traceutil/trace.go:171","msg":"trace[1607180569] transaction","detail":"{read_only:false; response_revision:1263; number_of_response:1; }","duration":"178.865587ms","start":"2026-07-01T07:30:15.731409Z","end":"2026-07-01T07:30:15.910275Z","steps":["trace[1607180569] 'process raft request' (duration: 138.613172ms)","trace[1607180569] 'compare' (duration: 39.946586ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T07:30:15.910341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.885661ms","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-07-01T07:30:15.910382Z","caller":"traceutil/trace.go:171","msg":"trace[218178332] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1263; }","duration":"113.968903ms","start":"2026-07-01T07:30:15.796402Z","end":"2026-07-01T07:30:15.910371Z","steps":["trace[218178332] 'agreement among raft nodes before linearized reading' (duration: 113.798818ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:30:16.795507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"575.536709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T07:30:16.795575Z","caller":"traceutil/trace.go:171","msg":"trace[876718443] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1264; }","duration":"575.652402ms","start":"2026-07-01T07:30:16.219906Z","end":"2026-07-01T07:30:16.795559Z","steps":["trace[876718443] 'range keys from in-memory index tree' (duration: 575.434736ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:30:16.795603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:30:16.219888Z","time spent":"575.709744ms","remote":"127.0.0.1:55530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-01T07:30:16.813384Z","caller":"traceutil/trace.go:171","msg":"trace[765551068] linearizableReadLoop","detail":"{readStateIndex:1314; appliedIndex:1313; }","duration":"247.836359ms","start":"2026-07-01T07:30:16.565527Z","end":"2026-07-01T07:30:16.813363Z","steps":["trace[765551068] 'read index received' (duration: 247.713865ms)","trace[765551068] 'applied index is now lower than readState.Index' (duration: 121.184µs)"],"step_count":2} {"level":"info","ts":"2026-07-01T07:30:16.813445Z","caller":"traceutil/trace.go:171","msg":"trace[693240692] transaction","detail":"{read_only:false; response_revision:1265; number_of_response:1; }","duration":"408.356924ms","start":"2026-07-01T07:30:16.405058Z","end":"2026-07-01T07:30:16.813415Z","steps":["trace[693240692] 'process raft request' (duration: 408.178989ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:30:16.813528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.863029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T07:30:16.813557Z","caller":"traceutil/trace.go:171","msg":"trace[850289101] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1265; }","duration":"188.942431ms","start":"2026-07-01T07:30:16.624606Z","end":"2026-07-01T07:30:16.813548Z","steps":["trace[850289101] 'agreement among raft nodes before linearized reading' (duration: 188.878809ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:30:16.813589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:30:16.405039Z","time spent":"408.474268ms","remote":"127.0.0.1:55784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T07:30:16.81364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.098417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.90\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-07-01T07:30:16.81371Z","caller":"traceutil/trace.go:171","msg":"trace[37357177] range","detail":"{range_begin:/registry/masterleases/199.19.213.90; range_end:; response_count:1; response_revision:1265; }","duration":"248.20232ms","start":"2026-07-01T07:30:16.565487Z","end":"2026-07-01T07:30:16.813689Z","steps":["trace[37357177] 'agreement among raft nodes before linearized reading' (duration: 247.990063ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:30:17.396589Z","caller":"traceutil/trace.go:171","msg":"trace[10266120] linearizableReadLoop","detail":"{readStateIndex:1315; appliedIndex:1314; }","duration":"352.446221ms","start":"2026-07-01T07:30:17.044119Z","end":"2026-07-01T07:30:17.396566Z","steps":["trace[10266120] 'read index received' (duration: 272.766416ms)","trace[10266120] 'applied index is now lower than readState.Index' (duration: 79.678295ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T07:30:17.396701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:30:16.815154Z","time spent":"581.544035ms","remote":"127.0.0.1:55574","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-07-01T07:30:17.396887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.714591ms","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-07-01T07:30:17.396946Z","caller":"traceutil/trace.go:171","msg":"trace[224626207] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1265; }","duration":"202.809235ms","start":"2026-07-01T07:30:17.194121Z","end":"2026-07-01T07:30:17.396931Z","steps":["trace[224626207] 'agreement among raft nodes before linearized reading' (duration: 202.626569ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:30:17.397012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.877084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T07:30:17.397056Z","caller":"traceutil/trace.go:171","msg":"trace[1317407169] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1265; }","duration":"352.954967ms","start":"2026-07-01T07:30:17.044087Z","end":"2026-07-01T07:30:17.397041Z","steps":["trace[1317407169] 'agreement among raft nodes before linearized reading' (duration: 352.873044ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:30:17.397102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:30:17.044073Z","time spent":"353.014528ms","remote":"127.0.0.1:46810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"info","ts":"2026-07-01T07:30:17.813854Z","caller":"traceutil/trace.go:171","msg":"trace[947255094] transaction","detail":"{read_only:false; response_revision:1269; number_of_response:1; }","duration":"122.467169ms","start":"2026-07-01T07:30:17.691371Z","end":"2026-07-01T07:30:17.813838Z","steps":["trace[947255094] 'process raft request' (duration: 122.069677ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:30:17.960688Z","caller":"traceutil/trace.go:171","msg":"trace[1461605264] transaction","detail":"{read_only:false; response_revision:1270; number_of_response:1; }","duration":"140.026646ms","start":"2026-07-01T07:30:17.820641Z","end":"2026-07-01T07:30:17.960668Z","steps":["trace[1461605264] 'process raft request' (duration: 139.881032ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:30:18.408317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.563791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T07:30:18.408965Z","caller":"traceutil/trace.go:171","msg":"trace[1273318835] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1272; }","duration":"189.274823ms","start":"2026-07-01T07:30:18.219668Z","end":"2026-07-01T07:30:18.408943Z","steps":["trace[1273318835] 'range keys from in-memory index tree' (duration: 188.455188ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:30:18.408394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.315717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T07:30:18.40922Z","caller":"traceutil/trace.go:171","msg":"trace[494735216] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:1272; }","duration":"302.194334ms","start":"2026-07-01T07:30:18.107007Z","end":"2026-07-01T07:30:18.409201Z","steps":["trace[494735216] 'count revisions from in-memory index tree' (duration: 301.199093ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:30:18.408483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.242373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-01T07:30:18.409284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:30:18.106986Z","time spent":"302.278467ms","remote":"127.0.0.1:46836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true "} {"level":"info","ts":"2026-07-01T07:30:18.409315Z","caller":"traceutil/trace.go:171","msg":"trace[714372953] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:1272; }","duration":"208.129061ms","start":"2026-07-01T07:30:18.201153Z","end":"2026-07-01T07:30:18.409282Z","steps":["trace[714372953] 'count revisions from in-memory index tree' (duration: 207.168121ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:30:19.388372Z","caller":"traceutil/trace.go:171","msg":"trace[1277740871] transaction","detail":"{read_only:false; response_revision:1273; number_of_response:1; }","duration":"568.457496ms","start":"2026-07-01T07:30:18.819858Z","end":"2026-07-01T07:30:19.388315Z","steps":["trace[1277740871] 'process raft request' (duration: 568.296591ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:30:19.38852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:30:18.819837Z","time spent":"568.609881ms","remote":"127.0.0.1:55784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T07:30:20.120412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.966158ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-01T07:30:20.120545Z","caller":"traceutil/trace.go:171","msg":"trace[555563881] linearizableReadLoop","detail":"{readStateIndex:1324; appliedIndex:1323; }","duration":"664.049009ms","start":"2026-07-01T07:30:19.456478Z","end":"2026-07-01T07:30:20.120527Z","steps":["trace[555563881] 'read index received' (duration: 470.036948ms)","trace[555563881] 'applied index is now lower than readState.Index' (duration: 194.010441ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T07:30:20.120733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"664.250775ms","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-07-01T07:30:20.120762Z","caller":"traceutil/trace.go:171","msg":"trace[1220097457] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1274; }","duration":"664.314947ms","start":"2026-07-01T07:30:19.456438Z","end":"2026-07-01T07:30:20.120753Z","steps":["trace[1220097457] 'agreement among raft nodes before linearized reading' (duration: 664.177822ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:30:20.120795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:30:19.456419Z","time spent":"664.368928ms","remote":"127.0.0.1:55784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-01T07:30:20.120889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.683614ms","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-07-01T07:30:20.120989Z","caller":"traceutil/trace.go:171","msg":"trace[73975688] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1274; }","duration":"357.819998ms","start":"2026-07-01T07:30:19.763147Z","end":"2026-07-01T07:30:20.120967Z","steps":["trace[73975688] 'agreement among raft nodes before linearized reading' (duration: 357.527069ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:30:20.120774Z","caller":"traceutil/trace.go:171","msg":"trace[1297420166] transaction","detail":"{read_only:false; response_revision:1274; number_of_response:1; }","duration":"777.133514ms","start":"2026-07-01T07:30:19.343457Z","end":"2026-07-01T07:30:20.12059Z","steps":["trace[1297420166] 'process raft request' (duration: 583.154054ms)","trace[1297420166] 'compare' (duration: 192.780782ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T07:30:20.121126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.40406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-01T07:30:20.121163Z","caller":"traceutil/trace.go:171","msg":"trace[1381466744] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1274; }","duration":"302.438821ms","start":"2026-07-01T07:30:19.818715Z","end":"2026-07-01T07:30:20.121154Z","steps":["trace[1381466744] 'agreement among raft nodes before linearized reading' (duration: 302.350958ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:30:20.121189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:30:19.818666Z","time spent":"302.517534ms","remote":"127.0.0.1:55784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-01T07:30:20.12119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:30:19.343426Z","time spent":"777.66544ms","remote":"127.0.0.1:55784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T07:30:20.121044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:30:19.763133Z","time spent":"357.90182ms","remote":"127.0.0.1:55784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-01T07:30:20.122147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.519117ms","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-07-01T07:30:20.122243Z","caller":"traceutil/trace.go:171","msg":"trace[929003918] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1274; }","duration":"152.64906ms","start":"2026-07-01T07:30:19.969576Z","end":"2026-07-01T07:30:20.122225Z","steps":["trace[929003918] 'agreement among raft nodes before linearized reading' (duration: 151.316369ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:30:20.546372Z","caller":"traceutil/trace.go:171","msg":"trace[1197760982] transaction","detail":"{read_only:false; response_revision:1275; number_of_response:1; }","duration":"421.741572ms","start":"2026-07-01T07:30:20.1246Z","end":"2026-07-01T07:30:20.546342Z","steps":["trace[1197760982] 'process raft request' (duration: 421.549926ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:30:20.54654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:30:20.124576Z","time spent":"421.875466ms","remote":"127.0.0.1:55784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T07:30:20.555634Z","caller":"traceutil/trace.go:171","msg":"trace[1311055434] linearizableReadLoop","detail":"{readStateIndex:1330; appliedIndex:1324; }","duration":"333.413997ms","start":"2026-07-01T07:30:20.222203Z","end":"2026-07-01T07:30:20.555617Z","steps":["trace[1311055434] 'read index received' (duration: 323.943172ms)","trace[1311055434] 'applied index is now lower than readState.Index' (duration: 9.470065ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T07:30:20.555808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.601653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T07:30:20.555839Z","caller":"traceutil/trace.go:171","msg":"trace[1670965252] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1280; }","duration":"333.691886ms","start":"2026-07-01T07:30:20.222139Z","end":"2026-07-01T07:30:20.555831Z","steps":["trace[1670965252] 'agreement among raft nodes before linearized reading' (duration: 333.624954ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:30:20.555868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:30:20.222112Z","time spent":"333.747448ms","remote":"127.0.0.1:55530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-01T07:30:20.555872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.458623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T07:30:20.555869Z","caller":"traceutil/trace.go:171","msg":"trace[357513782] transaction","detail":"{read_only:false; response_revision:1276; number_of_response:1; }","duration":"428.057809ms","start":"2026-07-01T07:30:20.127801Z","end":"2026-07-01T07:30:20.555859Z","steps":["trace[357513782] 'process raft request' (duration: 427.597384ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:30:20.555896Z","caller":"traceutil/trace.go:171","msg":"trace[206170791] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1280; }","duration":"269.523945ms","start":"2026-07-01T07:30:20.286363Z","end":"2026-07-01T07:30:20.555887Z","steps":["trace[206170791] 'agreement among raft nodes before linearized reading' (duration: 269.489674ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:30:20.555987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:30:20.127777Z","time spent":"428.136611ms","remote":"127.0.0.1:55784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T07:30:20.555966Z","caller":"traceutil/trace.go:171","msg":"trace[1347148504] transaction","detail":"{read_only:false; response_revision:1280; number_of_response:1; }","duration":"361.771311ms","start":"2026-07-01T07:30:20.19416Z","end":"2026-07-01T07:30:20.555931Z","steps":["trace[1347148504] 'process raft request' (duration: 361.42572ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:30:20.555809Z","caller":"traceutil/trace.go:171","msg":"trace[1570684731] transaction","detail":"{read_only:false; response_revision:1279; number_of_response:1; }","duration":"426.059516ms","start":"2026-07-01T07:30:20.1297Z","end":"2026-07-01T07:30:20.555759Z","steps":["trace[1570684731] 'process raft request' (duration: 425.86403ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:30:20.555976Z","caller":"traceutil/trace.go:171","msg":"trace[422002906] transaction","detail":"{read_only:false; response_revision:1277; number_of_response:1; }","duration":"428.044918ms","start":"2026-07-01T07:30:20.127874Z","end":"2026-07-01T07:30:20.555919Z","steps":["trace[422002906] 'process raft request' (duration: 427.631365ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:30:20.556094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:30:20.129691Z","time spent":"426.353966ms","remote":"127.0.0.1:55784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T07:30:20.556131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:30:20.194128Z","time spent":"361.914666ms","remote":"127.0.0.1:55604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":788,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-01T07:30:20.556039Z","caller":"traceutil/trace.go:171","msg":"trace[1614662564] transaction","detail":"{read_only:false; response_revision:1278; number_of_response:1; }","duration":"428.1263ms","start":"2026-07-01T07:30:20.127905Z","end":"2026-07-01T07:30:20.556031Z","steps":["trace[1614662564] 'process raft request' (duration: 427.628815ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:30:20.556236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:30:20.127867Z","time spent":"428.223544ms","remote":"127.0.0.1:55784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T07:30:20.556259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:30:20.127874Z","time spent":"428.362708ms","remote":"127.0.0.1:55784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T07:30:20.556005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.216763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T07:30:20.556623Z","caller":"traceutil/trace.go:171","msg":"trace[996831906] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1280; }","duration":"174.865203ms","start":"2026-07-01T07:30:20.381747Z","end":"2026-07-01T07:30:20.556613Z","steps":["trace[996831906] 'agreement among raft nodes before linearized reading' (duration: 174.236134ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:30:20.741743Z","caller":"traceutil/trace.go:171","msg":"trace[1696518949] transaction","detail":"{read_only:false; response_revision:1281; number_of_response:1; }","duration":"181.096598ms","start":"2026-07-01T07:30:20.560612Z","end":"2026-07-01T07:30:20.741709Z","steps":["trace[1696518949] 'process raft request' (duration: 180.709115ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:30:20.97363Z","caller":"traceutil/trace.go:171","msg":"trace[1206265123] transaction","detail":"{read_only:false; response_revision:1284; number_of_response:1; }","duration":"215.066437ms","start":"2026-07-01T07:30:20.758534Z","end":"2026-07-01T07:30:20.9736Z","steps":["trace[1206265123] 'process raft request' (duration: 120.869119ms)","trace[1206265123] 'compare' (duration: 93.891528ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T07:30:20.973654Z","caller":"traceutil/trace.go:171","msg":"trace[369974548] transaction","detail":"{read_only:false; response_revision:1285; number_of_response:1; }","duration":"186.968321ms","start":"2026-07-01T07:30:20.786664Z","end":"2026-07-01T07:30:20.973632Z","steps":["trace[369974548] 'process raft request' (duration: 186.876078ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:30:21.894338Z","caller":"traceutil/trace.go:171","msg":"trace[1355061108] transaction","detail":"{read_only:false; response_revision:1286; number_of_response:1; }","duration":"500.805066ms","start":"2026-07-01T07:30:21.393507Z","end":"2026-07-01T07:30:21.894312Z","steps":["trace[1355061108] 'process raft request' (duration: 500.627111ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:30:21.89454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:30:21.393489Z","time spent":"500.92952ms","remote":"127.0.0.1:55784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T07:30:22.67971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.116465ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-07-01T07:30:22.679915Z","caller":"traceutil/trace.go:171","msg":"trace[1494932995] linearizableReadLoop","detail":"{readStateIndex:1337; appliedIndex:1336; }","duration":"460.505391ms","start":"2026-07-01T07:30:22.219389Z","end":"2026-07-01T07:30:22.679895Z","steps":["trace[1494932995] 'read index received' (duration: 45.149028ms)","trace[1494932995] 'applied index is now lower than readState.Index' (duration: 415.354593ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T07:30:22.679957Z","caller":"traceutil/trace.go:171","msg":"trace[127622403] transaction","detail":"{read_only:false; response_revision:1287; number_of_response:1; }","duration":"364.810486ms","start":"2026-07-01T07:30:22.315128Z","end":"2026-07-01T07:30:22.679938Z","steps":["trace[127622403] 'process raft request' (duration: 364.690922ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:30:22.680034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.625594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T07:30:22.680053Z","caller":"traceutil/trace.go:171","msg":"trace[825158243] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1287; }","duration":"460.684756ms","start":"2026-07-01T07:30:22.219363Z","end":"2026-07-01T07:30:22.680047Z","steps":["trace[825158243] 'agreement among raft nodes before linearized reading' (duration: 460.607734ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:30:22.680053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:30:22.315107Z","time spent":"364.886669ms","remote":"127.0.0.1:55698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6104,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T07:30:22.68007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:30:22.219349Z","time spent":"460.716617ms","remote":"127.0.0.1:55530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-01T07:30:22.693301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.882504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-01T07:30:22.693346Z","caller":"traceutil/trace.go:171","msg":"trace[609613541] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1288; }","duration":"132.971837ms","start":"2026-07-01T07:30:22.560364Z","end":"2026-07-01T07:30:22.693335Z","steps":["trace[609613541] 'agreement among raft nodes before linearized reading' (duration: 132.836823ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:30:22.69333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.735478ms","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-07-01T07:30:22.693397Z","caller":"traceutil/trace.go:171","msg":"trace[2146461881] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1288; }","duration":"131.846341ms","start":"2026-07-01T07:30:22.561534Z","end":"2026-07-01T07:30:22.69338Z","steps":["trace[2146461881] 'agreement among raft nodes before linearized reading' (duration: 131.669866ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:30:22.693494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.71998ms","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-07-01T07:30:22.693529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.452852ms","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-07-01T07:30:22.69355Z","caller":"traceutil/trace.go:171","msg":"trace[539257676] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1288; }","duration":"142.822024ms","start":"2026-07-01T07:30:22.550712Z","end":"2026-07-01T07:30:22.693534Z","steps":["trace[539257676] 'agreement among raft nodes before linearized reading' (duration: 142.629808ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:30:22.693573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.548602ms","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-07-01T07:30:22.69358Z","caller":"traceutil/trace.go:171","msg":"trace[1591956625] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1288; }","duration":"133.534964ms","start":"2026-07-01T07:30:22.560029Z","end":"2026-07-01T07:30:22.693564Z","steps":["trace[1591956625] 'agreement among raft nodes before linearized reading' (duration: 133.422451ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:30:22.693602Z","caller":"traceutil/trace.go:171","msg":"trace[1847521730] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1288; }","duration":"131.597714ms","start":"2026-07-01T07:30:22.561995Z","end":"2026-07-01T07:30:22.693593Z","steps":["trace[1847521730] 'agreement among raft nodes before linearized reading' (duration: 131.488651ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:30:28.368684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.384414ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-01T07:30:28.368804Z","caller":"traceutil/trace.go:171","msg":"trace[889190461] transaction","detail":"{read_only:false; response_revision:1327; number_of_response:1; }","duration":"698.062208ms","start":"2026-07-01T07:30:27.670717Z","end":"2026-07-01T07:30:28.368779Z","steps":["trace[889190461] 'process raft request' (duration: 526.358474ms)","trace[889190461] 'compare' (duration: 171.022673ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T07:30:28.368999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:30:27.670692Z","time spent":"698.152281ms","remote":"127.0.0.1:55604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":911,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-01T07:30:28.72056Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14710901652913229639,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-01T07:30:29.22359Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14710901652913229639,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-01T07:30:29.3827Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.185549829s","expected-duration":"1s"} {"level":"warn","ts":"2026-07-01T07:30:29.728305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.023144ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T07:30:29.728457Z","caller":"traceutil/trace.go:171","msg":"trace[1325070331] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1327; }","duration":"400.186409ms","start":"2026-07-01T07:30:29.328253Z","end":"2026-07-01T07:30:29.72844Z","steps":["trace[1325070331] 'range keys from in-memory index tree' (duration: 400.009134ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:30:29.729038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.046841ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-01T07:30:29.729372Z","caller":"traceutil/trace.go:171","msg":"trace[843590781] transaction","detail":"{read_only:false; response_revision:1329; number_of_response:1; }","duration":"1.817458075s","start":"2026-07-01T07:30:27.911879Z","end":"2026-07-01T07:30:29.729337Z","steps":["trace[843590781] 'process raft request' (duration: 1.817253829s)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:30:29.729389Z","caller":"traceutil/trace.go:171","msg":"trace[299333925] linearizableReadLoop","detail":"{readStateIndex:1381; appliedIndex:1379; }","duration":"1.509059778s","start":"2026-07-01T07:30:28.220309Z","end":"2026-07-01T07:30:29.729368Z","steps":["trace[299333925] 'read index received' (duration: 1.162514531s)","trace[299333925] 'applied index is now lower than readState.Index' (duration: 346.542467ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T07:30:29.72967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.509359787s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T07:30:29.729719Z","caller":"traceutil/trace.go:171","msg":"trace[1063698293] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1330; }","duration":"1.50945565s","start":"2026-07-01T07:30:28.220254Z","end":"2026-07-01T07:30:29.729709Z","steps":["trace[1063698293] 'agreement among raft nodes before linearized reading' (duration: 1.509174321s)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:30:29.729759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:30:28.220225Z","time spent":"1.509527362s","remote":"127.0.0.1:55530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-01T07:30:29.729767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"968.17427ms","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-07-01T07:30:29.729824Z","caller":"traceutil/trace.go:171","msg":"trace[2106765496] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1330; }","duration":"968.268994ms","start":"2026-07-01T07:30:28.76154Z","end":"2026-07-01T07:30:29.729809Z","steps":["trace[2106765496] 'agreement among raft nodes before linearized reading' (duration: 968.114689ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:30:29.729905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:30:28.761519Z","time spent":"968.363816ms","remote":"127.0.0.1:55784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-01T07:30:29.730138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"811.377972ms","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":"warn","ts":"2026-07-01T07:30:29.730184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"964.109524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-01T07:30:29.730205Z","caller":"traceutil/trace.go:171","msg":"trace[1037433861] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1330; }","duration":"811.490175ms","start":"2026-07-01T07:30:28.918692Z","end":"2026-07-01T07:30:29.730182Z","steps":["trace[1037433861] 'agreement among raft nodes before linearized reading' (duration: 811.336131ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:30:29.730229Z","caller":"traceutil/trace.go:171","msg":"trace[2128196694] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1330; }","duration":"964.192707ms","start":"2026-07-01T07:30:28.766024Z","end":"2026-07-01T07:30:29.730217Z","steps":["trace[2128196694] 'agreement among raft nodes before linearized reading' (duration: 964.081183ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:30:29.73026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:30:28.765992Z","time spent":"964.260629ms","remote":"127.0.0.1:55784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-01T07:30:29.730303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"958.947772ms","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-07-01T07:30:29.730319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.271682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T07:30:29.730356Z","caller":"traceutil/trace.go:171","msg":"trace[1313399539] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:0; response_revision:1330; }","duration":"230.351684ms","start":"2026-07-01T07:30:29.499995Z","end":"2026-07-01T07:30:29.730347Z","steps":["trace[1313399539] 'agreement among raft nodes before linearized reading' (duration: 230.298432ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:30:29.730369Z","caller":"traceutil/trace.go:171","msg":"trace[1954506543] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1330; }","duration":"959.010554ms","start":"2026-07-01T07:30:28.771324Z","end":"2026-07-01T07:30:29.730335Z","steps":["trace[1954506543] 'agreement among raft nodes before linearized reading' (duration: 958.770217ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:30:29.730438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:30:28.771288Z","time spent":"959.140818ms","remote":"127.0.0.1:55784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-01T07:30:29.73047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"717.454433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-01T07:30:29.730258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:30:28.918665Z","time spent":"811.582798ms","remote":"127.0.0.1:55784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-07-01T07:30:29.730517Z","caller":"traceutil/trace.go:171","msg":"trace[2110152671] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:1330; }","duration":"717.530305ms","start":"2026-07-01T07:30:29.012975Z","end":"2026-07-01T07:30:29.730505Z","steps":["trace[2110152671] 'agreement among raft nodes before linearized reading' (duration: 717.463223ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:30:29.730562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:30:29.01296Z","time spent":"717.586258ms","remote":"127.0.0.1:46734","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true "} {"level":"warn","ts":"2026-07-01T07:30:29.730698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"955.536986ms","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-07-01T07:30:29.730743Z","caller":"traceutil/trace.go:171","msg":"trace[756087259] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1330; }","duration":"955.602209ms","start":"2026-07-01T07:30:28.775129Z","end":"2026-07-01T07:30:29.730731Z","steps":["trace[756087259] 'agreement among raft nodes before linearized reading' (duration: 955.497695ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:30:29.73078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:30:28.775117Z","time spent":"955.65557ms","remote":"127.0.0.1:55784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-01T07:30:29.730931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"954.70359ms","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-07-01T07:30:29.730981Z","caller":"traceutil/trace.go:171","msg":"trace[1072923058] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1330; }","duration":"954.755072ms","start":"2026-07-01T07:30:28.776218Z","end":"2026-07-01T07:30:29.730973Z","steps":["trace[1072923058] 'agreement among raft nodes before linearized reading' (duration: 954.658559ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:30:29.731017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:30:28.776147Z","time spent":"954.863725ms","remote":"127.0.0.1:55784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-01T07:30:29.731191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:30:27.91186Z","time spent":"1.817589039s","remote":"127.0.0.1:55784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T07:30:29.751297Z","caller":"traceutil/trace.go:171","msg":"trace[76968556] transaction","detail":"{read_only:false; response_revision:1328; number_of_response:1; }","duration":"2.053402582s","start":"2026-07-01T07:30:27.675962Z","end":"2026-07-01T07:30:29.729364Z","steps":["trace[76968556] 'process raft request' (duration: 1.706944728s)","trace[76968556] 'compare' (duration: 345.003379ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T07:30:29.751299Z","caller":"traceutil/trace.go:171","msg":"trace[843488721] transaction","detail":"{read_only:false; response_revision:1330; number_of_response:1; }","duration":"339.066644ms","start":"2026-07-01T07:30:29.390234Z","end":"2026-07-01T07:30:29.729301Z","steps":["trace[843488721] 'process raft request' (duration: 339.016892ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:30:29.751382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:30:27.675937Z","time spent":"2.075404348s","remote":"127.0.0.1:55784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":511,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T07:30:29.751402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T07:30:29.3902Z","time spent":"361.149772ms","remote":"127.0.0.1:55604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":806,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-01T07:31:30.428765Z","caller":"traceutil/trace.go:171","msg":"trace[828044093] transaction","detail":"{read_only:false; response_revision:1620; number_of_response:1; }","duration":"104.391272ms","start":"2026-07-01T07:31:30.324349Z","end":"2026-07-01T07:31:30.428741Z","steps":["trace[828044093] 'process raft request' (duration: 104.226737ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:31:30.432879Z","caller":"traceutil/trace.go:171","msg":"trace[1895353459] linearizableReadLoop","detail":"{readStateIndex:1685; appliedIndex:1683; }","duration":"106.406256ms","start":"2026-07-01T07:31:30.326458Z","end":"2026-07-01T07:31:30.432865Z","steps":["trace[1895353459] 'read index received' (duration: 102.078299ms)","trace[1895353459] 'applied index is now lower than readState.Index' (duration: 4.326777ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T07:31:30.432972Z","caller":"traceutil/trace.go:171","msg":"trace[1728390719] transaction","detail":"{read_only:false; response_revision:1621; number_of_response:1; }","duration":"107.4664ms","start":"2026-07-01T07:31:30.325469Z","end":"2026-07-01T07:31:30.432936Z","steps":["trace[1728390719] 'process raft request' (duration: 107.210351ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:31:30.433069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.183399ms","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-07-01T07:31:30.433114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.641734ms","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-07-01T07:31:30.433171Z","caller":"traceutil/trace.go:171","msg":"trace[1977170800] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1621; }","duration":"106.315983ms","start":"2026-07-01T07:31:30.326843Z","end":"2026-07-01T07:31:30.433159Z","steps":["trace[1977170800] 'agreement among raft nodes before linearized reading' (duration: 106.121477ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:31:30.4332Z","caller":"traceutil/trace.go:171","msg":"trace[1175386784] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1621; }","duration":"106.765627ms","start":"2026-07-01T07:31:30.326414Z","end":"2026-07-01T07:31:30.43318Z","steps":["trace[1175386784] 'agreement among raft nodes before linearized reading' (duration: 106.54534ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:31:30.433179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.03138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-01T07:31:30.433268Z","caller":"traceutil/trace.go:171","msg":"trace[785235209] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1621; }","duration":"104.142793ms","start":"2026-07-01T07:31:30.329101Z","end":"2026-07-01T07:31:30.433244Z","steps":["trace[785235209] 'agreement among raft nodes before linearized reading' (duration: 103.910486ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:34:17.540188Z","caller":"traceutil/trace.go:171","msg":"trace[1476606244] linearizableReadLoop","detail":"{readStateIndex:2513; appliedIndex:2512; }","duration":"213.832525ms","start":"2026-07-01T07:34:17.326332Z","end":"2026-07-01T07:34:17.540164Z","steps":["trace[1476606244] 'read index received' (duration: 213.630398ms)","trace[1476606244] 'applied index is now lower than readState.Index' (duration: 201.257µs)"],"step_count":2} {"level":"info","ts":"2026-07-01T07:34:17.540315Z","caller":"traceutil/trace.go:171","msg":"trace[556159154] transaction","detail":"{read_only:false; response_revision:2414; number_of_response:1; }","duration":"286.361266ms","start":"2026-07-01T07:34:17.253943Z","end":"2026-07-01T07:34:17.540304Z","steps":["trace[556159154] 'process raft request' (duration: 286.090198ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:34:17.540668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.276679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-07-01T07:34:17.5407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.866952ms","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-07-01T07:34:17.540759Z","caller":"traceutil/trace.go:171","msg":"trace[1746519974] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2414; }","duration":"214.382273ms","start":"2026-07-01T07:34:17.326355Z","end":"2026-07-01T07:34:17.540738Z","steps":["trace[1746519974] 'agreement among raft nodes before linearized reading' (duration: 214.159986ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:34:17.540782Z","caller":"traceutil/trace.go:171","msg":"trace[1579127558] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2414; }","duration":"211.980986ms","start":"2026-07-01T07:34:17.328781Z","end":"2026-07-01T07:34:17.540762Z","steps":["trace[1579127558] 'agreement among raft nodes before linearized reading' (duration: 211.752139ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:34:17.540808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.34375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-07-01T07:34:17.540895Z","caller":"traceutil/trace.go:171","msg":"trace[1246381375] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2414; }","duration":"213.495515ms","start":"2026-07-01T07:34:17.327381Z","end":"2026-07-01T07:34:17.540876Z","steps":["trace[1246381375] 'agreement among raft nodes before linearized reading' (duration: 213.218056ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T07:34:17.541242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.899859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-07-01T07:34:17.541283Z","caller":"traceutil/trace.go:171","msg":"trace[1553169263] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2414; }","duration":"214.969781ms","start":"2026-07-01T07:34:17.326302Z","end":"2026-07-01T07:34:17.541272Z","steps":["trace[1553169263] 'agreement among raft nodes before linearized reading' (duration: 214.108964ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:34:17.751979Z","caller":"traceutil/trace.go:171","msg":"trace[1902603696] transaction","detail":"{read_only:false; response_revision:2415; number_of_response:1; }","duration":"206.907492ms","start":"2026-07-01T07:34:17.545039Z","end":"2026-07-01T07:34:17.751947Z","steps":["trace[1902603696] 'process raft request' (duration: 195.991683ms)","trace[1902603696] 'compare' (duration: 10.647711ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T07:34:17.768411Z","caller":"traceutil/trace.go:171","msg":"trace[2044726852] transaction","detail":"{read_only:false; response_revision:2416; number_of_response:1; }","duration":"223.202744ms","start":"2026-07-01T07:34:17.545176Z","end":"2026-07-01T07:34:17.768379Z","steps":["trace[2044726852] 'process raft request' (duration: 223.027279ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:34:17.768816Z","caller":"traceutil/trace.go:171","msg":"trace[2004531730] transaction","detail":"{read_only:false; response_revision:2417; number_of_response:1; }","duration":"222.919615ms","start":"2026-07-01T07:34:17.545883Z","end":"2026-07-01T07:34:17.768803Z","steps":["trace[2004531730] 'process raft request' (duration: 222.422609ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T07:34:17.768927Z","caller":"traceutil/trace.go:171","msg":"trace[448841814] transaction","detail":"{read_only:false; response_revision:2418; number_of_response:1; }","duration":"222.747259ms","start":"2026-07-01T07:34:17.546162Z","end":"2026-07-01T07:34:17.768909Z","steps":["trace[448841814] 'process raft request' (duration: 222.500751ms)"],"step_count":1}