{"level":"warn","ts":"2026-06-02T06:49:23.313792Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-02T06:49:23.31387Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.54: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.54:2380","--initial-cluster=instance=https://199.19.213.54:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.54:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.54: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-06-02T06:49:23.31393Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-02T06:49:23.313938Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.54:2380"]} {"level":"info","ts":"2026-06-02T06:49:23.313957Z","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-06-02T06:49:23.314582Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.54:2379"]} {"level":"info","ts":"2026-06-02T06:49:23.314688Z","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.54:2380"],"listen-peer-urls":["https://199.19.213.54:2380"],"advertise-client-urls":["https://199.19.213.54:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.54:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.54: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-06-02T06:49:23.326026Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"10.157567ms"} {"level":"info","ts":"2026-06-02T06:49:23.3344Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"193b4dda7792855b","cluster-id":"dd6702a2f79ce697"} {"level":"info","ts":"2026-06-02T06:49:23.334487Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"193b4dda7792855b switched to configuration voters=()"} {"level":"info","ts":"2026-06-02T06:49:23.334514Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"193b4dda7792855b became follower at term 0"} {"level":"info","ts":"2026-06-02T06:49:23.334528Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 193b4dda7792855b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-02T06:49:23.33454Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"193b4dda7792855b became follower at term 1"} {"level":"info","ts":"2026-06-02T06:49:23.334579Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"193b4dda7792855b switched to configuration voters=(1818132475278427483)"} {"level":"warn","ts":"2026-06-02T06:49:23.342832Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-02T06:49:23.344526Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-02T06:49:23.346095Z","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-06-02T06:49:23.350018Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"193b4dda7792855b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-02T06:49:23.350245Z","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-06-02T06:49:23.35122Z","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-06-02T06:49:23.351252Z","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-06-02T06:49:23.351525Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"193b4dda7792855b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-02T06:49:23.353197Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"193b4dda7792855b switched to configuration voters=(1818132475278427483)"} {"level":"info","ts":"2026-06-02T06:49:23.353339Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"dd6702a2f79ce697","local-member-id":"193b4dda7792855b","added-peer-id":"193b4dda7792855b","added-peer-peer-urls":["https://199.19.213.54:2380"]} {"level":"info","ts":"2026-06-02T06:49:23.354363Z","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-06-02T06:49:23.354536Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.54:2380"} {"level":"info","ts":"2026-06-02T06:49:23.354573Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.54:2380"} {"level":"info","ts":"2026-06-02T06:49:23.354717Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"193b4dda7792855b","initial-advertise-peer-urls":["https://199.19.213.54:2380"],"listen-peer-urls":["https://199.19.213.54:2380"],"advertise-client-urls":["https://199.19.213.54:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.54:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-02T06:49:23.354755Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-02T06:49:23.735831Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"193b4dda7792855b is starting a new election at term 1"} {"level":"info","ts":"2026-06-02T06:49:23.735902Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"193b4dda7792855b became pre-candidate at term 1"} {"level":"info","ts":"2026-06-02T06:49:23.735921Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"193b4dda7792855b received MsgPreVoteResp from 193b4dda7792855b at term 1"} {"level":"info","ts":"2026-06-02T06:49:23.735961Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"193b4dda7792855b became candidate at term 2"} {"level":"info","ts":"2026-06-02T06:49:23.735971Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"193b4dda7792855b received MsgVoteResp from 193b4dda7792855b at term 2"} {"level":"info","ts":"2026-06-02T06:49:23.73598Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"193b4dda7792855b became leader at term 2"} {"level":"info","ts":"2026-06-02T06:49:23.73599Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 193b4dda7792855b elected leader 193b4dda7792855b at term 2"} {"level":"info","ts":"2026-06-02T06:49:23.738812Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-02T06:49:23.743769Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"193b4dda7792855b","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.54:2379]}","request-path":"/0/members/193b4dda7792855b/attributes","cluster-id":"dd6702a2f79ce697","publish-timeout":"7s"} {"level":"info","ts":"2026-06-02T06:49:23.743934Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-02T06:49:23.74405Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-02T06:49:23.745577Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"dd6702a2f79ce697","local-member-id":"193b4dda7792855b","cluster-version":"3.5"} {"level":"info","ts":"2026-06-02T06:49:23.745672Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-02T06:49:23.745679Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-02T06:49:23.745749Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-02T06:49:23.7457Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-02T06:49:23.74626Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.54:2379"} {"level":"info","ts":"2026-06-02T06:49:23.747317Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-06-02T06:49:29.814767Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9609448532986988708,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-02T06:49:31.650374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.492168919s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-02T06:49:31.650469Z","caller":"traceutil/trace.go:171","msg":"trace[105404889] linearizableReadLoop","detail":"{readStateIndex:313; appliedIndex:312; }","duration":"2.336339943s","start":"2026-06-02T06:49:29.314114Z","end":"2026-06-02T06:49:31.650454Z","steps":["trace[105404889] 'read index received' (duration: 843.312761ms)","trace[105404889] 'applied index is now lower than readState.Index' (duration: 1.493025951s)"],"step_count":2} {"level":"info","ts":"2026-06-02T06:49:31.650534Z","caller":"traceutil/trace.go:171","msg":"trace[209243370] transaction","detail":"{read_only:false; response_revision:301; number_of_response:1; }","duration":"2.354066812s","start":"2026-06-02T06:49:29.296458Z","end":"2026-06-02T06:49:31.650525Z","steps":["trace[209243370] 'process raft request' (duration: 860.931977ms)","trace[209243370] 'compare' (duration: 1.491394909s)"],"step_count":2} {"level":"warn","ts":"2026-06-02T06:49:31.650577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:49:29.296436Z","time spent":"2.354123385s","remote":"127.0.0.1:40806","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":302,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-02T06:49:31.650779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.336631933s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler\" ","response":"range_response_count:1 size:216"} {"level":"info","ts":"2026-06-02T06:49:31.650907Z","caller":"traceutil/trace.go:171","msg":"trace[1349264557] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler; range_end:; response_count:1; response_revision:301; }","duration":"2.33680211s","start":"2026-06-02T06:49:29.314088Z","end":"2026-06-02T06:49:31.65089Z","steps":["trace[1349264557] 'agreement among raft nodes before linearized reading' (duration: 2.336454727s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:49:31.650967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:49:29.314076Z","time spent":"2.336881162s","remote":"127.0.0.1:40696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":1,"response size":239,"request content":"key:\"/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler\" "} {"level":"warn","ts":"2026-06-02T06:49:32.151475Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9609448532986988714,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-02T06:49:32.676419Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9609448532986988714,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-02T06:49:33.051953Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.894516666s","expected-duration":"1s"} {"level":"warn","ts":"2026-06-02T06:49:34.371242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.998853913s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-06-02T06:49:34.371329Z","caller":"traceutil/trace.go:171","msg":"trace[222934823] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; }","duration":"4.998989038s","start":"2026-06-02T06:49:29.372321Z","end":"2026-06-02T06:49:34.37131Z","steps":["trace[222934823] 'agreement among raft nodes before linearized reading' (duration: 4.998884974s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:49:34.371486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:49:29.372302Z","time spent":"4.999173535s","remote":"127.0.0.1:40754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} 2026/06/02 06:49:34 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-06-02T06:49:34.641629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.589347956s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:5"} {"level":"info","ts":"2026-06-02T06:49:34.641867Z","caller":"traceutil/trace.go:171","msg":"trace[1165825358] transaction","detail":"{read_only:false; number_of_response:0; response_revision:301; }","duration":"4.758765122s","start":"2026-06-02T06:49:29.883081Z","end":"2026-06-02T06:49:34.641846Z","steps":["trace[1165825358] 'process raft request' (duration: 3.169140126s)","trace[1165825358] 'compare' (duration: 1.589311084s)"],"step_count":2} {"level":"warn","ts":"2026-06-02T06:49:34.642666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:49:29.883062Z","time spent":"4.75952674s","remote":"127.0.0.1:40678","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-02T06:49:34.641944Z","caller":"traceutil/trace.go:171","msg":"trace[1934250687] linearizableReadLoop","detail":"{readStateIndex:316; appliedIndex:313; }","duration":"2.991416752s","start":"2026-06-02T06:49:31.650497Z","end":"2026-06-02T06:49:34.641914Z","steps":["trace[1934250687] 'read index received' (duration: 1.401730623s)","trace[1934250687] 'applied index is now lower than readState.Index' (duration: 1.589685389s)"],"step_count":2} {"level":"info","ts":"2026-06-02T06:49:34.641978Z","caller":"traceutil/trace.go:171","msg":"trace[836185410] transaction","detail":"{read_only:false; response_revision:302; number_of_response:1; }","duration":"2.986461176s","start":"2026-06-02T06:49:31.655479Z","end":"2026-06-02T06:49:34.641941Z","steps":["trace[836185410] 'process raft request' (duration: 2.986283749s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:49:34.642889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:49:31.655461Z","time spent":"2.987358948s","remote":"127.0.0.1:40814","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":435,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-02T06:49:34.642023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.078909495s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T06:49:34.642987Z","caller":"traceutil/trace.go:171","msg":"trace[1994562795] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:302; }","duration":"5.079908461s","start":"2026-06-02T06:49:29.563069Z","end":"2026-06-02T06:49:34.642977Z","steps":["trace[1994562795] 'agreement among raft nodes before linearized reading' (duration: 5.078905145s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:49:34.643022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:49:29.563053Z","time spent":"5.079955423s","remote":"127.0.0.1:40754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-02T06:49:34.642019Z","caller":"traceutil/trace.go:171","msg":"trace[1065612410] transaction","detail":"{read_only:false; number_of_response:0; response_revision:301; }","duration":"4.758862195s","start":"2026-06-02T06:49:29.88315Z","end":"2026-06-02T06:49:34.642012Z","steps":["trace[1065612410] 'process raft request' (duration: 4.758576185s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:49:34.643198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:49:29.883146Z","time spent":"4.759998706s","remote":"127.0.0.1:40678","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-02T06:49:34.642064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.369002164s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-06-02T06:49:34.642101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.765599153s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:3466"} {"level":"warn","ts":"2026-06-02T06:49:34.642138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.828482417s","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-06-02T06:49:34.643194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.988395247s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler\" ","response":"range_response_count:1 size:216"} {"level":"info","ts":"2026-06-02T06:49:34.643296Z","caller":"traceutil/trace.go:171","msg":"trace[92504961] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:302; }","duration":"4.766823648s","start":"2026-06-02T06:49:29.876459Z","end":"2026-06-02T06:49:34.643283Z","steps":["trace[92504961] 'agreement among raft nodes before linearized reading' (duration: 4.765619095s)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:49:34.643301Z","caller":"traceutil/trace.go:171","msg":"trace[1587538782] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:302; }","duration":"4.370259149s","start":"2026-06-02T06:49:30.27302Z","end":"2026-06-02T06:49:34.643279Z","steps":["trace[1587538782] 'agreement among raft nodes before linearized reading' (duration: 4.369018614s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:49:34.643343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:49:29.876442Z","time spent":"4.76689029s","remote":"127.0.0.1:40678","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":3489,"request content":"key:\"/registry/pods/kube-system/kube-vip-instance\" "} {"level":"warn","ts":"2026-06-02T06:49:34.643375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:49:30.273Z","time spent":"4.370346072s","remote":"127.0.0.1:40754","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-06-02T06:49:34.64408Z","caller":"traceutil/trace.go:171","msg":"trace[223291339] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:302; }","duration":"4.829725302s","start":"2026-06-02T06:49:29.813607Z","end":"2026-06-02T06:49:34.643332Z","steps":["trace[223291339] 'agreement among raft nodes before linearized reading' (duration: 4.828511418s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:49:34.644146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:49:29.813582Z","time spent":"4.830534311s","remote":"127.0.0.1:40754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-06-02T06:49:34.643302Z","caller":"traceutil/trace.go:171","msg":"trace[1474271704] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler; range_end:; response_count:1; response_revision:302; }","duration":"2.988613245s","start":"2026-06-02T06:49:31.654675Z","end":"2026-06-02T06:49:34.643289Z","steps":["trace[1474271704] 'agreement among raft nodes before linearized reading' (duration: 2.988390147s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:49:34.645251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:49:31.654623Z","time spent":"2.990610888s","remote":"127.0.0.1:40696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":1,"response size":239,"request content":"key:\"/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler\" "} {"level":"warn","ts":"2026-06-02T06:49:35.150656Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9609448532986988719,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-02T06:49:35.303711Z","caller":"traceutil/trace.go:171","msg":"trace[1540651675] linearizableReadLoop","detail":"{readStateIndex:320; appliedIndex:316; }","duration":"653.82543ms","start":"2026-06-02T06:49:34.649863Z","end":"2026-06-02T06:49:35.303689Z","steps":["trace[1540651675] 'read index received' (duration: 645.584625ms)","trace[1540651675] 'applied index is now lower than readState.Index' (duration: 8.239535ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T06:49:35.303837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"653.954955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler\" ","response":"range_response_count:1 size:216"} {"level":"info","ts":"2026-06-02T06:49:35.303869Z","caller":"traceutil/trace.go:171","msg":"trace[1987520935] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler; range_end:; response_count:1; response_revision:306; }","duration":"654.022727ms","start":"2026-06-02T06:49:34.649834Z","end":"2026-06-02T06:49:35.303857Z","steps":["trace[1987520935] 'agreement among raft nodes before linearized reading' (duration: 653.938684ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:49:35.303896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:49:34.649825Z","time spent":"654.064098ms","remote":"127.0.0.1:40696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":1,"response size":239,"request content":"key:\"/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler\" "} {"level":"info","ts":"2026-06-02T06:49:35.30404Z","caller":"traceutil/trace.go:171","msg":"trace[475175441] transaction","detail":"{read_only:false; response_revision:303; number_of_response:1; }","duration":"657.088098ms","start":"2026-06-02T06:49:34.646943Z","end":"2026-06-02T06:49:35.304031Z","steps":["trace[475175441] 'process raft request' (duration: 648.504251ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:49:35.304104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:49:34.646919Z","time spent":"657.1433ms","remote":"127.0.0.1:40754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-02T06:49:35.304227Z","caller":"traceutil/trace.go:171","msg":"trace[120392790] transaction","detail":"{read_only:false; response_revision:306; number_of_response:1; }","duration":"640.795317ms","start":"2026-06-02T06:49:34.663423Z","end":"2026-06-02T06:49:35.304218Z","steps":["trace[120392790] 'process raft request' (duration: 640.225637ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:49:35.3044Z","caller":"traceutil/trace.go:171","msg":"trace[2100771013] transaction","detail":"{read_only:false; response_revision:304; number_of_response:1; }","duration":"654.351918ms","start":"2026-06-02T06:49:34.649982Z","end":"2026-06-02T06:49:35.304334Z","steps":["trace[2100771013] 'process raft request' (duration: 653.56331ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:49:35.30443Z","caller":"traceutil/trace.go:171","msg":"trace[762272711] transaction","detail":"{read_only:false; response_revision:305; number_of_response:1; }","duration":"654.38663ms","start":"2026-06-02T06:49:34.650016Z","end":"2026-06-02T06:49:35.304403Z","steps":["trace[762272711] 'process raft request' (duration: 653.599392ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:49:35.304497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:49:34.663388Z","time spent":"640.860709ms","remote":"127.0.0.1:40678","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4645,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T06:49:35.304586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:49:34.649964Z","time spent":"654.536964ms","remote":"127.0.0.1:40754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T06:49:35.305557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:49:34.650006Z","time spent":"654.518894ms","remote":"127.0.0.1:40754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T06:49:37.203653Z","caller":"traceutil/trace.go:171","msg":"trace[1631513238] transaction","detail":"{read_only:false; response_revision:339; number_of_response:1; }","duration":"201.165546ms","start":"2026-06-02T06:49:37.002474Z","end":"2026-06-02T06:49:37.20364Z","steps":["trace[1631513238] 'process raft request' (duration: 201.074233ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:49:37.203716Z","caller":"traceutil/trace.go:171","msg":"trace[1377568832] transaction","detail":"{read_only:false; response_revision:338; number_of_response:1; }","duration":"204.048769ms","start":"2026-06-02T06:49:36.999638Z","end":"2026-06-02T06:49:37.203687Z","steps":["trace[1377568832] 'process raft request' (duration: 198.452203ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:49:37.203629Z","caller":"traceutil/trace.go:171","msg":"trace[504362375] linearizableReadLoop","detail":"{readStateIndex:354; appliedIndex:352; }","duration":"193.170076ms","start":"2026-06-02T06:49:37.010437Z","end":"2026-06-02T06:49:37.203607Z","steps":["trace[504362375] 'read index received' (duration: 187.661033ms)","trace[504362375] 'applied index is now lower than readState.Index' (duration: 5.508443ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T06:49:37.204015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.029993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"warn","ts":"2026-06-02T06:49:37.204043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.529909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-06-02T06:49:37.204073Z","caller":"traceutil/trace.go:171","msg":"trace[1535103254] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:339; }","duration":"142.155347ms","start":"2026-06-02T06:49:37.061903Z","end":"2026-06-02T06:49:37.204058Z","steps":["trace[1535103254] 'agreement among raft nodes before linearized reading' (duration: 142.041303ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:49:37.204163Z","caller":"traceutil/trace.go:171","msg":"trace[1571522949] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:339; }","duration":"193.727346ms","start":"2026-06-02T06:49:37.01039Z","end":"2026-06-02T06:49:37.204117Z","steps":["trace[1571522949] 'agreement among raft nodes before linearized reading' (duration: 193.385044ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:49:38.190278Z","caller":"traceutil/trace.go:171","msg":"trace[886477005] linearizableReadLoop","detail":"{readStateIndex:372; appliedIndex:371; }","duration":"377.123567ms","start":"2026-06-02T06:49:37.813123Z","end":"2026-06-02T06:49:38.190246Z","steps":["trace[886477005] 'read index received' (duration: 376.91651ms)","trace[886477005] 'applied index is now lower than readState.Index' (duration: 206.127µs)"],"step_count":2} {"level":"info","ts":"2026-06-02T06:49:38.19036Z","caller":"traceutil/trace.go:171","msg":"trace[1217410267] transaction","detail":"{read_only:false; response_revision:357; number_of_response:1; }","duration":"417.605827ms","start":"2026-06-02T06:49:37.772733Z","end":"2026-06-02T06:49:38.190339Z","steps":["trace[1217410267] 'process raft request' (duration: 417.366219ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:49:38.190479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.341094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"warn","ts":"2026-06-02T06:49:38.190484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:49:37.772716Z","time spent":"417.69692ms","remote":"127.0.0.1:40668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4354,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T06:49:38.190538Z","caller":"traceutil/trace.go:171","msg":"trace[977467060] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:357; }","duration":"377.486249ms","start":"2026-06-02T06:49:37.813036Z","end":"2026-06-02T06:49:38.190522Z","steps":["trace[977467060] 'agreement among raft nodes before linearized reading' (duration: 377.383106ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:49:38.190535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.465346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-02T06:49:38.190583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:49:37.813019Z","time spent":"377.551441ms","remote":"127.0.0.1:40696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":230,"request content":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" "} {"level":"info","ts":"2026-06-02T06:49:38.190604Z","caller":"traceutil/trace.go:171","msg":"trace[1050019180] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:357; }","duration":"215.572341ms","start":"2026-06-02T06:49:37.975018Z","end":"2026-06-02T06:49:38.190591Z","steps":["trace[1050019180] 'agreement among raft nodes before linearized reading' (duration: 215.483707ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:49:38.190543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.074961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-06-02T06:49:38.190685Z","caller":"traceutil/trace.go:171","msg":"trace[193551535] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:357; }","duration":"329.265787ms","start":"2026-06-02T06:49:37.861407Z","end":"2026-06-02T06:49:38.190673Z","steps":["trace[193551535] 'agreement among raft nodes before linearized reading' (duration: 329.04907ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:49:38.190734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:49:37.861388Z","time spent":"329.33822ms","remote":"127.0.0.1:40696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":226,"request content":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" "} {"level":"warn","ts":"2026-06-02T06:49:38.190829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.691409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"warn","ts":"2026-06-02T06:49:38.190866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.313865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-06-02T06:49:38.190914Z","caller":"traceutil/trace.go:171","msg":"trace[999633122] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:357; }","duration":"279.399298ms","start":"2026-06-02T06:49:37.911502Z","end":"2026-06-02T06:49:38.190902Z","steps":["trace[999633122] 'agreement among raft nodes before linearized reading' (duration: 279.329666ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:49:38.19092Z","caller":"traceutil/trace.go:171","msg":"trace[1420845554] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:357; }","duration":"178.834514ms","start":"2026-06-02T06:49:38.012066Z","end":"2026-06-02T06:49:38.1909Z","steps":["trace[1420845554] 'agreement among raft nodes before linearized reading' (duration: 178.653328ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:49:38.190855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.00529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"info","ts":"2026-06-02T06:49:38.191116Z","caller":"traceutil/trace.go:171","msg":"trace[146055690] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:357; }","duration":"229.303422ms","start":"2026-06-02T06:49:37.961741Z","end":"2026-06-02T06:49:38.191045Z","steps":["trace[146055690] 'agreement among raft nodes before linearized reading' (duration: 228.807514ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:49:38.803086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.114083ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-02T06:49:38.803306Z","caller":"traceutil/trace.go:171","msg":"trace[538728329] linearizableReadLoop","detail":"{readStateIndex:378; appliedIndex:373; }","duration":"373.502109ms","start":"2026-06-02T06:49:38.429791Z","end":"2026-06-02T06:49:38.803293Z","steps":["trace[538728329] 'read index received' (duration: 7.111256ms)","trace[538728329] 'applied index is now lower than readState.Index' (duration: 366.390183ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T06:49:38.803376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.577701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T06:49:38.803398Z","caller":"traceutil/trace.go:171","msg":"trace[330379669] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:363; }","duration":"373.636263ms","start":"2026-06-02T06:49:38.429754Z","end":"2026-06-02T06:49:38.80339Z","steps":["trace[330379669] 'agreement among raft nodes before linearized reading' (duration: 373.577921ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:49:38.80342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:49:38.429736Z","time spent":"373.679295ms","remote":"127.0.0.1:40754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-06-02T06:49:38.803417Z","caller":"traceutil/trace.go:171","msg":"trace[738494008] transaction","detail":"{read_only:false; response_revision:360; number_of_response:1; }","duration":"603.672974ms","start":"2026-06-02T06:49:38.199728Z","end":"2026-06-02T06:49:38.803401Z","steps":["trace[738494008] 'process raft request' (duration: 603.454507ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:49:38.803474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:49:38.199714Z","time spent":"603.734277ms","remote":"127.0.0.1:40926","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2150,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-02T06:49:38.80345Z","caller":"traceutil/trace.go:171","msg":"trace[1361270664] transaction","detail":"{read_only:false; response_revision:359; number_of_response:1; }","duration":"603.774608ms","start":"2026-06-02T06:49:38.199642Z","end":"2026-06-02T06:49:38.803416Z","steps":["trace[1361270664] 'process raft request' (duration: 237.281082ms)","trace[1361270664] 'compare' (duration: 365.990479ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T06:49:38.80358Z","caller":"traceutil/trace.go:171","msg":"trace[1794764017] transaction","detail":"{read_only:false; response_revision:363; number_of_response:1; }","duration":"589.935178ms","start":"2026-06-02T06:49:38.21363Z","end":"2026-06-02T06:49:38.803565Z","steps":["trace[1794764017] 'process raft request' (duration: 589.638738ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:49:38.803601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:49:38.199622Z","time spent":"603.913843ms","remote":"127.0.0.1:40664","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":596,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-02T06:49:38.803641Z","caller":"traceutil/trace.go:171","msg":"trace[1332502868] transaction","detail":"{read_only:false; response_revision:361; number_of_response:1; }","duration":"603.074334ms","start":"2026-06-02T06:49:38.200553Z","end":"2026-06-02T06:49:38.803627Z","steps":["trace[1332502868] 'process raft request' (duration: 602.66261ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:49:38.803649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:49:38.213619Z","time spent":"590.00658ms","remote":"127.0.0.1:40696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":172,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-02T06:49:38.80354Z","caller":"traceutil/trace.go:171","msg":"trace[538809389] transaction","detail":"{read_only:false; response_revision:362; number_of_response:1; }","duration":"601.296502ms","start":"2026-06-02T06:49:38.202187Z","end":"2026-06-02T06:49:38.803484Z","steps":["trace[538809389] 'process raft request' (duration: 601.058534ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:49:38.803809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:49:38.20053Z","time spent":"603.174218ms","remote":"127.0.0.1:40678","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3527,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-02T06:49:38.803859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:49:38.202179Z","time spent":"601.656524ms","remote":"127.0.0.1:40614","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1723,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-02T06:49:39.152182Z","caller":"traceutil/trace.go:171","msg":"trace[530823361] transaction","detail":"{read_only:false; response_revision:364; number_of_response:1; }","duration":"344.890819ms","start":"2026-06-02T06:49:38.807264Z","end":"2026-06-02T06:49:39.152155Z","steps":["trace[530823361] 'process raft request' (duration: 331.634294ms)","trace[530823361] 'compare' (duration: 13.125121ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T06:49:39.152316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:49:38.80724Z","time spent":"345.015975ms","remote":"127.0.0.1:40754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-02T06:49:39.158024Z","caller":"traceutil/trace.go:171","msg":"trace[1432826723] transaction","detail":"{read_only:false; response_revision:367; number_of_response:1; }","duration":"347.107284ms","start":"2026-06-02T06:49:38.810902Z","end":"2026-06-02T06:49:39.158009Z","steps":["trace[1432826723] 'process raft request' (duration: 347.013201ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:49:39.158042Z","caller":"traceutil/trace.go:171","msg":"trace[151624366] transaction","detail":"{read_only:false; response_revision:368; number_of_response:1; }","duration":"347.078754ms","start":"2026-06-02T06:49:38.810945Z","end":"2026-06-02T06:49:39.158024Z","steps":["trace[151624366] 'process raft request' (duration: 346.999441ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:49:39.158063Z","caller":"traceutil/trace.go:171","msg":"trace[1708564080] linearizableReadLoop","detail":"{readStateIndex:384; appliedIndex:378; }","duration":"180.813855ms","start":"2026-06-02T06:49:38.977238Z","end":"2026-06-02T06:49:39.158052Z","steps":["trace[1708564080] 'read index received' (duration: 161.662337ms)","trace[1708564080] 'applied index is now lower than readState.Index' (duration: 19.150798ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T06:49:39.158071Z","caller":"traceutil/trace.go:171","msg":"trace[754009710] transaction","detail":"{read_only:false; response_revision:366; number_of_response:1; }","duration":"347.124875ms","start":"2026-06-02T06:49:38.810902Z","end":"2026-06-02T06:49:39.158027Z","steps":["trace[754009710] 'process raft request' (duration: 346.98006ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:49:39.158142Z","caller":"traceutil/trace.go:171","msg":"trace[954926967] transaction","detail":"{read_only:false; response_revision:369; number_of_response:1; }","duration":"347.119525ms","start":"2026-06-02T06:49:38.811016Z","end":"2026-06-02T06:49:39.158136Z","steps":["trace[954926967] 'process raft request' (duration: 346.958989ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:49:39.158142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:49:38.810872Z","time spent":"347.204748ms","remote":"127.0.0.1:40678","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3527,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-02T06:49:39.158143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:49:38.810872Z","time spent":"347.216448ms","remote":"127.0.0.1:40594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-02T06:49:39.158196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:49:38.811006Z","time spent":"347.151026ms","remote":"127.0.0.1:40678","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3378,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-02T06:49:39.158208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:49:38.810878Z","time spent":"347.25435ms","remote":"127.0.0.1:40614","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1739,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-02T06:49:39.158354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.108705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T06:49:39.15838Z","caller":"traceutil/trace.go:171","msg":"trace[1245980200] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:370; }","duration":"181.167587ms","start":"2026-06-02T06:49:38.977204Z","end":"2026-06-02T06:49:39.158371Z","steps":["trace[1245980200] 'agreement among raft nodes before linearized reading' (duration: 181.119385ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:49:39.157953Z","caller":"traceutil/trace.go:171","msg":"trace[997543338] transaction","detail":"{read_only:false; response_revision:365; number_of_response:1; }","duration":"347.041992ms","start":"2026-06-02T06:49:38.810884Z","end":"2026-06-02T06:49:39.157926Z","steps":["trace[997543338] 'process raft request' (duration: 346.904168ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:49:39.158434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:49:38.810859Z","time spent":"347.55354ms","remote":"127.0.0.1:40696","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":164,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-02T06:49:40.186188Z","caller":"traceutil/trace.go:171","msg":"trace[1805959137] transaction","detail":"{read_only:false; response_revision:399; number_of_response:1; }","duration":"125.18372ms","start":"2026-06-02T06:49:40.06097Z","end":"2026-06-02T06:49:40.186154Z","steps":["trace[1805959137] 'process raft request' (duration: 109.192547ms)","trace[1805959137] 'compare' (duration: 15.801897ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T06:49:41.814668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"483.444285ms","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-06-02T06:49:41.814757Z","caller":"traceutil/trace.go:171","msg":"trace[836472894] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:400; }","duration":"483.5932ms","start":"2026-06-02T06:49:41.331133Z","end":"2026-06-02T06:49:41.814726Z","steps":["trace[836472894] 'range keys from in-memory index tree' (duration: 483.341772ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:49:41.814823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:49:41.331114Z","time spent":"483.695654ms","remote":"127.0.0.1:40754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-02T06:49:41.814852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.712072ms","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-06-02T06:49:41.814923Z","caller":"traceutil/trace.go:171","msg":"trace[1176118422] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:400; }","duration":"477.837666ms","start":"2026-06-02T06:49:41.337069Z","end":"2026-06-02T06:49:41.814906Z","steps":["trace[1176118422] 'range keys from in-memory index tree' (duration: 477.590428ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:49:41.814957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:49:41.337048Z","time spent":"477.901839ms","remote":"127.0.0.1:40754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-02T06:49:41.815077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"475.020242ms","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-06-02T06:49:41.815122Z","caller":"traceutil/trace.go:171","msg":"trace[1674532748] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:400; }","duration":"475.097105ms","start":"2026-06-02T06:49:41.34001Z","end":"2026-06-02T06:49:41.815107Z","steps":["trace[1674532748] 'range keys from in-memory index tree' (duration: 474.922679ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:49:41.815157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:49:41.339991Z","time spent":"475.156836ms","remote":"127.0.0.1:40754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-02T06:49:55.492174Z","caller":"traceutil/trace.go:171","msg":"trace[1039506363] transaction","detail":"{read_only:false; response_revision:462; number_of_response:1; }","duration":"211.399837ms","start":"2026-06-02T06:49:55.280752Z","end":"2026-06-02T06:49:55.492151Z","steps":["trace[1039506363] 'process raft request' (duration: 211.270613ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:50:04.494778Z","caller":"traceutil/trace.go:171","msg":"trace[1758006849] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"433.053545ms","start":"2026-06-02T06:50:04.061659Z","end":"2026-06-02T06:50:04.494713Z","steps":["trace[1758006849] 'process raft request' (duration: 429.396068ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:50:04.495051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:50:04.061642Z","time spent":"433.22536ms","remote":"127.0.0.1:40754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T06:50:04.495389Z","caller":"traceutil/trace.go:171","msg":"trace[976849027] linearizableReadLoop","detail":"{readStateIndex:507; appliedIndex:505; }","duration":"360.140805ms","start":"2026-06-02T06:50:04.135225Z","end":"2026-06-02T06:50:04.495365Z","steps":["trace[976849027] 'read index received' (duration: 355.978065ms)","trace[976849027] 'applied index is now lower than readState.Index' (duration: 4.16159ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T06:50:04.495468Z","caller":"traceutil/trace.go:171","msg":"trace[1330077925] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"433.26971ms","start":"2026-06-02T06:50:04.062156Z","end":"2026-06-02T06:50:04.495426Z","steps":["trace[1330077925] 'process raft request' (duration: 433.071635ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:50:04.495527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.27581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T06:50:04.495602Z","caller":"traceutil/trace.go:171","msg":"trace[900095356] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:484; }","duration":"360.388232ms","start":"2026-06-02T06:50:04.135186Z","end":"2026-06-02T06:50:04.495574Z","steps":["trace[900095356] 'agreement among raft nodes before linearized reading' (duration: 360.271259ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:50:04.495655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:50:04.135161Z","time spent":"360.485075ms","remote":"127.0.0.1:40530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-02T06:50:04.495731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:50:04.062115Z","time spent":"433.476675ms","remote":"127.0.0.1:40754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T06:50:10.156468Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9609448532986989216,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-02T06:50:10.423672Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.246496163s","expected-duration":"1s"} {"level":"info","ts":"2026-06-02T06:50:10.423955Z","caller":"traceutil/trace.go:171","msg":"trace[1239576976] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"1.246853694s","start":"2026-06-02T06:50:09.177079Z","end":"2026-06-02T06:50:10.423933Z","steps":["trace[1239576976] 'process raft request' (duration: 1.246760261s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:50:10.424098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:50:09.177058Z","time spent":"1.246952597s","remote":"127.0.0.1:40564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-02T06:50:10.436804Z","caller":"traceutil/trace.go:171","msg":"trace[40125126] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"1.247897046s","start":"2026-06-02T06:50:09.188837Z","end":"2026-06-02T06:50:10.436734Z","steps":["trace[40125126] 'process raft request' (duration: 1.247565648s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:50:10.436943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:50:09.188815Z","time spent":"1.248074671s","remote":"127.0.0.1:40564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-02T06:50:10.436942Z","caller":"traceutil/trace.go:171","msg":"trace[1211065941] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"1.24572024s","start":"2026-06-02T06:50:09.191148Z","end":"2026-06-02T06:50:10.436868Z","steps":["trace[1211065941] 'process raft request' (duration: 1.24528395s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:50:10.437148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:50:09.191127Z","time spent":"1.245924657s","remote":"127.0.0.1:40564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-02T06:50:10.439428Z","caller":"traceutil/trace.go:171","msg":"trace[1453825607] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"1.247430374s","start":"2026-06-02T06:50:09.191921Z","end":"2026-06-02T06:50:10.439351Z","steps":["trace[1453825607] 'process raft request' (duration: 1.245123686s)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:50:10.439433Z","caller":"traceutil/trace.go:171","msg":"trace[1008886826] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"1.245458695s","start":"2026-06-02T06:50:09.193918Z","end":"2026-06-02T06:50:10.439377Z","steps":["trace[1008886826] 'process raft request' (duration: 1.244091251s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:50:10.440391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:50:09.193901Z","time spent":"1.246457249s","remote":"127.0.0.1:40564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-02T06:50:10.439495Z","caller":"traceutil/trace.go:171","msg":"trace[759322275] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"1.256188396s","start":"2026-06-02T06:50:09.180477Z","end":"2026-06-02T06:50:10.436665Z","steps":["trace[759322275] 'process raft request' (duration: 1.255843588s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:50:10.440555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:50:09.180457Z","time spent":"1.260075224s","remote":"127.0.0.1:40564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-02T06:50:10.440635Z","caller":"traceutil/trace.go:171","msg":"trace[1987423981] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"1.246447549s","start":"2026-06-02T06:50:09.194161Z","end":"2026-06-02T06:50:10.440609Z","steps":["trace[1987423981] 'process raft request' (duration: 1.246165971s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:50:10.440631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:50:09.191911Z","time spent":"1.247573637s","remote":"127.0.0.1:40564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-02T06:50:10.440648Z","caller":"traceutil/trace.go:171","msg":"trace[2122700193] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"1.246120501s","start":"2026-06-02T06:50:09.194511Z","end":"2026-06-02T06:50:10.440631Z","steps":["trace[2122700193] 'process raft request' (duration: 1.245956186s)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:50:10.440703Z","caller":"traceutil/trace.go:171","msg":"trace[1004043676] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"1.234164607s","start":"2026-06-02T06:50:09.206523Z","end":"2026-06-02T06:50:10.440688Z","steps":["trace[1004043676] 'process raft request' (duration: 1.233998652s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:50:10.440716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:50:09.194502Z","time spent":"1.246183532s","remote":"127.0.0.1:40564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-02T06:50:10.440797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:50:09.206504Z","time spent":"1.234234709s","remote":"127.0.0.1:40564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-02T06:50:10.44083Z","caller":"traceutil/trace.go:171","msg":"trace[893857175] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"1.233740357s","start":"2026-06-02T06:50:09.207066Z","end":"2026-06-02T06:50:10.440806Z","steps":["trace[893857175] 'process raft request' (duration: 1.233528602s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:50:10.44071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:50:09.194152Z","time spent":"1.246525961s","remote":"127.0.0.1:40564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-02T06:50:10.440881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:50:09.207057Z","time spent":"1.233800988s","remote":"127.0.0.1:40564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-02T06:50:10.440949Z","caller":"traceutil/trace.go:171","msg":"trace[1684378385] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"1.089929947s","start":"2026-06-02T06:50:09.351012Z","end":"2026-06-02T06:50:10.440942Z","steps":["trace[1684378385] 'process raft request' (duration: 1.089812505s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:50:10.440982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:50:09.350991Z","time spent":"1.089975838s","remote":"127.0.0.1:40564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-02T06:50:10.441022Z","caller":"traceutil/trace.go:171","msg":"trace[1959952459] linearizableReadLoop","detail":"{readStateIndex:566; appliedIndex:553; }","duration":"785.043561ms","start":"2026-06-02T06:50:09.655952Z","end":"2026-06-02T06:50:10.440995Z","steps":["trace[1959952459] 'read index received' (duration: 767.955731ms)","trace[1959952459] 'applied index is now lower than readState.Index' (duration: 17.08603ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T06:50:10.441031Z","caller":"traceutil/trace.go:171","msg":"trace[1393873141] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"1.215542724s","start":"2026-06-02T06:50:09.225483Z","end":"2026-06-02T06:50:10.441025Z","steps":["trace[1393873141] 'process raft request' (duration: 1.215236417s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:50:10.441062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:50:09.225464Z","time spent":"1.215586786s","remote":"127.0.0.1:40564","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-02T06:50:10.441186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"785.243066ms","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-06-02T06:50:10.441212Z","caller":"traceutil/trace.go:171","msg":"trace[62898605] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:544; }","duration":"785.321838ms","start":"2026-06-02T06:50:09.655883Z","end":"2026-06-02T06:50:10.441205Z","steps":["trace[62898605] 'agreement among raft nodes before linearized reading' (duration: 785.227306ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:50:10.441231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:50:09.655856Z","time spent":"785.370989ms","remote":"127.0.0.1:40754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-02T06:50:10.44125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.728501ms","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-06-02T06:50:10.44127Z","caller":"traceutil/trace.go:171","msg":"trace[1569679499] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:544; }","duration":"327.774671ms","start":"2026-06-02T06:50:10.113489Z","end":"2026-06-02T06:50:10.441264Z","steps":["trace[1569679499] 'agreement among raft nodes before linearized reading' (duration: 327.71987ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:50:10.441295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:50:10.113472Z","time spent":"327.818523ms","remote":"127.0.0.1:40754","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-06-02T06:50:10.44132Z","caller":"traceutil/trace.go:171","msg":"trace[1235031259] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"309.995636ms","start":"2026-06-02T06:50:10.131318Z","end":"2026-06-02T06:50:10.441314Z","steps":["trace[1235031259] 'process raft request' (duration: 309.561865ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:50:10.441365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:50:10.131296Z","time spent":"310.044546ms","remote":"127.0.0.1:40754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T06:50:10.441186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.431519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T06:50:10.441418Z","caller":"traceutil/trace.go:171","msg":"trace[1749768314] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:544; }","duration":"463.698366ms","start":"2026-06-02T06:50:09.977713Z","end":"2026-06-02T06:50:10.441411Z","steps":["trace[1749768314] 'agreement among raft nodes before linearized reading' (duration: 463.45289ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:50:10.441434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:50:09.977691Z","time spent":"463.739167ms","remote":"127.0.0.1:40530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-02T06:50:11.232293Z","caller":"traceutil/trace.go:171","msg":"trace[543677357] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"189.79584ms","start":"2026-06-02T06:50:11.042466Z","end":"2026-06-02T06:50:11.232262Z","steps":["trace[543677357] 'process raft request' (duration: 97.353676ms)","trace[543677357] 'compare' (duration: 92.275289ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T06:50:11.236786Z","caller":"traceutil/trace.go:171","msg":"trace[1783098531] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"189.723938ms","start":"2026-06-02T06:50:11.047034Z","end":"2026-06-02T06:50:11.236758Z","steps":["trace[1783098531] 'process raft request' (duration: 188.62527ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:50:11.246682Z","caller":"traceutil/trace.go:171","msg":"trace[1106790637] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"103.427547ms","start":"2026-06-02T06:50:11.143231Z","end":"2026-06-02T06:50:11.246658Z","steps":["trace[1106790637] 'process raft request' (duration: 103.252763ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:50:16.642568Z","caller":"traceutil/trace.go:171","msg":"trace[1954670599] linearizableReadLoop","detail":"{readStateIndex:662; appliedIndex:660; }","duration":"139.820492ms","start":"2026-06-02T06:50:16.502719Z","end":"2026-06-02T06:50:16.64254Z","steps":["trace[1954670599] 'read index received' (duration: 14.170149ms)","trace[1954670599] 'applied index is now lower than readState.Index' (duration: 125.649503ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T06:50:16.643066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.318064ms","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-06-02T06:50:16.643231Z","caller":"traceutil/trace.go:171","msg":"trace[347483887] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:633; }","duration":"140.483709ms","start":"2026-06-02T06:50:16.502662Z","end":"2026-06-02T06:50:16.643145Z","steps":["trace[347483887] 'agreement among raft nodes before linearized reading' (duration: 140.053718ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:50:16.642687Z","caller":"traceutil/trace.go:171","msg":"trace[1522910984] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"146.075671ms","start":"2026-06-02T06:50:16.496536Z","end":"2026-06-02T06:50:16.642612Z","steps":["trace[1522910984] 'process raft request' (duration: 129.771563ms)","trace[1522910984] 'compare' (duration: 15.902018ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T06:50:17.331522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:50:16.833482Z","time spent":"498.03627ms","remote":"127.0.0.1:40552","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-06-02T06:50:19.66162Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.326338135s","expected-duration":"1s"} {"level":"info","ts":"2026-06-02T06:50:19.662791Z","caller":"traceutil/trace.go:171","msg":"trace[1437733100] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"2.827029548s","start":"2026-06-02T06:50:16.835731Z","end":"2026-06-02T06:50:19.66276Z","steps":["trace[1437733100] 'process raft request' (duration: 2.82626967s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:50:19.662941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:50:16.835701Z","time spent":"2.827163052s","remote":"127.0.0.1:40754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T06:50:19.663115Z","caller":"traceutil/trace.go:171","msg":"trace[756338946] linearizableReadLoop","detail":"{readStateIndex:671; appliedIndex:670; }","duration":"2.643237491s","start":"2026-06-02T06:50:17.019852Z","end":"2026-06-02T06:50:19.66309Z","steps":["trace[756338946] 'read index received' (duration: 311.654922ms)","trace[756338946] 'applied index is now lower than readState.Index' (duration: 2.331581569s)"],"step_count":2} {"level":"info","ts":"2026-06-02T06:50:19.663234Z","caller":"traceutil/trace.go:171","msg":"trace[74057271] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"2.541560289s","start":"2026-06-02T06:50:17.121664Z","end":"2026-06-02T06:50:19.663224Z","steps":["trace[74057271] 'process raft request' (duration: 2.54080208s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:50:19.66336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:50:17.121634Z","time spent":"2.541630101s","remote":"127.0.0.1:40610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036305,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T06:50:19.663403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.643544418s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-6zqmq\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-06-02T06:50:19.663441Z","caller":"traceutil/trace.go:171","msg":"trace[2004660245] range","detail":"{range_begin:/registry/pods/kube-system/cilium-6zqmq; range_end:; response_count:1; response_revision:642; }","duration":"2.64362683s","start":"2026-06-02T06:50:17.019804Z","end":"2026-06-02T06:50:19.663431Z","steps":["trace[2004660245] 'agreement among raft nodes before linearized reading' (duration: 2.643470756s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:50:19.663473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:50:17.019781Z","time spent":"2.643684751s","remote":"127.0.0.1:40678","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16984,"request content":"key:\"/registry/pods/kube-system/cilium-6zqmq\" "} {"level":"warn","ts":"2026-06-02T06:50:19.713172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.736594042s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T06:50:19.713184Z","caller":"traceutil/trace.go:171","msg":"trace[1529888275] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"2.355743288s","start":"2026-06-02T06:50:17.357376Z","end":"2026-06-02T06:50:19.713119Z","steps":["trace[1529888275] 'process raft request' (duration: 2.355590153s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:50:19.713308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.055404374s","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-06-02T06:50:19.713308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.190789485s","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-06-02T06:50:19.713336Z","caller":"traceutil/trace.go:171","msg":"trace[700013286] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:643; }","duration":"1.055476766s","start":"2026-06-02T06:50:18.657854Z","end":"2026-06-02T06:50:19.713331Z","steps":["trace[700013286] 'agreement among raft nodes before linearized reading' (duration: 1.055413414s)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:50:19.713354Z","caller":"traceutil/trace.go:171","msg":"trace[224705809] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:643; }","duration":"1.190870697s","start":"2026-06-02T06:50:18.522477Z","end":"2026-06-02T06:50:19.713347Z","steps":["trace[224705809] 'agreement among raft nodes before linearized reading' (duration: 1.190795105s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:50:19.713373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:50:18.522457Z","time spent":"1.190911238s","remote":"127.0.0.1:40754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-02T06:50:19.713373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:50:18.65783Z","time spent":"1.055534037s","remote":"127.0.0.1:40754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-02T06:50:19.713254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.052991537s","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-06-02T06:50:19.713516Z","caller":"traceutil/trace.go:171","msg":"trace[1216226787] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:643; }","duration":"1.053248073s","start":"2026-06-02T06:50:18.660257Z","end":"2026-06-02T06:50:19.713505Z","steps":["trace[1216226787] 'agreement among raft nodes before linearized reading' (duration: 1.052959416s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:50:19.713519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:50:17.35735Z","time spent":"2.355993624s","remote":"127.0.0.1:40552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T06:50:19.713552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:50:18.660208Z","time spent":"1.053336085s","remote":"127.0.0.1:40754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-06-02T06:50:19.713246Z","caller":"traceutil/trace.go:171","msg":"trace[1320573820] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:643; }","duration":"1.736674463s","start":"2026-06-02T06:50:17.97655Z","end":"2026-06-02T06:50:19.713225Z","steps":["trace[1320573820] 'agreement among raft nodes before linearized reading' (duration: 1.736511969s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:50:19.713199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.05523437s","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-06-02T06:50:19.713654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:50:17.976495Z","time spent":"1.737152314s","remote":"127.0.0.1:40530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-02T06:50:19.713678Z","caller":"traceutil/trace.go:171","msg":"trace[276285715] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:643; }","duration":"1.055753951s","start":"2026-06-02T06:50:18.657908Z","end":"2026-06-02T06:50:19.713662Z","steps":["trace[276285715] 'agreement among raft nodes before linearized reading' (duration: 1.055191427s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:50:19.713708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:50:18.657877Z","time spent":"1.055823713s","remote":"127.0.0.1:40754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-06-02T06:50:19.993584Z","caller":"traceutil/trace.go:171","msg":"trace[1970429730] linearizableReadLoop","detail":"{readStateIndex:674; appliedIndex:673; }","duration":"278.436086ms","start":"2026-06-02T06:50:19.715128Z","end":"2026-06-02T06:50:19.993564Z","steps":["trace[1970429730] 'read index received' (duration: 278.327813ms)","trace[1970429730] 'applied index is now lower than readState.Index' (duration: 107.333µs)"],"step_count":2} {"level":"info","ts":"2026-06-02T06:50:19.993666Z","caller":"traceutil/trace.go:171","msg":"trace[735174933] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"308.302138ms","start":"2026-06-02T06:50:19.685353Z","end":"2026-06-02T06:50:19.993656Z","steps":["trace[735174933] 'process raft request' (duration: 308.013311ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:50:19.993761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:50:19.685327Z","time spent":"308.362899ms","remote":"127.0.0.1:40678","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T06:50:19.994052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.916447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T06:50:19.994087Z","caller":"traceutil/trace.go:171","msg":"trace[1984747741] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:644; }","duration":"278.968227ms","start":"2026-06-02T06:50:19.715112Z","end":"2026-06-02T06:50:19.99408Z","steps":["trace[1984747741] 'agreement among raft nodes before linearized reading' (duration: 278.830814ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:50:20.493804Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9609448532986989414,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-02T06:50:20.994603Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9609448532986989414,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-02T06:50:21.495348Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9609448532986989414,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-02T06:50:21.907631Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.914173976s","expected-duration":"1s"} {"level":"warn","ts":"2026-06-02T06:50:22.001562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999968471s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-06-02T06:50:22.001635Z","caller":"traceutil/trace.go:171","msg":"trace[769392963] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000276018s","start":"2026-06-02T06:50:20.001341Z","end":"2026-06-02T06:50:22.001617Z","steps":["trace[769392963] 'agreement among raft nodes before linearized reading' (duration: 2.000182506s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:50:22.001677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:50:20.001327Z","time spent":"2.00034216s","remote":"127.0.0.1:40530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-02T06:50:23.137819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.229976433s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-02T06:50:23.138162Z","caller":"traceutil/trace.go:171","msg":"trace[14007951] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"3.418613442s","start":"2026-06-02T06:50:19.719515Z","end":"2026-06-02T06:50:23.138129Z","steps":["trace[14007951] 'process raft request' (duration: 3.418417507s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:50:23.138344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:50:19.719508Z","time spent":"3.418749985s","remote":"127.0.0.1:40754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T06:50:23.138556Z","caller":"traceutil/trace.go:171","msg":"trace[351012032] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"3.418938709s","start":"2026-06-02T06:50:19.719599Z","end":"2026-06-02T06:50:23.138538Z","steps":["trace[351012032] 'process raft request' (duration: 3.418360166s)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:50:23.138587Z","caller":"traceutil/trace.go:171","msg":"trace[1299568263] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"3.420452054s","start":"2026-06-02T06:50:19.718101Z","end":"2026-06-02T06:50:23.138554Z","steps":["trace[1299568263] 'process raft request' (duration: 2.189675363s)","trace[1299568263] 'compare' (duration: 1.229871371s)"],"step_count":2} {"level":"warn","ts":"2026-06-02T06:50:23.138665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:50:19.719564Z","time spent":"3.419035771s","remote":"127.0.0.1:40754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T06:50:23.138704Z","caller":"traceutil/trace.go:171","msg":"trace[1065091389] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"3.4203s","start":"2026-06-02T06:50:19.71826Z","end":"2026-06-02T06:50:23.13856Z","steps":["trace[1065091389] 'process raft request' (duration: 3.419632596s)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:50:23.138743Z","caller":"traceutil/trace.go:171","msg":"trace[1046826109] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"3.404196467s","start":"2026-06-02T06:50:19.734523Z","end":"2026-06-02T06:50:23.13872Z","steps":["trace[1046826109] 'process raft request' (duration: 3.403459151s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:50:23.138761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:50:19.718254Z","time spent":"3.420479415s","remote":"127.0.0.1:40754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T06:50:23.138811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:50:19.734482Z","time spent":"3.404300139s","remote":"127.0.0.1:40720","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-02T06:50:23.138674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:50:19.718091Z","time spent":"3.420524346s","remote":"127.0.0.1:40754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T06:50:23.138914Z","caller":"traceutil/trace.go:171","msg":"trace[945834344] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"2.59701667s","start":"2026-06-02T06:50:20.541832Z","end":"2026-06-02T06:50:23.138848Z","steps":["trace[945834344] 'process raft request' (duration: 2.596178491s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:50:23.139177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:50:20.541801Z","time spent":"2.597237415s","remote":"127.0.0.1:40754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T06:50:23.189156Z","caller":"traceutil/trace.go:171","msg":"trace[1031053384] linearizableReadLoop","detail":"{readStateIndex:680; appliedIndex:674; }","duration":"3.195496452s","start":"2026-06-02T06:50:19.993633Z","end":"2026-06-02T06:50:23.18913Z","steps":["trace[1031053384] 'read index received' (duration: 1.914144135s)","trace[1031053384] 'applied index is now lower than readState.Index' (duration: 1.281350637s)"],"step_count":2} {"level":"warn","ts":"2026-06-02T06:50:23.189383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.47278122s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-06-02T06:50:23.189478Z","caller":"traceutil/trace.go:171","msg":"trace[1000412373] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:650; }","duration":"3.472908773s","start":"2026-06-02T06:50:19.716548Z","end":"2026-06-02T06:50:23.189457Z","steps":["trace[1000412373] 'agreement among raft nodes before linearized reading' (duration: 3.472705279s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:50:23.189506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.187215557s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-02T06:50:23.189531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:50:19.716542Z","time spent":"3.472979985s","remote":"127.0.0.1:40664","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":444,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"info","ts":"2026-06-02T06:50:23.189574Z","caller":"traceutil/trace.go:171","msg":"trace[964912061] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:650; }","duration":"1.187323889s","start":"2026-06-02T06:50:22.002236Z","end":"2026-06-02T06:50:23.18956Z","steps":["trace[964912061] 'agreement among raft nodes before linearized reading' (duration: 1.187189456s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:50:23.189614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:50:22.002223Z","time spent":"1.187385951s","remote":"127.0.0.1:40538","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-02T06:50:23.189714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.189962933s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-6zqmq\" ","response":"range_response_count:1 size:17145"} {"level":"info","ts":"2026-06-02T06:50:23.189802Z","caller":"traceutil/trace.go:171","msg":"trace[1967052244] range","detail":"{range_begin:/registry/pods/kube-system/cilium-6zqmq; range_end:; response_count:1; response_revision:650; }","duration":"3.190063645s","start":"2026-06-02T06:50:19.999721Z","end":"2026-06-02T06:50:23.189785Z","steps":["trace[1967052244] 'agreement among raft nodes before linearized reading' (duration: 3.189659635s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:50:23.189879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:50:19.999669Z","time spent":"3.190196369s","remote":"127.0.0.1:40678","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":17168,"request content":"key:\"/registry/pods/kube-system/cilium-6zqmq\" "} {"level":"info","ts":"2026-06-02T06:50:30.187959Z","caller":"traceutil/trace.go:171","msg":"trace[962591060] linearizableReadLoop","detail":"{readStateIndex:783; appliedIndex:782; }","duration":"155.662711ms","start":"2026-06-02T06:50:30.032276Z","end":"2026-06-02T06:50:30.187938Z","steps":["trace[962591060] 'read index received' (duration: 155.483847ms)","trace[962591060] 'applied index is now lower than readState.Index' (duration: 178.044µs)"],"step_count":2} {"level":"info","ts":"2026-06-02T06:50:30.188036Z","caller":"traceutil/trace.go:171","msg":"trace[1857503138] transaction","detail":"{read_only:false; response_revision:750; number_of_response:1; }","duration":"177.029774ms","start":"2026-06-02T06:50:30.010984Z","end":"2026-06-02T06:50:30.188013Z","steps":["trace[1857503138] 'process raft request' (duration: 176.822889ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:50:30.188156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.852065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T06:50:30.1882Z","caller":"traceutil/trace.go:171","msg":"trace[1299184092] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:750; }","duration":"155.941397ms","start":"2026-06-02T06:50:30.032248Z","end":"2026-06-02T06:50:30.18819Z","steps":["trace[1299184092] 'agreement among raft nodes before linearized reading' (duration: 155.832745ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:50:30.188266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.962627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" limit:10 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T06:50:30.188328Z","caller":"traceutil/trace.go:171","msg":"trace[913039983] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:750; }","duration":"156.06536ms","start":"2026-06-02T06:50:30.032249Z","end":"2026-06-02T06:50:30.188314Z","steps":["trace[913039983] 'agreement among raft nodes before linearized reading' (duration: 155.918997ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:50:30.188216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.899576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T06:50:30.188416Z","caller":"traceutil/trace.go:171","msg":"trace[1905306401] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:750; }","duration":"156.155002ms","start":"2026-06-02T06:50:30.032249Z","end":"2026-06-02T06:50:30.188404Z","steps":["trace[1905306401] 'agreement among raft nodes before linearized reading' (duration: 155.818575ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:50:41.479116Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9609448532986989801,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-02T06:50:41.871753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.893604192s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T06:50:41.871833Z","caller":"traceutil/trace.go:171","msg":"trace[977867202] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:807; }","duration":"2.893737715s","start":"2026-06-02T06:50:38.978077Z","end":"2026-06-02T06:50:41.871815Z","steps":["trace[977867202] 'range keys from in-memory index tree' (duration: 2.893463319s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:50:41.871871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:50:38.977966Z","time spent":"2.893896178s","remote":"127.0.0.1:40530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-02T06:50:41.872027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.768713706s","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-06-02T06:50:41.872059Z","caller":"traceutil/trace.go:171","msg":"trace[528051618] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:807; }","duration":"2.768783118s","start":"2026-06-02T06:50:39.103267Z","end":"2026-06-02T06:50:41.87205Z","steps":["trace[528051618] 'range keys from in-memory index tree' (duration: 2.768558812s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:50:41.872085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:50:39.103244Z","time spent":"2.768834868s","remote":"127.0.0.1:40754","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-06-02T06:50:41.872307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.671866587s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-g7zhs\" ","response":"range_response_count:1 size:3964"} {"level":"info","ts":"2026-06-02T06:50:41.872345Z","caller":"traceutil/trace.go:171","msg":"trace[1845228383] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-g7zhs; range_end:; response_count:1; response_revision:807; }","duration":"2.671939489s","start":"2026-06-02T06:50:39.200394Z","end":"2026-06-02T06:50:41.872334Z","steps":["trace[1845228383] 'range keys from in-memory index tree' (duration: 2.671664644s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:50:41.872372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:50:39.200374Z","time spent":"2.67199176s","remote":"127.0.0.1:40678","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3987,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-g7zhs\" "} {"level":"warn","ts":"2026-06-02T06:50:41.872557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.644933214s","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-06-02T06:50:41.872591Z","caller":"traceutil/trace.go:171","msg":"trace[1420204681] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:807; }","duration":"2.644966295s","start":"2026-06-02T06:50:39.227615Z","end":"2026-06-02T06:50:41.872582Z","steps":["trace[1420204681] 'range keys from in-memory index tree' (duration: 2.644768911s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:50:41.872656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:50:39.22759Z","time spent":"2.645019936s","remote":"127.0.0.1:40754","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-06-02T06:50:41.872842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.641633449s","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-06-02T06:50:41.872875Z","caller":"traceutil/trace.go:171","msg":"trace[1903897550] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:807; }","duration":"2.64170664s","start":"2026-06-02T06:50:39.231161Z","end":"2026-06-02T06:50:41.872867Z","steps":["trace[1903897550] 'range keys from in-memory index tree' (duration: 2.641481856s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:50:41.872902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:50:39.23114Z","time spent":"2.641755932s","remote":"127.0.0.1:40754","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-06-02T06:50:41.873061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.57808549s","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-06-02T06:50:41.873093Z","caller":"traceutil/trace.go:171","msg":"trace[1749648095] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:807; }","duration":"2.578147031s","start":"2026-06-02T06:50:39.294938Z","end":"2026-06-02T06:50:41.873085Z","steps":["trace[1749648095] 'range keys from in-memory index tree' (duration: 2.577925407s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:50:41.87314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:50:39.294918Z","time spent":"2.578192872s","remote":"127.0.0.1:40754","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-06-02T06:50:41.8733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.577903726s","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-06-02T06:50:41.873331Z","caller":"traceutil/trace.go:171","msg":"trace[2100256066] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:807; }","duration":"2.577973668s","start":"2026-06-02T06:50:39.29535Z","end":"2026-06-02T06:50:41.873324Z","steps":["trace[2100256066] 'range keys from in-memory index tree' (duration: 2.577804795s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:50:41.873357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:50:39.29534Z","time spent":"2.578009638s","remote":"127.0.0.1:40754","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-06-02T06:50:41.979574Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9609448532986989801,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-02T06:50:42.14361Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.158315221s","expected-duration":"1s"} {"level":"info","ts":"2026-06-02T06:50:42.144141Z","caller":"traceutil/trace.go:171","msg":"trace[83149535] transaction","detail":"{read_only:false; response_revision:808; number_of_response:1; }","duration":"2.158911153s","start":"2026-06-02T06:50:39.985203Z","end":"2026-06-02T06:50:42.144114Z","steps":["trace[83149535] 'process raft request' (duration: 2.158687679s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:50:42.144344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:50:39.985182Z","time spent":"2.159016475s","remote":"127.0.0.1:40754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T06:50:42.324729Z","caller":"traceutil/trace.go:171","msg":"trace[990430077] transaction","detail":"{read_only:false; response_revision:809; number_of_response:1; }","duration":"445.070132ms","start":"2026-06-02T06:50:41.879634Z","end":"2026-06-02T06:50:42.324704Z","steps":["trace[990430077] 'process raft request' (duration: 363.249107ms)","trace[990430077] 'compare' (duration: 81.616381ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T06:50:42.324906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:50:41.879616Z","time spent":"445.193115ms","remote":"127.0.0.1:40754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T06:50:42.32506Z","caller":"traceutil/trace.go:171","msg":"trace[1484106047] transaction","detail":"{read_only:false; response_revision:810; number_of_response:1; }","duration":"444.237345ms","start":"2026-06-02T06:50:41.880795Z","end":"2026-06-02T06:50:42.325032Z","steps":["trace[1484106047] 'process raft request' (duration: 443.861188ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:50:42.325227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:50:41.880786Z","time spent":"444.347327ms","remote":"127.0.0.1:40754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T06:50:42.325276Z","caller":"traceutil/trace.go:171","msg":"trace[1670406906] transaction","detail":"{read_only:false; response_revision:812; number_of_response:1; }","duration":"444.116683ms","start":"2026-06-02T06:50:41.881147Z","end":"2026-06-02T06:50:42.325263Z","steps":["trace[1670406906] 'process raft request' (duration: 444.024381ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:50:42.32529Z","caller":"traceutil/trace.go:171","msg":"trace[1963348585] transaction","detail":"{read_only:false; response_revision:811; number_of_response:1; }","duration":"444.415619ms","start":"2026-06-02T06:50:41.880842Z","end":"2026-06-02T06:50:42.325257Z","steps":["trace[1963348585] 'process raft request' (duration: 444.086652ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:50:42.325352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:50:41.881123Z","time spent":"444.193965ms","remote":"127.0.0.1:40754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T06:50:42.325446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:50:41.880837Z","time spent":"444.529161ms","remote":"127.0.0.1:40754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T06:50:42.325848Z","caller":"traceutil/trace.go:171","msg":"trace[426958966] transaction","detail":"{read_only:false; response_revision:813; number_of_response:1; }","duration":"442.732277ms","start":"2026-06-02T06:50:41.883088Z","end":"2026-06-02T06:50:42.32582Z","steps":["trace[426958966] 'process raft request' (duration: 442.132865ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:50:42.325872Z","caller":"traceutil/trace.go:171","msg":"trace[790213917] transaction","detail":"{read_only:false; response_revision:814; number_of_response:1; }","duration":"436.492305ms","start":"2026-06-02T06:50:41.889365Z","end":"2026-06-02T06:50:42.325858Z","steps":["trace[790213917] 'process raft request' (duration: 436.327361ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:50:42.325975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:50:41.889354Z","time spent":"436.579977ms","remote":"127.0.0.1:40754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T06:50:42.32605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:50:41.883056Z","time spent":"442.86504ms","remote":"127.0.0.1:40678","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4024,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T06:50:42.359917Z","caller":"traceutil/trace.go:171","msg":"trace[852020417] linearizableReadLoop","detail":"{readStateIndex:851; appliedIndex:843; }","duration":"1.381532872s","start":"2026-06-02T06:50:40.978355Z","end":"2026-06-02T06:50:42.359888Z","steps":["trace[852020417] 'read index received' (duration: 1.165499616s)","trace[852020417] 'applied index is now lower than readState.Index' (duration: 216.031746ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T06:50:42.360096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.381730456s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T06:50:42.360175Z","caller":"traceutil/trace.go:171","msg":"trace[274951966] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:814; }","duration":"1.38186045s","start":"2026-06-02T06:50:40.978301Z","end":"2026-06-02T06:50:42.360161Z","steps":["trace[274951966] 'agreement among raft nodes before linearized reading' (duration: 1.381738657s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:50:42.360248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:50:40.978279Z","time spent":"1.381957531s","remote":"127.0.0.1:40538","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-02T06:50:42.360242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"640.159885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T06:50:42.360325Z","caller":"traceutil/trace.go:171","msg":"trace[1253493086] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:814; }","duration":"640.243136ms","start":"2026-06-02T06:50:41.720053Z","end":"2026-06-02T06:50:42.360296Z","steps":["trace[1253493086] 'agreement among raft nodes before linearized reading' (duration: 640.118534ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:50:42.360364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:50:41.720025Z","time spent":"640.331488ms","remote":"127.0.0.1:40914","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":28,"request content":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true "} {"level":"info","ts":"2026-06-02T06:50:43.397694Z","caller":"traceutil/trace.go:171","msg":"trace[729129991] transaction","detail":"{read_only:false; response_revision:816; number_of_response:1; }","duration":"171.440147ms","start":"2026-06-02T06:50:43.226232Z","end":"2026-06-02T06:50:43.397672Z","steps":["trace[729129991] 'process raft request' (duration: 171.288494ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:50:43.507182Z","caller":"traceutil/trace.go:171","msg":"trace[800410003] transaction","detail":"{read_only:false; response_revision:817; number_of_response:1; }","duration":"173.396326ms","start":"2026-06-02T06:50:43.333769Z","end":"2026-06-02T06:50:43.507166Z","steps":["trace[800410003] 'process raft request' (duration: 172.380877ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:50:44.440839Z","caller":"traceutil/trace.go:171","msg":"trace[1166480397] transaction","detail":"{read_only:false; response_revision:819; number_of_response:1; }","duration":"106.640718ms","start":"2026-06-02T06:50:44.334174Z","end":"2026-06-02T06:50:44.440815Z","steps":["trace[1166480397] 'process raft request' (duration: 106.493584ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:50:44.702269Z","caller":"traceutil/trace.go:171","msg":"trace[1275939102] linearizableReadLoop","detail":"{readStateIndex:861; appliedIndex:856; }","duration":"135.142031ms","start":"2026-06-02T06:50:44.567104Z","end":"2026-06-02T06:50:44.702246Z","steps":["trace[1275939102] 'read index received' (duration: 134.824605ms)","trace[1275939102] 'applied index is now lower than readState.Index' (duration: 316.226µs)"],"step_count":2} {"level":"warn","ts":"2026-06-02T06:50:44.702536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.412036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-g7zhs\" ","response":"range_response_count:1 size:2875"} {"level":"info","ts":"2026-06-02T06:50:44.702555Z","caller":"traceutil/trace.go:171","msg":"trace[796902364] transaction","detail":"{read_only:false; response_revision:822; number_of_response:1; }","duration":"367.732174ms","start":"2026-06-02T06:50:44.334792Z","end":"2026-06-02T06:50:44.702524Z","steps":["trace[796902364] 'process raft request' (duration: 367.346177ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:50:44.702584Z","caller":"traceutil/trace.go:171","msg":"trace[6281351] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-g7zhs; range_end:; response_count:1; response_revision:824; }","duration":"135.504518ms","start":"2026-06-02T06:50:44.567067Z","end":"2026-06-02T06:50:44.702571Z","steps":["trace[6281351] 'agreement among raft nodes before linearized reading' (duration: 135.276723ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:50:44.702665Z","caller":"traceutil/trace.go:171","msg":"trace[996830068] transaction","detail":"{read_only:false; response_revision:824; number_of_response:1; }","duration":"297.985542ms","start":"2026-06-02T06:50:44.404619Z","end":"2026-06-02T06:50:44.702604Z","steps":["trace[996830068] 'process raft request' (duration: 297.588575ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:50:44.702722Z","caller":"traceutil/trace.go:171","msg":"trace[1057401704] transaction","detail":"{read_only:false; response_revision:821; number_of_response:1; }","duration":"368.116791ms","start":"2026-06-02T06:50:44.334586Z","end":"2026-06-02T06:50:44.702703Z","steps":["trace[1057401704] 'process raft request' (duration: 367.51449ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:50:44.702724Z","caller":"traceutil/trace.go:171","msg":"trace[1574812948] transaction","detail":"{read_only:false; response_revision:820; number_of_response:1; }","duration":"368.427699ms","start":"2026-06-02T06:50:44.334251Z","end":"2026-06-02T06:50:44.702679Z","steps":["trace[1574812948] 'process raft request' (duration: 367.733096ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:50:44.702776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:50:44.334784Z","time spent":"367.838217ms","remote":"127.0.0.1:40754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T06:50:44.702836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:50:44.334572Z","time spent":"368.183622ms","remote":"127.0.0.1:40754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T06:50:44.702879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:50:44.334222Z","time spent":"368.583301ms","remote":"127.0.0.1:40754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T06:50:44.703026Z","caller":"traceutil/trace.go:171","msg":"trace[337689733] transaction","detail":"{read_only:false; response_revision:823; number_of_response:1; }","duration":"367.810006ms","start":"2026-06-02T06:50:44.335197Z","end":"2026-06-02T06:50:44.703007Z","steps":["trace[337689733] 'process raft request' (duration: 366.97536ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:50:44.703214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:50:44.335184Z","time spent":"367.876057ms","remote":"127.0.0.1:40754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T06:50:45.412772Z","caller":"traceutil/trace.go:171","msg":"trace[1406475186] transaction","detail":"{read_only:false; response_revision:826; number_of_response:1; }","duration":"179.490034ms","start":"2026-06-02T06:50:45.233257Z","end":"2026-06-02T06:50:45.412747Z","steps":["trace[1406475186] 'process raft request' (duration: 179.327081ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:50:45.701287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.60214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-02T06:50:45.701357Z","caller":"traceutil/trace.go:171","msg":"trace[1536868771] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:826; }","duration":"175.734423ms","start":"2026-06-02T06:50:45.525609Z","end":"2026-06-02T06:50:45.701344Z","steps":["trace[1536868771] 'count revisions from in-memory index tree' (duration: 175.454547ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:50:45.934865Z","caller":"traceutil/trace.go:171","msg":"trace[357467994] transaction","detail":"{read_only:false; response_revision:827; number_of_response:1; }","duration":"224.280364ms","start":"2026-06-02T06:50:45.710562Z","end":"2026-06-02T06:50:45.934842Z","steps":["trace[357467994] 'process raft request' (duration: 224.138302ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:50:46.092904Z","caller":"traceutil/trace.go:171","msg":"trace[2078755445] linearizableReadLoop","detail":"{readStateIndex:865; appliedIndex:864; }","duration":"132.92517ms","start":"2026-06-02T06:50:45.959947Z","end":"2026-06-02T06:50:46.092872Z","steps":["trace[2078755445] 'read index received' (duration: 54.242848ms)","trace[2078755445] 'applied index is now lower than readState.Index' (duration: 78.676022ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T06:50:46.09303Z","caller":"traceutil/trace.go:171","msg":"trace[1276318684] transaction","detail":"{read_only:false; response_revision:828; number_of_response:1; }","duration":"148.893602ms","start":"2026-06-02T06:50:45.944109Z","end":"2026-06-02T06:50:46.093003Z","steps":["trace[1276318684] 'process raft request' (duration: 70.17873ms)","trace[1276318684] 'compare' (duration: 78.442277ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T06:50:46.09305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.083792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T06:50:46.093209Z","caller":"traceutil/trace.go:171","msg":"trace[1125127149] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:828; }","duration":"133.292957ms","start":"2026-06-02T06:50:45.959905Z","end":"2026-06-02T06:50:46.093198Z","steps":["trace[1125127149] 'agreement among raft nodes before linearized reading' (duration: 133.082173ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:50:46.427506Z","caller":"traceutil/trace.go:171","msg":"trace[1043971522] linearizableReadLoop","detail":"{readStateIndex:867; appliedIndex:866; }","duration":"292.073512ms","start":"2026-06-02T06:50:46.1354Z","end":"2026-06-02T06:50:46.427474Z","steps":["trace[1043971522] 'read index received' (duration: 225.374466ms)","trace[1043971522] 'applied index is now lower than readState.Index' (duration: 66.696926ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T06:50:46.427538Z","caller":"traceutil/trace.go:171","msg":"trace[418104621] transaction","detail":"{read_only:false; response_revision:830; number_of_response:1; }","duration":"294.09728ms","start":"2026-06-02T06:50:46.133396Z","end":"2026-06-02T06:50:46.427493Z","steps":["trace[418104621] 'process raft request' (duration: 227.467844ms)","trace[418104621] 'compare' (duration: 66.458442ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T06:50:46.427709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.287266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/envoy-gateway-system/\" range_end:\"/registry/resourcequotas/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T06:50:46.427778Z","caller":"traceutil/trace.go:171","msg":"trace[1971164746] range","detail":"{range_begin:/registry/resourcequotas/envoy-gateway-system/; range_end:/registry/resourcequotas/envoy-gateway-system0; response_count:0; response_revision:830; }","duration":"292.377637ms","start":"2026-06-02T06:50:46.135385Z","end":"2026-06-02T06:50:46.427762Z","steps":["trace[1971164746] 'agreement among raft nodes before linearized reading' (duration: 292.180724ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:50:47.318703Z","caller":"traceutil/trace.go:171","msg":"trace[412712651] transaction","detail":"{read_only:false; response_revision:863; number_of_response:1; }","duration":"151.416393ms","start":"2026-06-02T06:50:47.167252Z","end":"2026-06-02T06:50:47.318669Z","steps":["trace[412712651] 'process raft request' (duration: 78.958977ms)","trace[412712651] 'compare' (duration: 72.1681ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T06:50:53.003997Z","caller":"traceutil/trace.go:171","msg":"trace[496410605] transaction","detail":"{read_only:false; response_revision:929; number_of_response:1; }","duration":"241.731313ms","start":"2026-06-02T06:50:52.762242Z","end":"2026-06-02T06:50:53.003973Z","steps":["trace[496410605] 'process raft request' (duration: 241.593551ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:50:53.004568Z","caller":"traceutil/trace.go:171","msg":"trace[243213132] linearizableReadLoop","detail":"{readStateIndex:969; appliedIndex:967; }","duration":"239.553094ms","start":"2026-06-02T06:50:52.764997Z","end":"2026-06-02T06:50:53.004551Z","steps":["trace[243213132] 'read index received' (duration: 238.76576ms)","trace[243213132] 'applied index is now lower than readState.Index' (duration: 786.584µs)"],"step_count":2} {"level":"info","ts":"2026-06-02T06:50:53.004614Z","caller":"traceutil/trace.go:171","msg":"trace[2004645206] transaction","detail":"{read_only:false; response_revision:930; number_of_response:1; }","duration":"241.4949ms","start":"2026-06-02T06:50:52.763075Z","end":"2026-06-02T06:50:53.00457Z","steps":["trace[2004645206] 'process raft request' (duration: 241.382567ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:50:53.004752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.732258ms","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-06-02T06:50:53.004774Z","caller":"traceutil/trace.go:171","msg":"trace[926016289] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:930; }","duration":"239.791078ms","start":"2026-06-02T06:50:52.764977Z","end":"2026-06-02T06:50:53.004768Z","steps":["trace[926016289] 'agreement among raft nodes before linearized reading' (duration: 239.658836ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:50:53.004844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.057151ms","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-06-02T06:50:53.004923Z","caller":"traceutil/trace.go:171","msg":"trace[498067945] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:930; }","duration":"236.160483ms","start":"2026-06-02T06:50:52.768745Z","end":"2026-06-02T06:50:53.004906Z","steps":["trace[498067945] 'agreement among raft nodes before linearized reading' (duration: 235.928969ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:50:54.788659Z","caller":"traceutil/trace.go:171","msg":"trace[1275385486] transaction","detail":"{read_only:false; response_revision:935; number_of_response:1; }","duration":"113.876213ms","start":"2026-06-02T06:50:54.674754Z","end":"2026-06-02T06:50:54.78863Z","steps":["trace[1275385486] 'process raft request' (duration: 113.72088ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:50:55.081666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.129799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T06:50:55.08174Z","caller":"traceutil/trace.go:171","msg":"trace[851656765] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:939; }","duration":"100.243532ms","start":"2026-06-02T06:50:54.98148Z","end":"2026-06-02T06:50:55.081723Z","steps":["trace[851656765] 'agreement among raft nodes before linearized reading' (duration: 100.073489ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:50:55.08205Z","caller":"traceutil/trace.go:171","msg":"trace[106649232] transaction","detail":"{read_only:false; response_revision:939; number_of_response:1; }","duration":"141.66004ms","start":"2026-06-02T06:50:54.940375Z","end":"2026-06-02T06:50:55.082035Z","steps":["trace[106649232] 'process raft request' (duration: 140.951908ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:50:55.323416Z","caller":"traceutil/trace.go:171","msg":"trace[195462149] transaction","detail":"{read_only:false; response_revision:950; number_of_response:1; }","duration":"134.216241ms","start":"2026-06-02T06:50:55.189174Z","end":"2026-06-02T06:50:55.32339Z","steps":["trace[195462149] 'process raft request' (duration: 127.35045ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:50:55.330317Z","caller":"traceutil/trace.go:171","msg":"trace[1953673923] transaction","detail":"{read_only:false; response_revision:951; number_of_response:1; }","duration":"137.888938ms","start":"2026-06-02T06:50:55.192411Z","end":"2026-06-02T06:50:55.3303Z","steps":["trace[1953673923] 'process raft request' (duration: 137.759786ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:50:56.947331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.849671ms","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-06-02T06:50:56.947401Z","caller":"traceutil/trace.go:171","msg":"trace[503913973] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:967; }","duration":"154.972122ms","start":"2026-06-02T06:50:56.792412Z","end":"2026-06-02T06:50:56.947384Z","steps":["trace[503913973] 'range keys from in-memory index tree' (duration: 154.681958ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:50:56.947403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.887953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.54\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-06-02T06:50:56.94747Z","caller":"traceutil/trace.go:171","msg":"trace[501616386] range","detail":"{range_begin:/registry/masterleases/199.19.213.54; range_end:; response_count:1; response_revision:967; }","duration":"111.998845ms","start":"2026-06-02T06:50:56.835456Z","end":"2026-06-02T06:50:56.947455Z","steps":["trace[501616386] 'range keys from in-memory index tree' (duration: 111.728421ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:50:56.94751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.819012ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-7c96b6546b-4nmrd\" ","response":"range_response_count:1 size:4883"} {"level":"info","ts":"2026-06-02T06:50:56.947569Z","caller":"traceutil/trace.go:171","msg":"trace[39568189] range","detail":"{range_begin:/registry/pods/kube-system/coredns-7c96b6546b-4nmrd; range_end:; response_count:1; response_revision:967; }","duration":"183.884293ms","start":"2026-06-02T06:50:56.763671Z","end":"2026-06-02T06:50:56.947556Z","steps":["trace[39568189] 'range keys from in-memory index tree' (duration: 183.70258ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:50:57.195164Z","caller":"traceutil/trace.go:171","msg":"trace[2093378563] transaction","detail":"{read_only:false; response_revision:968; number_of_response:1; }","duration":"244.619402ms","start":"2026-06-02T06:50:56.950511Z","end":"2026-06-02T06:50:57.19513Z","steps":["trace[2093378563] 'process raft request' (duration: 244.190744ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:50:57.195344Z","caller":"traceutil/trace.go:171","msg":"trace[834593991] linearizableReadLoop","detail":"{readStateIndex:1009; appliedIndex:1006; }","duration":"217.362801ms","start":"2026-06-02T06:50:56.977953Z","end":"2026-06-02T06:50:57.195316Z","steps":["trace[834593991] 'read index received' (duration: 57.265136ms)","trace[834593991] 'applied index is now lower than readState.Index' (duration: 160.096605ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T06:50:57.195429Z","caller":"traceutil/trace.go:171","msg":"trace[1765122801] transaction","detail":"{read_only:false; response_revision:969; number_of_response:1; }","duration":"241.971855ms","start":"2026-06-02T06:50:56.953415Z","end":"2026-06-02T06:50:57.195387Z","steps":["trace[1765122801] 'process raft request' (duration: 241.65431ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:50:57.195596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.597736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T06:50:57.195776Z","caller":"traceutil/trace.go:171","msg":"trace[1040125302] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:969; }","duration":"217.8469ms","start":"2026-06-02T06:50:56.977904Z","end":"2026-06-02T06:50:57.195751Z","steps":["trace[1040125302] 'agreement among raft nodes before linearized reading' (duration: 217.548745ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:50:57.245438Z","caller":"traceutil/trace.go:171","msg":"trace[1482476626] transaction","detail":"{read_only:false; response_revision:970; number_of_response:1; }","duration":"209.163314ms","start":"2026-06-02T06:50:57.036257Z","end":"2026-06-02T06:50:57.24542Z","steps":["trace[1482476626] 'process raft request' (duration: 208.90903ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:50:57.245644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.712574ms","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-06-02T06:50:57.245705Z","caller":"traceutil/trace.go:171","msg":"trace[1633826693] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:970; }","duration":"120.822577ms","start":"2026-06-02T06:50:57.124865Z","end":"2026-06-02T06:50:57.245688Z","steps":["trace[1633826693] 'agreement among raft nodes before linearized reading' (duration: 120.629353ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:50:57.24579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.99634ms","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-06-02T06:50:57.245956Z","caller":"traceutil/trace.go:171","msg":"trace[551938672] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:970; }","duration":"121.216724ms","start":"2026-06-02T06:50:57.124722Z","end":"2026-06-02T06:50:57.245939Z","steps":["trace[551938672] 'agreement among raft nodes before linearized reading' (duration: 120.846957ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:50:57.761391Z","caller":"traceutil/trace.go:171","msg":"trace[31356688] transaction","detail":"{read_only:false; response_revision:976; number_of_response:1; }","duration":"471.930299ms","start":"2026-06-02T06:50:57.289438Z","end":"2026-06-02T06:50:57.761368Z","steps":["trace[31356688] 'process raft request' (duration: 471.782706ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:50:57.761548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:50:57.28943Z","time spent":"472.039922ms","remote":"127.0.0.1:40678","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5471,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T06:50:57.87827Z","caller":"traceutil/trace.go:171","msg":"trace[258489274] linearizableReadLoop","detail":"{readStateIndex:1017; appliedIndex:1016; }","duration":"108.349828ms","start":"2026-06-02T06:50:57.769889Z","end":"2026-06-02T06:50:57.878238Z","steps":["trace[258489274] 'read index received' (duration: 81.342µs)","trace[258489274] 'applied index is now lower than readState.Index' (duration: 108.267376ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T06:50:57.878448Z","caller":"traceutil/trace.go:171","msg":"trace[865441398] transaction","detail":"{read_only:false; response_revision:977; number_of_response:1; }","duration":"108.551651ms","start":"2026-06-02T06:50:57.769868Z","end":"2026-06-02T06:50:57.87842Z","steps":["trace[865441398] 'process raft request' (duration: 59.212818ms)","trace[865441398] 'compare' (duration: 49.026618ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T06:50:57.878463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.554262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-7c96b6546b-4nmrd\" ","response":"range_response_count:1 size:5071"} {"level":"info","ts":"2026-06-02T06:50:57.878693Z","caller":"traceutil/trace.go:171","msg":"trace[748210066] range","detail":"{range_begin:/registry/pods/kube-system/coredns-7c96b6546b-4nmrd; range_end:; response_count:1; response_revision:977; }","duration":"108.809496ms","start":"2026-06-02T06:50:57.769862Z","end":"2026-06-02T06:50:57.878671Z","steps":["trace[748210066] 'agreement among raft nodes before linearized reading' (duration: 108.47923ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:50:57.924437Z","caller":"traceutil/trace.go:171","msg":"trace[2102720016] transaction","detail":"{read_only:false; response_revision:978; number_of_response:1; }","duration":"154.502797ms","start":"2026-06-02T06:50:57.769906Z","end":"2026-06-02T06:50:57.924408Z","steps":["trace[2102720016] 'process raft request' (duration: 154.261223ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:50:57.924667Z","caller":"traceutil/trace.go:171","msg":"trace[953617030] transaction","detail":"{read_only:false; response_revision:979; number_of_response:1; }","duration":"154.578169ms","start":"2026-06-02T06:50:57.770063Z","end":"2026-06-02T06:50:57.924641Z","steps":["trace[953617030] 'process raft request' (duration: 154.273224ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:50:58.111677Z","caller":"traceutil/trace.go:171","msg":"trace[554394913] transaction","detail":"{read_only:false; response_revision:980; number_of_response:1; }","duration":"184.605894ms","start":"2026-06-02T06:50:57.927048Z","end":"2026-06-02T06:50:58.111654Z","steps":["trace[554394913] 'process raft request' (duration: 184.460941ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:50:58.371394Z","caller":"traceutil/trace.go:171","msg":"trace[1546177184] linearizableReadLoop","detail":"{readStateIndex:1022; appliedIndex:1019; }","duration":"396.416826ms","start":"2026-06-02T06:50:57.974957Z","end":"2026-06-02T06:50:58.371373Z","steps":["trace[1546177184] 'read index received' (duration: 136.430559ms)","trace[1546177184] 'applied index is now lower than readState.Index' (duration: 259.985467ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T06:50:58.371472Z","caller":"traceutil/trace.go:171","msg":"trace[1158681076] transaction","detail":"{read_only:false; response_revision:981; number_of_response:1; }","duration":"438.153168ms","start":"2026-06-02T06:50:57.933294Z","end":"2026-06-02T06:50:58.371447Z","steps":["trace[1158681076] 'process raft request' (duration: 392.740397ms)","trace[1158681076] 'compare' (duration: 44.876001ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T06:50:58.371582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:50:57.933276Z","time spent":"438.246639ms","remote":"127.0.0.1:40910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5626,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T06:50:58.371641Z","caller":"traceutil/trace.go:171","msg":"trace[838386527] transaction","detail":"{read_only:false; response_revision:982; number_of_response:1; }","duration":"417.73769ms","start":"2026-06-02T06:50:57.953892Z","end":"2026-06-02T06:50:58.37163Z","steps":["trace[838386527] 'process raft request' (duration: 417.419164ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:50:58.371685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.718611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T06:50:58.371731Z","caller":"traceutil/trace.go:171","msg":"trace[1064194341] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:982; }","duration":"396.795342ms","start":"2026-06-02T06:50:57.974924Z","end":"2026-06-02T06:50:58.37172Z","steps":["trace[1064194341] 'agreement among raft nodes before linearized reading' (duration: 396.709141ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:50:58.371738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:50:57.953872Z","time spent":"417.799551ms","remote":"127.0.0.1:40754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T06:50:58.371753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:50:57.974913Z","time spent":"396.836294ms","remote":"127.0.0.1:40530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-02T06:50:58.394341Z","caller":"traceutil/trace.go:171","msg":"trace[1894848676] transaction","detail":"{read_only:false; number_of_response:1; response_revision:982; }","duration":"280.25421ms","start":"2026-06-02T06:50:58.114066Z","end":"2026-06-02T06:50:58.39432Z","steps":["trace[1894848676] 'process raft request' (duration: 280.069237ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:50:58.39437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.929844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-02T06:50:58.39445Z","caller":"traceutil/trace.go:171","msg":"trace[104139886] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:982; }","duration":"365.058177ms","start":"2026-06-02T06:50:58.029375Z","end":"2026-06-02T06:50:58.394434Z","steps":["trace[104139886] 'agreement among raft nodes before linearized reading' (duration: 364.907383ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:50:58.395048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:50:58.029327Z","time spent":"365.686518ms","remote":"127.0.0.1:40852","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":30,"request content":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true "} {"level":"warn","ts":"2026-06-02T06:50:59.614369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.60548ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-02T06:50:59.614491Z","caller":"traceutil/trace.go:171","msg":"trace[427857204] transaction","detail":"{read_only:false; response_revision:985; number_of_response:1; }","duration":"322.073514ms","start":"2026-06-02T06:50:59.292398Z","end":"2026-06-02T06:50:59.614471Z","steps":["trace[427857204] 'process raft request' (duration: 124.27618ms)","trace[427857204] 'compare' (duration: 197.173933ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T06:50:59.614672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:50:59.292385Z","time spent":"322.224726ms","remote":"127.0.0.1:40754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T06:50:59.649827Z","caller":"traceutil/trace.go:171","msg":"trace[2068313940] transaction","detail":"{read_only:false; response_revision:988; number_of_response:1; }","duration":"354.895803ms","start":"2026-06-02T06:50:59.294902Z","end":"2026-06-02T06:50:59.649797Z","steps":["trace[2068313940] 'process raft request' (duration: 354.837322ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:50:59.650067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:50:59.29488Z","time spent":"355.050925ms","remote":"127.0.0.1:40754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T06:50:59.650186Z","caller":"traceutil/trace.go:171","msg":"trace[1839224730] transaction","detail":"{read_only:false; response_revision:986; number_of_response:1; }","duration":"356.898678ms","start":"2026-06-02T06:50:59.293254Z","end":"2026-06-02T06:50:59.650152Z","steps":["trace[1839224730] 'process raft request' (duration: 356.270277ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:50:59.650344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:50:59.29323Z","time spent":"357.026959ms","remote":"127.0.0.1:40754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T06:50:59.65043Z","caller":"traceutil/trace.go:171","msg":"trace[1181012504] transaction","detail":"{read_only:false; response_revision:987; number_of_response:1; }","duration":"357.09634ms","start":"2026-06-02T06:50:59.293325Z","end":"2026-06-02T06:50:59.650421Z","steps":["trace[1181012504] 'process raft request' (duration: 356.368078ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:50:59.65051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:50:59.29332Z","time spent":"357.133571ms","remote":"127.0.0.1:40754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T06:51:01.404375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.250571ms","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-06-02T06:51:01.404446Z","caller":"traceutil/trace.go:171","msg":"trace[292272805] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:991; }","duration":"125.380543ms","start":"2026-06-02T06:51:01.279051Z","end":"2026-06-02T06:51:01.404432Z","steps":["trace[292272805] 'range keys from in-memory index tree' (duration: 124.976227ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:51:17.814334Z","caller":"traceutil/trace.go:171","msg":"trace[1971968305] transaction","detail":"{read_only:false; response_revision:1056; number_of_response:1; }","duration":"103.297436ms","start":"2026-06-02T06:51:17.710986Z","end":"2026-06-02T06:51:17.814284Z","steps":["trace[1971968305] 'process raft request' (duration: 103.104383ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:51:20.04342Z","caller":"traceutil/trace.go:171","msg":"trace[1764879484] linearizableReadLoop","detail":"{readStateIndex:1107; appliedIndex:1106; }","duration":"216.534871ms","start":"2026-06-02T06:51:19.826855Z","end":"2026-06-02T06:51:20.04339Z","steps":["trace[1764879484] 'read index received' (duration: 216.178205ms)","trace[1764879484] 'applied index is now lower than readState.Index' (duration: 355.306µs)"],"step_count":2} {"level":"info","ts":"2026-06-02T06:51:20.043583Z","caller":"traceutil/trace.go:171","msg":"trace[221637482] transaction","detail":"{read_only:false; response_revision:1062; number_of_response:1; }","duration":"220.709926ms","start":"2026-06-02T06:51:19.822861Z","end":"2026-06-02T06:51:20.04357Z","steps":["trace[221637482] 'process raft request' (duration: 220.297409ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:51:20.043818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.914197ms","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-06-02T06:51:20.043899Z","caller":"traceutil/trace.go:171","msg":"trace[457818495] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1062; }","duration":"217.05446ms","start":"2026-06-02T06:51:19.826827Z","end":"2026-06-02T06:51:20.043881Z","steps":["trace[457818495] 'agreement among raft nodes before linearized reading' (duration: 216.778375ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:51:20.043904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.028128ms","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-06-02T06:51:20.043992Z","caller":"traceutil/trace.go:171","msg":"trace[1277243567] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1062; }","duration":"217.164291ms","start":"2026-06-02T06:51:19.826805Z","end":"2026-06-02T06:51:20.043969Z","steps":["trace[1277243567] 'agreement among raft nodes before linearized reading' (duration: 216.928877ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:51:20.044971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.689162ms","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-06-02T06:51:20.045057Z","caller":"traceutil/trace.go:171","msg":"trace[1188507390] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1062; }","duration":"216.831475ms","start":"2026-06-02T06:51:19.828205Z","end":"2026-06-02T06:51:20.045036Z","steps":["trace[1188507390] 'agreement among raft nodes before linearized reading' (duration: 215.563016ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:51:20.580946Z","caller":"traceutil/trace.go:171","msg":"trace[1676624478] transaction","detail":"{read_only:false; response_revision:1066; number_of_response:1; }","duration":"112.94362ms","start":"2026-06-02T06:51:20.467987Z","end":"2026-06-02T06:51:20.580931Z","steps":["trace[1676624478] 'process raft request' (duration: 112.631085ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:51:26.441045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.269713ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-02T06:51:26.441179Z","caller":"traceutil/trace.go:171","msg":"trace[1779327559] transaction","detail":"{read_only:false; response_revision:1083; number_of_response:1; }","duration":"313.715837ms","start":"2026-06-02T06:51:26.127438Z","end":"2026-06-02T06:51:26.441154Z","steps":["trace[1779327559] 'process raft request' (duration: 100.217321ms)","trace[1779327559] 'compare' (duration: 213.031949ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T06:51:26.441254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:51:26.127406Z","time spent":"313.812198ms","remote":"127.0.0.1:40754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T06:51:26.760219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.236332ms","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-06-02T06:51:26.760402Z","caller":"traceutil/trace.go:171","msg":"trace[418852308] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1083; }","duration":"201.452817ms","start":"2026-06-02T06:51:26.558924Z","end":"2026-06-02T06:51:26.760377Z","steps":["trace[418852308] 'range keys from in-memory index tree' (duration: 201.203452ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:51:26.760671Z","caller":"traceutil/trace.go:171","msg":"trace[1166785850] transaction","detail":"{read_only:false; response_revision:1084; number_of_response:1; }","duration":"632.173274ms","start":"2026-06-02T06:51:26.128458Z","end":"2026-06-02T06:51:26.760632Z","steps":["trace[1166785850] 'process raft request' (duration: 548.505043ms)","trace[1166785850] 'compare' (duration: 83.305946ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T06:51:26.760686Z","caller":"traceutil/trace.go:171","msg":"trace[793834896] transaction","detail":"{read_only:false; response_revision:1085; number_of_response:1; }","duration":"631.267931ms","start":"2026-06-02T06:51:26.129361Z","end":"2026-06-02T06:51:26.760629Z","steps":["trace[793834896] 'process raft request' (duration: 631.16092ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:51:26.760857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:51:26.128424Z","time spent":"632.339096ms","remote":"127.0.0.1:40754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T06:51:26.760935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:51:26.129354Z","time spent":"631.443814ms","remote":"127.0.0.1:40754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T06:51:27.120993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.621943ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-02T06:51:27.121124Z","caller":"traceutil/trace.go:171","msg":"trace[261412454] linearizableReadLoop","detail":"{readStateIndex:1132; appliedIndex:1131; }","duration":"309.677618ms","start":"2026-06-02T06:51:26.811428Z","end":"2026-06-02T06:51:27.121106Z","steps":["trace[261412454] 'read index received' (duration: 185.776951ms)","trace[261412454] 'applied index is now lower than readState.Index' (duration: 123.899397ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T06:51:27.121194Z","caller":"traceutil/trace.go:171","msg":"trace[992238698] transaction","detail":"{read_only:false; response_revision:1086; number_of_response:1; }","duration":"517.229947ms","start":"2026-06-02T06:51:26.603914Z","end":"2026-06-02T06:51:27.121143Z","steps":["trace[992238698] 'process raft request' (duration: 393.36821ms)","trace[992238698] 'compare' (duration: 123.464721ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T06:51:27.121257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.82121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-02T06:51:27.121305Z","caller":"traceutil/trace.go:171","msg":"trace[1984753701] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:1086; }","duration":"309.897052ms","start":"2026-06-02T06:51:26.811394Z","end":"2026-06-02T06:51:27.121291Z","steps":["trace[1984753701] 'agreement among raft nodes before linearized reading' (duration: 309.806211ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:51:27.121346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:51:26.811376Z","time spent":"309.960013ms","remote":"127.0.0.1:40966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":29,"response size":30,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true "} {"level":"warn","ts":"2026-06-02T06:51:27.121374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.968099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.54\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-06-02T06:51:27.121412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:51:26.603894Z","time spent":"517.404429ms","remote":"127.0.0.1:40754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T06:51:27.121438Z","caller":"traceutil/trace.go:171","msg":"trace[64699034] range","detail":"{range_begin:/registry/masterleases/199.19.213.54; range_end:; response_count:1; response_revision:1086; }","duration":"283.266194ms","start":"2026-06-02T06:51:26.838156Z","end":"2026-06-02T06:51:27.121423Z","steps":["trace[64699034] 'agreement among raft nodes before linearized reading' (duration: 283.067971ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:51:29.04631Z","caller":"traceutil/trace.go:171","msg":"trace[627836434] transaction","detail":"{read_only:false; response_revision:1091; number_of_response:1; }","duration":"275.569855ms","start":"2026-06-02T06:51:28.770702Z","end":"2026-06-02T06:51:29.046272Z","steps":["trace[627836434] 'process raft request' (duration: 275.343642ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:51:29.175383Z","caller":"traceutil/trace.go:171","msg":"trace[1824076621] linearizableReadLoop","detail":"{readStateIndex:1139; appliedIndex:1137; }","duration":"197.034281ms","start":"2026-06-02T06:51:28.978325Z","end":"2026-06-02T06:51:29.175359Z","steps":["trace[1824076621] 'read index received' (duration: 67.703692ms)","trace[1824076621] 'applied index is now lower than readState.Index' (duration: 129.329579ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T06:51:29.175557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.228684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T06:51:29.175636Z","caller":"traceutil/trace.go:171","msg":"trace[104196065] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1093; }","duration":"197.364196ms","start":"2026-06-02T06:51:28.978257Z","end":"2026-06-02T06:51:29.175621Z","steps":["trace[104196065] 'agreement among raft nodes before linearized reading' (duration: 197.185593ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:51:29.175693Z","caller":"traceutil/trace.go:171","msg":"trace[31837320] transaction","detail":"{read_only:false; response_revision:1092; number_of_response:1; }","duration":"404.596791ms","start":"2026-06-02T06:51:28.77081Z","end":"2026-06-02T06:51:29.175407Z","steps":["trace[31837320] 'process raft request' (duration: 385.182864ms)","trace[31837320] 'compare' (duration: 19.196504ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T06:51:29.175877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:51:28.770793Z","time spent":"404.999547ms","remote":"127.0.0.1:40754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T06:51:31.388433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.98472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-02T06:51:31.388572Z","caller":"traceutil/trace.go:171","msg":"trace[1371168754] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1099; }","duration":"138.198183ms","start":"2026-06-02T06:51:31.250342Z","end":"2026-06-02T06:51:31.38854Z","steps":["trace[1371168754] 'count revisions from in-memory index tree' (duration: 137.870898ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:51:32.311106Z","caller":"traceutil/trace.go:171","msg":"trace[1993707597] transaction","detail":"{read_only:false; response_revision:1101; number_of_response:1; }","duration":"429.418718ms","start":"2026-06-02T06:51:31.881641Z","end":"2026-06-02T06:51:32.31106Z","steps":["trace[1993707597] 'process raft request' (duration: 429.263265ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:51:32.311474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:51:31.881612Z","time spent":"429.601561ms","remote":"127.0.0.1:40668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T06:51:32.312215Z","caller":"traceutil/trace.go:171","msg":"trace[155661720] linearizableReadLoop","detail":"{readStateIndex:1148; appliedIndex:1148; }","duration":"321.67851ms","start":"2026-06-02T06:51:31.990505Z","end":"2026-06-02T06:51:32.312184Z","steps":["trace[155661720] 'read index received' (duration: 321.66767ms)","trace[155661720] 'applied index is now lower than readState.Index' (duration: 8.24µs)"],"step_count":2} {"level":"warn","ts":"2026-06-02T06:51:32.312376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.853013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T06:51:32.312395Z","caller":"traceutil/trace.go:171","msg":"trace[1896300224] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1101; }","duration":"321.930453ms","start":"2026-06-02T06:51:31.990459Z","end":"2026-06-02T06:51:32.312389Z","steps":["trace[1896300224] 'agreement among raft nodes before linearized reading' (duration: 321.848692ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:51:32.31242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:51:31.990441Z","time spent":"321.974775ms","remote":"127.0.0.1:40530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-02T06:51:32.621067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.362731ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-06-02T06:51:32.6212Z","caller":"traceutil/trace.go:171","msg":"trace[1182395093] linearizableReadLoop","detail":"{readStateIndex:1149; appliedIndex:1148; }","duration":"308.896202ms","start":"2026-06-02T06:51:32.31228Z","end":"2026-06-02T06:51:32.621176Z","steps":["trace[1182395093] 'read index received' (duration: 206.415689ms)","trace[1182395093] 'applied index is now lower than readState.Index' (duration: 102.478813ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T06:51:32.621481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.700041ms","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-06-02T06:51:32.621523Z","caller":"traceutil/trace.go:171","msg":"trace[447976908] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1101; }","duration":"411.794371ms","start":"2026-06-02T06:51:32.209717Z","end":"2026-06-02T06:51:32.621512Z","steps":["trace[447976908] 'agreement among raft nodes before linearized reading' (duration: 411.569607ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:51:32.621559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:51:32.209696Z","time spent":"411.853973ms","remote":"127.0.0.1:40754","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-06-02T06:51:32.621731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.533197ms","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-06-02T06:51:32.621888Z","caller":"traceutil/trace.go:171","msg":"trace[1675578568] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1101; }","duration":"131.76586ms","start":"2026-06-02T06:51:32.490093Z","end":"2026-06-02T06:51:32.621859Z","steps":["trace[1675578568] 'agreement among raft nodes before linearized reading' (duration: 131.277193ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:51:32.622002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.376214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T06:51:32.622055Z","caller":"traceutil/trace.go:171","msg":"trace[1805437923] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1101; }","duration":"308.472636ms","start":"2026-06-02T06:51:32.313571Z","end":"2026-06-02T06:51:32.622044Z","steps":["trace[1805437923] 'agreement among raft nodes before linearized reading' (duration: 308.393835ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:51:32.622076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:51:32.313548Z","time spent":"308.523966ms","remote":"127.0.0.1:40538","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-02T06:51:32.621736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.46616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T06:51:32.622281Z","caller":"traceutil/trace.go:171","msg":"trace[33656747] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:1101; }","duration":"146.051028ms","start":"2026-06-02T06:51:32.47621Z","end":"2026-06-02T06:51:32.622261Z","steps":["trace[33656747] 'agreement among raft nodes before linearized reading' (duration: 145.300977ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:51:33.410158Z","caller":"traceutil/trace.go:171","msg":"trace[1199403578] linearizableReadLoop","detail":"{readStateIndex:1153; appliedIndex:1151; }","duration":"170.312872ms","start":"2026-06-02T06:51:33.239817Z","end":"2026-06-02T06:51:33.41013Z","steps":["trace[1199403578] 'read index received' (duration: 9.127033ms)","trace[1199403578] 'applied index is now lower than readState.Index' (duration: 161.185149ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T06:51:33.410215Z","caller":"traceutil/trace.go:171","msg":"trace[975019505] transaction","detail":"{read_only:false; response_revision:1105; number_of_response:1; }","duration":"184.739381ms","start":"2026-06-02T06:51:33.225459Z","end":"2026-06-02T06:51:33.410198Z","steps":["trace[975019505] 'process raft request' (duration: 131.416467ms)","trace[975019505] 'compare' (duration: 53.09234ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T06:51:33.41034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.505174ms","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-06-02T06:51:33.410366Z","caller":"traceutil/trace.go:171","msg":"trace[1743467294] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1105; }","duration":"170.674657ms","start":"2026-06-02T06:51:33.239683Z","end":"2026-06-02T06:51:33.410358Z","steps":["trace[1743467294] 'agreement among raft nodes before linearized reading' (duration: 170.553905ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:51:33.671953Z","caller":"traceutil/trace.go:171","msg":"trace[69824594] transaction","detail":"{read_only:false; response_revision:1107; number_of_response:1; }","duration":"257.528067ms","start":"2026-06-02T06:51:33.414383Z","end":"2026-06-02T06:51:33.671911Z","steps":["trace[69824594] 'process raft request' (duration: 227.800935ms)","trace[69824594] 'compare' (duration: 29.582559ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T06:51:54.947109Z","caller":"traceutil/trace.go:171","msg":"trace[90728080] transaction","detail":"{read_only:false; response_revision:1176; number_of_response:1; }","duration":"110.403709ms","start":"2026-06-02T06:51:54.836656Z","end":"2026-06-02T06:51:54.947059Z","steps":["trace[90728080] 'process raft request' (duration: 108.27603ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:51:57.078639Z","caller":"traceutil/trace.go:171","msg":"trace[2043532324] linearizableReadLoop","detail":"{readStateIndex:1236; appliedIndex:1235; }","duration":"126.676983ms","start":"2026-06-02T06:51:56.951928Z","end":"2026-06-02T06:51:57.078605Z","steps":["trace[2043532324] 'read index received' (duration: 82.293544ms)","trace[2043532324] 'applied index is now lower than readState.Index' (duration: 44.382589ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T06:51:57.078974Z","caller":"traceutil/trace.go:171","msg":"trace[1196080947] transaction","detail":"{read_only:false; response_revision:1183; number_of_response:1; }","duration":"201.50655ms","start":"2026-06-02T06:51:56.877456Z","end":"2026-06-02T06:51:57.078962Z","steps":["trace[1196080947] 'process raft request' (duration: 156.017616ms)","trace[1196080947] 'compare' (duration: 44.781945ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T06:51:57.079284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.332622ms","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-06-02T06:51:57.079326Z","caller":"traceutil/trace.go:171","msg":"trace[1334128590] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1183; }","duration":"127.428854ms","start":"2026-06-02T06:51:56.951888Z","end":"2026-06-02T06:51:57.079317Z","steps":["trace[1334128590] 'agreement among raft nodes before linearized reading' (duration: 127.283782ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:51:57.079492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.434913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-06-02T06:51:57.079539Z","caller":"traceutil/trace.go:171","msg":"trace[826057070] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:1183; }","duration":"127.514494ms","start":"2026-06-02T06:51:56.952017Z","end":"2026-06-02T06:51:57.079531Z","steps":["trace[826057070] 'agreement among raft nodes before linearized reading' (duration: 127.433953ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:51:58.187398Z","caller":"traceutil/trace.go:171","msg":"trace[401579925] transaction","detail":"{read_only:false; response_revision:1188; number_of_response:1; }","duration":"168.997765ms","start":"2026-06-02T06:51:58.018365Z","end":"2026-06-02T06:51:58.187363Z","steps":["trace[401579925] 'process raft request' (duration: 168.835153ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:52:32.277732Z","caller":"traceutil/trace.go:171","msg":"trace[780829734] transaction","detail":"{read_only:false; response_revision:1330; number_of_response:1; }","duration":"186.317909ms","start":"2026-06-02T06:52:32.091389Z","end":"2026-06-02T06:52:32.277707Z","steps":["trace[780829734] 'process raft request' (duration: 186.141677ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:52:32.361228Z","caller":"traceutil/trace.go:171","msg":"trace[1267494317] linearizableReadLoop","detail":"{readStateIndex:1391; appliedIndex:1389; }","duration":"154.745846ms","start":"2026-06-02T06:52:32.206459Z","end":"2026-06-02T06:52:32.361205Z","steps":["trace[1267494317] 'read index received' (duration: 71.047151ms)","trace[1267494317] 'applied index is now lower than readState.Index' (duration: 83.697215ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T06:52:32.361358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.887478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T06:52:32.361483Z","caller":"traceutil/trace.go:171","msg":"trace[1223401912] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:1330; }","duration":"155.072399ms","start":"2026-06-02T06:52:32.206392Z","end":"2026-06-02T06:52:32.361464Z","steps":["trace[1223401912] 'agreement among raft nodes before linearized reading' (duration: 154.905778ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:52:40.159619Z","caller":"traceutil/trace.go:171","msg":"trace[566415176] linearizableReadLoop","detail":"{readStateIndex:1423; appliedIndex:1422; }","duration":"145.816081ms","start":"2026-06-02T06:52:40.013783Z","end":"2026-06-02T06:52:40.159599Z","steps":["trace[566415176] 'read index received' (duration: 145.634459ms)","trace[566415176] 'applied index is now lower than readState.Index' (duration: 180.752µs)"],"step_count":2} {"level":"info","ts":"2026-06-02T06:52:40.159859Z","caller":"traceutil/trace.go:171","msg":"trace[1236380829] transaction","detail":"{read_only:false; response_revision:1361; number_of_response:1; }","duration":"304.796713ms","start":"2026-06-02T06:52:39.855046Z","end":"2026-06-02T06:52:40.159842Z","steps":["trace[1236380829] 'process raft request' (duration: 304.418869ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:52:40.159796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.025383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:2565"} {"level":"info","ts":"2026-06-02T06:52:40.160013Z","caller":"traceutil/trace.go:171","msg":"trace[1169723869] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:1361; }","duration":"146.316667ms","start":"2026-06-02T06:52:40.013684Z","end":"2026-06-02T06:52:40.160001Z","steps":["trace[1169723869] 'agreement among raft nodes before linearized reading' (duration: 146.004843ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:52:40.160042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:52:39.855021Z","time spent":"304.934194ms","remote":"127.0.0.1:40754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T06:52:40.847411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.608754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T06:52:40.847527Z","caller":"traceutil/trace.go:171","msg":"trace[935298912] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:1364; }","duration":"302.777956ms","start":"2026-06-02T06:52:40.544724Z","end":"2026-06-02T06:52:40.847502Z","steps":["trace[935298912] 'count revisions from in-memory index tree' (duration: 302.515153ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:52:40.847592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:52:40.544706Z","time spent":"302.867567ms","remote":"127.0.0.1:40768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":0,"response size":28,"request content":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-06-02T06:52:40.847787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.87226ms","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-06-02T06:52:40.847912Z","caller":"traceutil/trace.go:171","msg":"trace[1192306848] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1364; }","duration":"382.044972ms","start":"2026-06-02T06:52:40.465848Z","end":"2026-06-02T06:52:40.847893Z","steps":["trace[1192306848] 'range keys from in-memory index tree' (duration: 381.344434ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:52:40.848227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:52:40.465828Z","time spent":"382.192184ms","remote":"127.0.0.1:40754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-02T06:52:42.18479Z","caller":"traceutil/trace.go:171","msg":"trace[1531974575] transaction","detail":"{read_only:false; response_revision:1368; number_of_response:1; }","duration":"423.502915ms","start":"2026-06-02T06:52:41.761261Z","end":"2026-06-02T06:52:42.184764Z","steps":["trace[1531974575] 'process raft request' (duration: 423.315083ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:52:42.184993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:52:41.761246Z","time spent":"423.626476ms","remote":"127.0.0.1:40754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T06:52:42.185213Z","caller":"traceutil/trace.go:171","msg":"trace[1250347636] linearizableReadLoop","detail":"{readStateIndex:1430; appliedIndex:1430; }","duration":"279.070712ms","start":"2026-06-02T06:52:41.905902Z","end":"2026-06-02T06:52:42.184973Z","steps":["trace[1250347636] 'read index received' (duration: 279.059662ms)","trace[1250347636] 'applied index is now lower than readState.Index' (duration: 8.75µs)"],"step_count":2} {"level":"warn","ts":"2026-06-02T06:52:42.185567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.62015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T06:52:42.185745Z","caller":"traceutil/trace.go:171","msg":"trace[1788350223] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:1368; }","duration":"279.861772ms","start":"2026-06-02T06:52:41.905862Z","end":"2026-06-02T06:52:42.185724Z","steps":["trace[1788350223] 'agreement among raft nodes before linearized reading' (duration: 279.592859ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:52:42.276985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.756422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T06:52:42.277086Z","caller":"traceutil/trace.go:171","msg":"trace[1133634250] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1369; }","duration":"296.976775ms","start":"2026-06-02T06:52:41.980093Z","end":"2026-06-02T06:52:42.27707Z","steps":["trace[1133634250] 'agreement among raft nodes before linearized reading' (duration: 296.768872ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:52:42.276977Z","caller":"traceutil/trace.go:171","msg":"trace[1670502562] transaction","detail":"{read_only:false; response_revision:1369; number_of_response:1; }","duration":"111.460546ms","start":"2026-06-02T06:52:42.165476Z","end":"2026-06-02T06:52:42.276936Z","steps":["trace[1670502562] 'process raft request' (duration: 111.186072ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:52:46.332641Z","caller":"traceutil/trace.go:171","msg":"trace[104398809] transaction","detail":"{read_only:false; response_revision:1385; number_of_response:1; }","duration":"130.990467ms","start":"2026-06-02T06:52:46.201625Z","end":"2026-06-02T06:52:46.332615Z","steps":["trace[104398809] 'process raft request' (duration: 130.829026ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:52:46.68515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.085476ms","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-06-02T06:52:46.685319Z","caller":"traceutil/trace.go:171","msg":"trace[1733010857] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1385; }","duration":"129.273408ms","start":"2026-06-02T06:52:46.556019Z","end":"2026-06-02T06:52:46.685293Z","steps":["trace[1733010857] 'range keys from in-memory index tree' (duration: 129.063945ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:52:46.68559Z","caller":"traceutil/trace.go:171","msg":"trace[332731083] transaction","detail":"{read_only:false; response_revision:1386; number_of_response:1; }","duration":"422.459301ms","start":"2026-06-02T06:52:46.263109Z","end":"2026-06-02T06:52:46.685568Z","steps":["trace[332731083] 'process raft request' (duration: 359.554199ms)","trace[332731083] 'compare' (duration: 62.534088ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T06:52:46.685788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:52:46.263086Z","time spent":"422.612133ms","remote":"127.0.0.1:40754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T06:52:46.685979Z","caller":"traceutil/trace.go:171","msg":"trace[1188942087] transaction","detail":"{read_only:false; response_revision:1387; number_of_response:1; }","duration":"396.159131ms","start":"2026-06-02T06:52:46.289797Z","end":"2026-06-02T06:52:46.685956Z","steps":["trace[1188942087] 'process raft request' (duration: 395.649195ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:52:46.68596Z","caller":"traceutil/trace.go:171","msg":"trace[1183764261] linearizableReadLoop","detail":"{readStateIndex:1450; appliedIndex:1447; }","duration":"393.740423ms","start":"2026-06-02T06:52:46.292192Z","end":"2026-06-02T06:52:46.685932Z","steps":["trace[1183764261] 'read index received' (duration: 40.330106ms)","trace[1183764261] 'applied index is now lower than readState.Index' (duration: 353.407827ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T06:52:46.686301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:52:46.289773Z","time spent":"396.262333ms","remote":"127.0.0.1:40754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T06:52:46.686493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.192813ms","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-06-02T06:52:46.686554Z","caller":"traceutil/trace.go:171","msg":"trace[2120208445] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1387; }","duration":"325.284803ms","start":"2026-06-02T06:52:46.361254Z","end":"2026-06-02T06:52:46.686539Z","steps":["trace[2120208445] 'agreement among raft nodes before linearized reading' (duration: 325.058631ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:52:46.686599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:52:46.36124Z","time spent":"325.346954ms","remote":"127.0.0.1:40754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-02T06:52:46.686526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.32068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" ","response":"range_response_count:1 size:12755"} {"level":"info","ts":"2026-06-02T06:52:46.68685Z","caller":"traceutil/trace.go:171","msg":"trace[1174874409] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:1; response_revision:1387; }","duration":"394.633203ms","start":"2026-06-02T06:52:46.29216Z","end":"2026-06-02T06:52:46.686793Z","steps":["trace[1174874409] 'agreement among raft nodes before linearized reading' (duration: 393.852533ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:52:46.686996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:52:46.292144Z","time spent":"394.832756ms","remote":"127.0.0.1:40610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":12778,"request content":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" "} {"level":"warn","ts":"2026-06-02T06:52:46.979709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.160107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.54\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-06-02T06:52:46.979775Z","caller":"traceutil/trace.go:171","msg":"trace[368681194] range","detail":"{range_begin:/registry/masterleases/199.19.213.54; range_end:; response_count:1; response_revision:1388; }","duration":"135.284389ms","start":"2026-06-02T06:52:46.844474Z","end":"2026-06-02T06:52:46.979758Z","steps":["trace[368681194] 'range keys from in-memory index tree' (duration: 134.50567ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:52:46.979816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.912956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" ","response":"range_response_count:1 size:12755"} {"level":"info","ts":"2026-06-02T06:52:46.979909Z","caller":"traceutil/trace.go:171","msg":"trace[1646310576] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:1; response_revision:1388; }","duration":"185.042888ms","start":"2026-06-02T06:52:46.794841Z","end":"2026-06-02T06:52:46.979883Z","steps":["trace[1646310576] 'range keys from in-memory index tree' (duration: 184.062016ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:52:47.191221Z","caller":"traceutil/trace.go:171","msg":"trace[1280403755] transaction","detail":"{read_only:false; response_revision:1390; number_of_response:1; }","duration":"131.853116ms","start":"2026-06-02T06:52:47.05934Z","end":"2026-06-02T06:52:47.191194Z","steps":["trace[1280403755] 'process raft request' (duration: 131.731485ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:52:47.191263Z","caller":"traceutil/trace.go:171","msg":"trace[2142276647] transaction","detail":"{read_only:false; response_revision:1389; number_of_response:1; }","duration":"207.787082ms","start":"2026-06-02T06:52:46.983446Z","end":"2026-06-02T06:52:47.191233Z","steps":["trace[2142276647] 'process raft request' (duration: 156.959792ms)","trace[2142276647] 'compare' (duration: 50.528256ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T06:52:47.601149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.005747ms","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-06-02T06:52:47.601232Z","caller":"traceutil/trace.go:171","msg":"trace[1670352730] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1390; }","duration":"204.134888ms","start":"2026-06-02T06:52:47.39708Z","end":"2026-06-02T06:52:47.601215Z","steps":["trace[1670352730] 'range keys from in-memory index tree' (duration: 203.825835ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:52:47.60133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.114981ms","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-06-02T06:52:47.601445Z","caller":"traceutil/trace.go:171","msg":"trace[407916853] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1390; }","duration":"167.260362ms","start":"2026-06-02T06:52:47.434146Z","end":"2026-06-02T06:52:47.601406Z","steps":["trace[407916853] 'range keys from in-memory index tree' (duration: 166.960379ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:52:47.871745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.398851ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-02T06:52:47.871852Z","caller":"traceutil/trace.go:171","msg":"trace[1668610337] transaction","detail":"{read_only:false; response_revision:1392; number_of_response:1; }","duration":"267.055491ms","start":"2026-06-02T06:52:47.604776Z","end":"2026-06-02T06:52:47.871832Z","steps":["trace[1668610337] 'process raft request' (duration: 140.495268ms)","trace[1668610337] 'compare' (duration: 125.960106ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T06:52:48.144473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.711846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T06:52:48.144638Z","caller":"traceutil/trace.go:171","msg":"trace[1494172414] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:1392; }","duration":"167.943239ms","start":"2026-06-02T06:52:47.97667Z","end":"2026-06-02T06:52:48.144613Z","steps":["trace[1494172414] 'count revisions from in-memory index tree' (duration: 167.550265ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:52:48.144641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.973557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T06:52:48.144726Z","caller":"traceutil/trace.go:171","msg":"trace[2083839652] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1392; }","duration":"167.091458ms","start":"2026-06-02T06:52:47.97761Z","end":"2026-06-02T06:52:48.144701Z","steps":["trace[2083839652] 'range keys from in-memory index tree' (duration: 166.861225ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:52:48.537313Z","caller":"traceutil/trace.go:171","msg":"trace[3928754] transaction","detail":"{read_only:false; response_revision:1393; number_of_response:1; }","duration":"197.628807ms","start":"2026-06-02T06:52:48.339635Z","end":"2026-06-02T06:52:48.537264Z","steps":["trace[3928754] 'process raft request' (duration: 197.318854ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:52:49.884248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.445697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-view\" ","response":"range_response_count:1 size:1930"} {"level":"info","ts":"2026-06-02T06:52:49.884323Z","caller":"traceutil/trace.go:171","msg":"trace[309465523] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-view; range_end:; response_count:1; response_revision:1397; }","duration":"126.532958ms","start":"2026-06-02T06:52:49.757773Z","end":"2026-06-02T06:52:49.884306Z","steps":["trace[309465523] 'range keys from in-memory index tree' (duration: 126.334316ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:52:50.702251Z","caller":"traceutil/trace.go:171","msg":"trace[2054087395] linearizableReadLoop","detail":"{readStateIndex:1463; appliedIndex:1462; }","duration":"136.416712ms","start":"2026-06-02T06:52:50.56581Z","end":"2026-06-02T06:52:50.702227Z","steps":["trace[2054087395] 'read index received' (duration: 136.24752ms)","trace[2054087395] 'applied index is now lower than readState.Index' (duration: 167.832µs)"],"step_count":2} {"level":"info","ts":"2026-06-02T06:52:50.702319Z","caller":"traceutil/trace.go:171","msg":"trace[704809261] transaction","detail":"{read_only:false; response_revision:1399; number_of_response:1; }","duration":"158.700822ms","start":"2026-06-02T06:52:50.543587Z","end":"2026-06-02T06:52:50.702288Z","steps":["trace[704809261] 'process raft request' (duration: 158.47064ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:52:50.702661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.804806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:certificate-controller\" ","response":"range_response_count:1 size:1142"} {"level":"info","ts":"2026-06-02T06:52:50.70272Z","caller":"traceutil/trace.go:171","msg":"trace[493860303] range","detail":"{range_begin:/registry/clusterroles/system:controller:certificate-controller; range_end:; response_count:1; response_revision:1399; }","duration":"136.954218ms","start":"2026-06-02T06:52:50.565748Z","end":"2026-06-02T06:52:50.702702Z","steps":["trace[493860303] 'agreement among raft nodes before linearized reading' (duration: 136.624314ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:52:50.703316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.743304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:attachdetach-controller\" ","response":"range_response_count:1 size:950"} {"level":"info","ts":"2026-06-02T06:52:50.703427Z","caller":"traceutil/trace.go:171","msg":"trace[1823677905] range","detail":"{range_begin:/registry/clusterroles/system:controller:attachdetach-controller; range_end:; response_count:1; response_revision:1400; }","duration":"123.887895ms","start":"2026-06-02T06:52:50.579495Z","end":"2026-06-02T06:52:50.703383Z","steps":["trace[1823677905] 'agreement among raft nodes before linearized reading' (duration: 123.730123ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:52:58.945316Z","caller":"traceutil/trace.go:171","msg":"trace[1893253110] linearizableReadLoop","detail":"{readStateIndex:1493; appliedIndex:1492; }","duration":"186.204091ms","start":"2026-06-02T06:52:58.759092Z","end":"2026-06-02T06:52:58.945296Z","steps":["trace[1893253110] 'read index received' (duration: 179.874927ms)","trace[1893253110] 'applied index is now lower than readState.Index' (duration: 6.328244ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T06:52:58.945418Z","caller":"traceutil/trace.go:171","msg":"trace[1777016842] transaction","detail":"{read_only:false; response_revision:1427; number_of_response:1; }","duration":"193.06165ms","start":"2026-06-02T06:52:58.752348Z","end":"2026-06-02T06:52:58.94541Z","steps":["trace[1777016842] 'process raft request' (duration: 186.671956ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:52:58.945744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.120364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-06-02T06:52:58.945809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.698387ms","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-06-02T06:52:58.945846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.999466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:node\" ","response":"range_response_count:1 size:603"} {"level":"info","ts":"2026-06-02T06:52:58.945874Z","caller":"traceutil/trace.go:171","msg":"trace[949259572] range","detail":"{range_begin:/registry/clusterrolebindings/system:node; range_end:; response_count:1; response_revision:1427; }","duration":"122.064276ms","start":"2026-06-02T06:52:58.823802Z","end":"2026-06-02T06:52:58.945866Z","steps":["trace[949259572] 'agreement among raft nodes before linearized reading' (duration: 122.017516ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:52:58.945872Z","caller":"traceutil/trace.go:171","msg":"trace[1091590247] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1427; }","duration":"186.807077ms","start":"2026-06-02T06:52:58.759051Z","end":"2026-06-02T06:52:58.945858Z","steps":["trace[1091590247] 'agreement among raft nodes before linearized reading' (duration: 186.656916ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:52:58.945858Z","caller":"traceutil/trace.go:171","msg":"trace[1719434018] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1427; }","duration":"182.233315ms","start":"2026-06-02T06:52:58.763561Z","end":"2026-06-02T06:52:58.945794Z","steps":["trace[1719434018] 'agreement among raft nodes before linearized reading' (duration: 181.873131ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:52:59.577422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.801398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:1 size:339"} {"level":"info","ts":"2026-06-02T06:52:59.577498Z","caller":"traceutil/trace.go:171","msg":"trace[1739878041] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:1; response_revision:1429; }","duration":"100.941079ms","start":"2026-06-02T06:52:59.47654Z","end":"2026-06-02T06:52:59.577481Z","steps":["trace[1739878041] 'range keys from in-memory index tree' (duration: 100.674067ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:52:59.577734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.874072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-06-02T06:52:59.577771Z","caller":"traceutil/trace.go:171","msg":"trace[1991701147] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:1429; }","duration":"159.943273ms","start":"2026-06-02T06:52:59.417818Z","end":"2026-06-02T06:52:59.577762Z","steps":["trace[1991701147] 'range keys from in-memory index tree' (duration: 159.719161ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:53:00.896005Z","caller":"traceutil/trace.go:171","msg":"trace[176726392] transaction","detail":"{read_only:false; response_revision:1432; number_of_response:1; }","duration":"143.50697ms","start":"2026-06-02T06:53:00.752456Z","end":"2026-06-02T06:53:00.895963Z","steps":["trace[176726392] 'process raft request' (duration: 143.273927ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:53:01.135745Z","caller":"traceutil/trace.go:171","msg":"trace[671838652] linearizableReadLoop","detail":"{readStateIndex:1499; appliedIndex:1498; }","duration":"158.001886ms","start":"2026-06-02T06:53:00.977718Z","end":"2026-06-02T06:53:01.13572Z","steps":["trace[671838652] 'read index received' (duration: 157.785923ms)","trace[671838652] 'applied index is now lower than readState.Index' (duration: 215.143µs)"],"step_count":2} {"level":"info","ts":"2026-06-02T06:53:01.135837Z","caller":"traceutil/trace.go:171","msg":"trace[1631690994] transaction","detail":"{read_only:false; response_revision:1433; number_of_response:1; }","duration":"182.629291ms","start":"2026-06-02T06:53:00.953182Z","end":"2026-06-02T06:53:01.135811Z","steps":["trace[1631690994] 'process raft request' (duration: 182.389018ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:53:01.135939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.195278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-02T06:53:01.135999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.873942ms","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-06-02T06:53:01.136041Z","caller":"traceutil/trace.go:171","msg":"trace[2054650888] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1433; }","duration":"158.34111ms","start":"2026-06-02T06:53:00.977677Z","end":"2026-06-02T06:53:01.136018Z","steps":["trace[2054650888] 'agreement among raft nodes before linearized reading' (duration: 158.164158ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:53:01.136052Z","caller":"traceutil/trace.go:171","msg":"trace[1005889914] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1433; }","duration":"130.972453ms","start":"2026-06-02T06:53:01.005063Z","end":"2026-06-02T06:53:01.136036Z","steps":["trace[1005889914] 'agreement among raft nodes before linearized reading' (duration: 130.795421ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:53:03.255246Z","caller":"traceutil/trace.go:171","msg":"trace[1412735356] linearizableReadLoop","detail":"{readStateIndex:1506; appliedIndex:1505; }","duration":"105.08462ms","start":"2026-06-02T06:53:03.15014Z","end":"2026-06-02T06:53:03.255224Z","steps":["trace[1412735356] 'read index received' (duration: 104.966249ms)","trace[1412735356] 'applied index is now lower than readState.Index' (duration: 117.381µs)"],"step_count":2} {"level":"info","ts":"2026-06-02T06:53:03.255294Z","caller":"traceutil/trace.go:171","msg":"trace[81206687] transaction","detail":"{read_only:false; response_revision:1439; number_of_response:1; }","duration":"112.659207ms","start":"2026-06-02T06:53:03.142597Z","end":"2026-06-02T06:53:03.255256Z","steps":["trace[81206687] 'process raft request' (duration: 112.477785ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:53:03.255412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.249192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-node-lease/\" range_end:\"/registry/daemonsets/kube-node-lease0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T06:53:03.255684Z","caller":"traceutil/trace.go:171","msg":"trace[1638365000] range","detail":"{range_begin:/registry/daemonsets/kube-node-lease/; range_end:/registry/daemonsets/kube-node-lease0; response_count:0; response_revision:1439; }","duration":"105.555396ms","start":"2026-06-02T06:53:03.1501Z","end":"2026-06-02T06:53:03.255655Z","steps":["trace[1638365000] 'agreement among raft nodes before linearized reading' (duration: 105.239302ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:53:03.256004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.301268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/default/default\" ","response":"range_response_count:1 size:171"} {"level":"info","ts":"2026-06-02T06:53:03.256088Z","caller":"traceutil/trace.go:171","msg":"trace[1457847138] range","detail":"{range_begin:/registry/serviceaccounts/default/default; range_end:; response_count:1; response_revision:1439; }","duration":"102.42325ms","start":"2026-06-02T06:53:03.153645Z","end":"2026-06-02T06:53:03.256068Z","steps":["trace[1457847138] 'agreement among raft nodes before linearized reading' (duration: 101.712811ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:53:09.231132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.742583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/kube-system/kubeadm:nodes-kubeadm-config\" ","response":"range_response_count:1 size:357"} {"level":"info","ts":"2026-06-02T06:53:09.231254Z","caller":"traceutil/trace.go:171","msg":"trace[1474293853] range","detail":"{range_begin:/registry/roles/kube-system/kubeadm:nodes-kubeadm-config; range_end:; response_count:1; response_revision:1460; }","duration":"170.907315ms","start":"2026-06-02T06:53:09.060312Z","end":"2026-06-02T06:53:09.231219Z","steps":["trace[1474293853] 'range keys from in-memory index tree' (duration: 170.58069ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:53:09.231319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.994033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/local-path-provisioner-679c578f5-sdnmx\" ","response":"range_response_count:1 size:3720"} {"level":"info","ts":"2026-06-02T06:53:09.231393Z","caller":"traceutil/trace.go:171","msg":"trace[1859234080] range","detail":"{range_begin:/registry/pods/local-path-storage/local-path-provisioner-679c578f5-sdnmx; range_end:; response_count:1; response_revision:1460; }","duration":"162.113805ms","start":"2026-06-02T06:53:09.069261Z","end":"2026-06-02T06:53:09.231375Z","steps":["trace[1859234080] 'range keys from in-memory index tree' (duration: 161.81141ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:53:09.46889Z","caller":"traceutil/trace.go:171","msg":"trace[2074052108] transaction","detail":"{read_only:false; response_revision:1462; number_of_response:1; }","duration":"117.574795ms","start":"2026-06-02T06:53:09.351291Z","end":"2026-06-02T06:53:09.468866Z","steps":["trace[2074052108] 'process raft request' (duration: 101.868116ms)","trace[2074052108] 'compare' (duration: 15.269864ms)"],"step_count":2}