{"level":"warn","ts":"2026-02-02T17:42:34.446946Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-02T17:42:34.447947Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.215: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.215:2380","--initial-cluster=instance=https://199.19.213.215:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.215:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.215: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-02-02T17:42:34.448227Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-02T17:42:34.448583Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.215:2380"]} {"level":"info","ts":"2026-02-02T17:42:34.448745Z","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-02-02T17:42:34.453336Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.215:2379"]} {"level":"info","ts":"2026-02-02T17:42:34.453628Z","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":2,"max-cpu-available":2,"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.215:2380"],"listen-peer-urls":["https://199.19.213.215:2380"],"advertise-client-urls":["https://199.19.213.215:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.215:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.215: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-02-02T17:42:34.469526Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"11.472212ms"} {"level":"info","ts":"2026-02-02T17:42:34.533665Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"e6e5712872899056","cluster-id":"5d812b997db5c8f"} {"level":"info","ts":"2026-02-02T17:42:34.533888Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e6e5712872899056 switched to configuration voters=()"} {"level":"info","ts":"2026-02-02T17:42:34.533955Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e6e5712872899056 became follower at term 0"} {"level":"info","ts":"2026-02-02T17:42:34.533977Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft e6e5712872899056 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-02T17:42:34.534049Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e6e5712872899056 became follower at term 1"} {"level":"info","ts":"2026-02-02T17:42:34.534134Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e6e5712872899056 switched to configuration voters=(16637828816924414038)"} {"level":"warn","ts":"2026-02-02T17:42:34.541342Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-02T17:42:34.542628Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-02T17:42:34.543709Z","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-02-02T17:42:34.550752Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"e6e5712872899056","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-02T17:42:34.559444Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"e6e5712872899056","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-02T17:42:34.559649Z","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-02-02T17:42:34.559725Z","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-02-02T17:42:34.55978Z","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-02-02T17:42:34.559987Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e6e5712872899056 switched to configuration voters=(16637828816924414038)"} {"level":"info","ts":"2026-02-02T17:42:34.560111Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"5d812b997db5c8f","local-member-id":"e6e5712872899056","added-peer-id":"e6e5712872899056","added-peer-peer-urls":["https://199.19.213.215:2380"]} {"level":"info","ts":"2026-02-02T17:42:34.586837Z","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-02-02T17:42:34.587147Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.215:2380"} {"level":"info","ts":"2026-02-02T17:42:34.587205Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.215:2380"} {"level":"info","ts":"2026-02-02T17:42:34.588536Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"e6e5712872899056","initial-advertise-peer-urls":["https://199.19.213.215:2380"],"listen-peer-urls":["https://199.19.213.215:2380"],"advertise-client-urls":["https://199.19.213.215:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.215:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-02T17:42:34.588566Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-02T17:42:35.137436Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e6e5712872899056 is starting a new election at term 1"} {"level":"info","ts":"2026-02-02T17:42:35.137525Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e6e5712872899056 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-02T17:42:35.141403Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e6e5712872899056 received MsgPreVoteResp from e6e5712872899056 at term 1"} {"level":"info","ts":"2026-02-02T17:42:35.141443Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e6e5712872899056 became candidate at term 2"} {"level":"info","ts":"2026-02-02T17:42:35.141463Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e6e5712872899056 received MsgVoteResp from e6e5712872899056 at term 2"} {"level":"info","ts":"2026-02-02T17:42:35.141484Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e6e5712872899056 became leader at term 2"} {"level":"info","ts":"2026-02-02T17:42:35.141514Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: e6e5712872899056 elected leader e6e5712872899056 at term 2"} {"level":"info","ts":"2026-02-02T17:42:35.145487Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-02T17:42:35.149547Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"e6e5712872899056","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.215:2379]}","request-path":"/0/members/e6e5712872899056/attributes","cluster-id":"5d812b997db5c8f","publish-timeout":"7s"} {"level":"info","ts":"2026-02-02T17:42:35.149692Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-02T17:42:35.14998Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-02T17:42:35.151061Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-02T17:42:35.151102Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-02T17:42:35.151258Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"5d812b997db5c8f","local-member-id":"e6e5712872899056","cluster-version":"3.5"} {"level":"info","ts":"2026-02-02T17:42:35.151333Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-02T17:42:35.151372Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-02T17:42:35.152606Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.215:2379"} {"level":"info","ts":"2026-02-02T17:42:35.165542Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-02-02T17:42:43.492656Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10400672048345589985,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-02T17:42:43.99301Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10400672048345589985,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-02T17:42:44.493799Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10400672048345589985,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-02T17:42:44.994282Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10400672048345589985,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-02T17:42:45.494724Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10400672048345589985,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-02T17:42:45.995008Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10400672048345589985,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-02T17:42:46.495596Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10400672048345589985,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-02T17:42:46.996374Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10400672048345589985,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-02T17:42:47.497173Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10400672048345589985,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-02T17:42:47.991749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.999702426s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-02T17:42:47.991854Z","caller":"traceutil/trace.go:171","msg":"trace[2143606275] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; }","duration":"4.999867748s","start":"2026-02-02T17:42:42.991961Z","end":"2026-02-02T17:42:47.991829Z","steps":["trace[2143606275] 'agreement among raft nodes before linearized reading' (duration: 4.999731447s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:47.991927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:42.991916Z","time spent":"5.000000539s","remote":"127.0.0.1:43340","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/02/02 17:42:47 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-02T17:42:47.998208Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10400672048345589985,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-02T17:42:48.138199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.000224693s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-02T17:42:48.138273Z","caller":"traceutil/trace.go:171","msg":"trace[530451208] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; }","duration":"5.000339705s","start":"2026-02-02T17:42:43.137916Z","end":"2026-02-02T17:42:48.138255Z","steps":["trace[530451208] 'agreement among raft nodes before linearized reading' (duration: 5.000244644s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:48.13832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:43.137902Z","time spent":"5.000410535s","remote":"127.0.0.1:43340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-02T17:42:48.49834Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10400672048345589985,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-02T17:42:48.619459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:41.61908Z","time spent":"7.000373193s","remote":"127.0.0.1:43262","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-02-02T17:42:48.929357Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"7.310125001s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-02T17:42:49.35845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.436212ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"warn","ts":"2026-02-02T17:42:50.7209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.066609752s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-02T17:42:50.721005Z","caller":"traceutil/trace.go:171","msg":"trace[620993943] linearizableReadLoop","detail":"{readStateIndex:340; appliedIndex:338; }","duration":"7.728971839s","start":"2026-02-02T17:42:42.992007Z","end":"2026-02-02T17:42:50.720978Z","steps":["trace[620993943] 'read index received' (duration: 5.93760394s)","trace[620993943] 'applied index is now lower than readState.Index' (duration: 1.791366919s)"],"step_count":2} {"level":"info","ts":"2026-02-02T17:42:50.72124Z","caller":"traceutil/trace.go:171","msg":"trace[947365059] transaction","detail":"{read_only:false; response_revision:329; number_of_response:1; }","duration":"3.295294139s","start":"2026-02-02T17:42:47.425932Z","end":"2026-02-02T17:42:50.721226Z","steps":["trace[947365059] 'process raft request' (duration: 2.228137752s)","trace[947365059] 'compare' (duration: 1.066497391s)"],"step_count":2} {"level":"warn","ts":"2026-02-02T17:42:50.721519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:47.425906Z","time spent":"3.295577093s","remote":"127.0.0.1:43340","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-02-02T17:42:50.72335Z","caller":"traceutil/trace.go:171","msg":"trace[1310602202] transaction","detail":"{read_only:false; response_revision:330; number_of_response:1; }","duration":"1.305014552s","start":"2026-02-02T17:42:49.418321Z","end":"2026-02-02T17:42:50.723335Z","steps":["trace[1310602202] 'process raft request' (duration: 1.304939371s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:50.723428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:49.418293Z","time spent":"1.305104392s","remote":"127.0.0.1:43144","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":858,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-02T17:42:52.160707Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.43751586s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-02T17:42:52.354241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.333734ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-02T17:42:52.354314Z","caller":"traceutil/trace.go:171","msg":"trace[377009774] linearizableReadLoop","detail":"{readStateIndex:342; appliedIndex:341; }","duration":"1.633245445s","start":"2026-02-02T17:42:50.721054Z","end":"2026-02-02T17:42:52.3543Z","steps":["trace[377009774] 'read index received' (duration: 2.602273ms)","trace[377009774] 'applied index is now lower than readState.Index' (duration: 1.630642192s)"],"step_count":2} {"level":"info","ts":"2026-02-02T17:42:52.354563Z","caller":"traceutil/trace.go:171","msg":"trace[1480366423] transaction","detail":"{read_only:false; response_revision:331; number_of_response:1; }","duration":"2.111376165s","start":"2026-02-02T17:42:50.243174Z","end":"2026-02-02T17:42:52.35455Z","steps":["trace[1480366423] 'process raft request' (duration: 1.917679618s)","trace[1480366423] 'compare' (duration: 192.285575ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T17:42:52.354613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:50.24314Z","time spent":"2.111448345s","remote":"127.0.0.1:43340","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-02-02T17:42:52.354742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.361111855s","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-02-02T17:42:52.354762Z","caller":"traceutil/trace.go:171","msg":"trace[2093123076] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:331; }","duration":"2.361130615s","start":"2026-02-02T17:42:49.993624Z","end":"2026-02-02T17:42:52.354755Z","steps":["trace[2093123076] 'agreement among raft nodes before linearized reading' (duration: 2.361092074s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:52.354784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:49.993564Z","time spent":"2.361214006s","remote":"127.0.0.1:43340","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-02-02T17:42:52.354886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.516278353s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4260"} {"level":"info","ts":"2026-02-02T17:42:52.354905Z","caller":"traceutil/trace.go:171","msg":"trace[350680508] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:331; }","duration":"3.516347224s","start":"2026-02-02T17:42:48.838552Z","end":"2026-02-02T17:42:52.354899Z","steps":["trace[350680508] 'agreement among raft nodes before linearized reading' (duration: 3.516311664s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:52.354921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:48.838537Z","time spent":"3.516380364s","remote":"127.0.0.1:43242","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":4283,"request content":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" "} {"level":"warn","ts":"2026-02-02T17:42:52.355347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"9.261884821s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-02T17:42:52.355378Z","caller":"traceutil/trace.go:171","msg":"trace[452513528] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:331; }","duration":"9.261929391s","start":"2026-02-02T17:42:43.09344Z","end":"2026-02-02T17:42:52.355369Z","steps":["trace[452513528] 'agreement among raft nodes before linearized reading' (duration: 9.261880141s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:52.355407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:43.093433Z","time spent":"9.261969851s","remote":"127.0.0.1:43340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-02T17:42:52.355504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"9.262086342s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-02-02T17:42:52.355532Z","caller":"traceutil/trace.go:171","msg":"trace[941632623] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:331; }","duration":"9.262186483s","start":"2026-02-02T17:42:43.093339Z","end":"2026-02-02T17:42:52.355525Z","steps":["trace[941632623] 'agreement among raft nodes before linearized reading' (duration: 9.262146293s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:52.355567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:43.093317Z","time spent":"9.262243984s","remote":"127.0.0.1:43340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-02T17:42:52.357119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.954710651s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.215\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-02T17:42:52.35715Z","caller":"traceutil/trace.go:171","msg":"trace[1040492949] range","detail":"{range_begin:/registry/masterleases/199.19.213.215; range_end:; response_count:1; response_revision:331; }","duration":"3.954775422s","start":"2026-02-02T17:42:48.402368Z","end":"2026-02-02T17:42:52.357143Z","steps":["trace[1040492949] 'agreement among raft nodes before linearized reading' (duration: 3.952633163s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:52.357169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:48.40235Z","time spent":"3.954813903s","remote":"127.0.0.1:43086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.215\" "} {"level":"warn","ts":"2026-02-02T17:42:52.993364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:52.363154Z","time spent":"630.204089ms","remote":"127.0.0.1:43340","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} 2026/02/02 17:42:52 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-02T17:42:53.095977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:52.363904Z","time spent":"732.068717ms","remote":"127.0.0.1:43340","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} 2026/02/02 17:42:53 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-02T17:42:53.096373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:52.363953Z","time spent":"732.41788ms","remote":"127.0.0.1:43340","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} 2026/02/02 17:42:53 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-02T17:42:53.882299Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10400672048345589992,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-02T17:42:54.382856Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10400672048345589992,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-02T17:42:54.865582Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.502342558s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-02T17:42:55.235288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.425537ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"warn","ts":"2026-02-02T17:42:55.381871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999928958s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-02T17:42:55.382001Z","caller":"traceutil/trace.go:171","msg":"trace[1116045127] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.00012862s","start":"2026-02-02T17:42:53.381842Z","end":"2026-02-02T17:42:55.381971Z","steps":["trace[1116045127] 'agreement among raft nodes before linearized reading' (duration: 1.999955169s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:55.382077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:53.381824Z","time spent":"2.000237791s","remote":"127.0.0.1:43054","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-02-02T17:42:55.773633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.841714ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-02-02T17:42:55.774138Z","caller":"traceutil/trace.go:171","msg":"trace[1524560423] transaction","detail":"{read_only:false; number_of_response:1; response_revision:335; }","duration":"2.677199783s","start":"2026-02-02T17:42:53.096914Z","end":"2026-02-02T17:42:55.774114Z","steps":["trace[1524560423] 'process raft request' (duration: 2.677161243s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:55.774288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:53.096906Z","time spent":"2.677304204s","remote":"127.0.0.1:43340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":46,"response count":0,"response size":465,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-02T17:42:55.774741Z","caller":"traceutil/trace.go:171","msg":"trace[1416977080] transaction","detail":"{read_only:false; response_revision:335; number_of_response:1; }","duration":"3.410099317s","start":"2026-02-02T17:42:52.364627Z","end":"2026-02-02T17:42:55.774726Z","steps":["trace[1416977080] 'process raft request' (duration: 3.409332581s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:55.774817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:52.364619Z","time spent":"3.410162418s","remote":"127.0.0.1:43242","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4245,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-02T17:42:55.774971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:52.363804Z","time spent":"3.411165327s","remote":"127.0.0.1:43086","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-02T17:42:55.774956Z","caller":"traceutil/trace.go:171","msg":"trace[1995629718] transaction","detail":"{read_only:false; number_of_response:1; response_revision:335; }","duration":"2.781241051s","start":"2026-02-02T17:42:52.993673Z","end":"2026-02-02T17:42:55.774914Z","steps":["trace[1995629718] 'process raft request' (duration: 2.780338423s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:55.775149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:52.993654Z","time spent":"2.781384542s","remote":"127.0.0.1:43340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":47,"response count":0,"response size":513,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-02T17:42:55.775315Z","caller":"traceutil/trace.go:171","msg":"trace[1375746628] transaction","detail":"{read_only:false; number_of_response:1; response_revision:335; }","duration":"2.678484884s","start":"2026-02-02T17:42:53.096818Z","end":"2026-02-02T17:42:55.775303Z","steps":["trace[1375746628] 'process raft request' (duration: 2.677235034s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:55.775405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:53.096795Z","time spent":"2.678578475s","remote":"127.0.0.1:43340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":48,"response count":0,"response size":469,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-02T17:42:56.13321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.092197ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-02T17:42:56.134183Z","caller":"traceutil/trace.go:171","msg":"trace[88223576] transaction","detail":"{read_only:false; number_of_response:1; response_revision:337; }","duration":"354.639149ms","start":"2026-02-02T17:42:55.779526Z","end":"2026-02-02T17:42:56.134165Z","steps":["trace[88223576] 'process raft request' (duration: 354.609829ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:56.134287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:55.779501Z","time spent":"354.720259ms","remote":"127.0.0.1:43086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":41,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-02T17:42:56.134481Z","caller":"traceutil/trace.go:171","msg":"trace[713577080] linearizableReadLoop","detail":"{readStateIndex:351; appliedIndex:342; }","duration":"2.752587706s","start":"2026-02-02T17:42:53.381883Z","end":"2026-02-02T17:42:56.13447Z","steps":["trace[713577080] 'read index received' (duration: 1.483903884s)","trace[713577080] 'applied index is now lower than readState.Index' (duration: 1.268682802s)"],"step_count":2} {"level":"info","ts":"2026-02-02T17:42:56.134548Z","caller":"traceutil/trace.go:171","msg":"trace[716445744] transaction","detail":"{read_only:false; response_revision:337; number_of_response:1; }","duration":"744.69704ms","start":"2026-02-02T17:42:55.389844Z","end":"2026-02-02T17:42:56.134541Z","steps":["trace[716445744] 'process raft request' (duration: 744.211206ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:56.134592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:55.389793Z","time spent":"744.77326ms","remote":"127.0.0.1:43144","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":757,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-02T17:42:56.134799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.991236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:5270"} {"level":"info","ts":"2026-02-02T17:42:56.134831Z","caller":"traceutil/trace.go:171","msg":"trace[999240897] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:337; }","duration":"345.065396ms","start":"2026-02-02T17:42:55.789756Z","end":"2026-02-02T17:42:56.134821Z","steps":["trace[999240897] 'agreement among raft nodes before linearized reading' (duration: 344.982666ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:56.134857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:55.789729Z","time spent":"345.122717ms","remote":"127.0.0.1:43242","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":5293,"request content":"key:\"/registry/pods/kube-system/etcd-instance\" "} {"level":"warn","ts":"2026-02-02T17:42:56.499654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.555239ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-02T17:42:56.499781Z","caller":"traceutil/trace.go:171","msg":"trace[947407595] transaction","detail":"{read_only:false; response_revision:339; number_of_response:1; }","duration":"313.95198ms","start":"2026-02-02T17:42:56.185815Z","end":"2026-02-02T17:42:56.499767Z","steps":["trace[947407595] 'process raft request' (duration: 313.91781ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:56.49983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:56.185781Z","time spent":"314.02578ms","remote":"127.0.0.1:43086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-02T17:42:56.500047Z","caller":"traceutil/trace.go:171","msg":"trace[244064427] transaction","detail":"{read_only:false; response_revision:338; number_of_response:1; }","duration":"348.828069ms","start":"2026-02-02T17:42:56.151207Z","end":"2026-02-02T17:42:56.500035Z","steps":["trace[244064427] 'process raft request' (duration: 215.833996ms)","trace[244064427] 'compare' (duration: 132.408578ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T17:42:56.500095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:56.151179Z","time spent":"348.89743ms","remote":"127.0.0.1:43242","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5083,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-02T17:42:56.881533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.505951ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-02T17:42:56.881651Z","caller":"traceutil/trace.go:171","msg":"trace[1503608893] linearizableReadLoop","detail":"{readStateIndex:357; appliedIndex:356; }","duration":"378.848869ms","start":"2026-02-02T17:42:56.502785Z","end":"2026-02-02T17:42:56.881633Z","steps":["trace[1503608893] 'read index received' (duration: 247.082546ms)","trace[1503608893] 'applied index is now lower than readState.Index' (duration: 131.764393ms)"],"step_count":2} {"level":"info","ts":"2026-02-02T17:42:56.881682Z","caller":"traceutil/trace.go:171","msg":"trace[167832192] transaction","detail":"{read_only:false; response_revision:340; number_of_response:1; }","duration":"396.302229ms","start":"2026-02-02T17:42:56.48534Z","end":"2026-02-02T17:42:56.881642Z","steps":["trace[167832192] 'process raft request' (duration: 264.587876ms)","trace[167832192] 'compare' (duration: 131.201039ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T17:42:56.88181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.01483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-02-02T17:42:56.881842Z","caller":"traceutil/trace.go:171","msg":"trace[804475087] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:340; }","duration":"379.075561ms","start":"2026-02-02T17:42:56.502757Z","end":"2026-02-02T17:42:56.881832Z","steps":["trace[804475087] 'agreement among raft nodes before linearized reading' (duration: 378.96494ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:56.88187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:56.485323Z","time spent":"396.46736ms","remote":"127.0.0.1:43144","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":746,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-02T17:42:56.881879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:56.502744Z","time spent":"379.124751ms","remote":"127.0.0.1:43224","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":445,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"warn","ts":"2026-02-02T17:42:56.882111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.522476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4260"} {"level":"info","ts":"2026-02-02T17:42:56.882145Z","caller":"traceutil/trace.go:171","msg":"trace[135498762] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:340; }","duration":"378.581646ms","start":"2026-02-02T17:42:56.503554Z","end":"2026-02-02T17:42:56.882136Z","steps":["trace[135498762] 'agreement among raft nodes before linearized reading' (duration: 378.521736ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:56.882171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:56.503542Z","time spent":"378.624037ms","remote":"127.0.0.1:43242","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":4283,"request content":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" "} {"level":"warn","ts":"2026-02-02T17:42:57.3951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.041553ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-02T17:42:57.395257Z","caller":"traceutil/trace.go:171","msg":"trace[1175618136] transaction","detail":"{read_only:false; response_revision:342; number_of_response:1; }","duration":"460.669369ms","start":"2026-02-02T17:42:56.934572Z","end":"2026-02-02T17:42:57.395241Z","steps":["trace[1175618136] 'process raft request' (duration: 460.615949ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:57.395312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:56.934566Z","time spent":"460.72288ms","remote":"127.0.0.1:43144","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":823,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-02T17:42:57.395648Z","caller":"traceutil/trace.go:171","msg":"trace[1830909633] transaction","detail":"{read_only:false; response_revision:341; number_of_response:1; }","duration":"462.408934ms","start":"2026-02-02T17:42:56.933226Z","end":"2026-02-02T17:42:57.395635Z","steps":["trace[1830909633] 'process raft request' (duration: 331.762215ms)","trace[1830909633] 'compare' (duration: 129.831482ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T17:42:57.39571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:56.933209Z","time spent":"462.466154ms","remote":"127.0.0.1:43242","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4053,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-02T17:42:57.936642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.143792ms","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-02-02T17:42:57.936835Z","caller":"traceutil/trace.go:171","msg":"trace[270639287] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:342; }","duration":"251.343383ms","start":"2026-02-02T17:42:57.685474Z","end":"2026-02-02T17:42:57.936817Z","steps":["trace[270639287] 'range keys from in-memory index tree' (duration: 251.128792ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:57.937272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.875789ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-02T17:42:57.937344Z","caller":"traceutil/trace.go:171","msg":"trace[400414140] linearizableReadLoop","detail":"{readStateIndex:360; appliedIndex:359; }","duration":"534.62736ms","start":"2026-02-02T17:42:57.402685Z","end":"2026-02-02T17:42:57.937312Z","steps":["trace[400414140] 'read index received' (duration: 206.61777ms)","trace[400414140] 'applied index is now lower than readState.Index' (duration: 328.00865ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T17:42:57.937465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"534.775471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:4597"} {"level":"info","ts":"2026-02-02T17:42:57.937491Z","caller":"traceutil/trace.go:171","msg":"trace[1441177315] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:343; }","duration":"534.820251ms","start":"2026-02-02T17:42:57.402663Z","end":"2026-02-02T17:42:57.937483Z","steps":["trace[1441177315] 'agreement among raft nodes before linearized reading' (duration: 534.70197ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:57.937521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:57.402656Z","time spent":"534.856941ms","remote":"127.0.0.1:43242","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":4620,"request content":"key:\"/registry/pods/kube-system/kube-vip-instance\" "} {"level":"info","ts":"2026-02-02T17:42:57.93767Z","caller":"traceutil/trace.go:171","msg":"trace[278050695] transaction","detail":"{read_only:false; response_revision:343; number_of_response:1; }","duration":"535.432687ms","start":"2026-02-02T17:42:57.402226Z","end":"2026-02-02T17:42:57.937659Z","steps":["trace[278050695] 'process raft request' (duration: 207.125064ms)","trace[278050695] 'compare' (duration: 327.038852ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T17:42:57.937715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:57.402212Z","time spent":"535.480437ms","remote":"127.0.0.1:43144","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":777,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-02T17:42:58.262314Z","caller":"traceutil/trace.go:171","msg":"trace[1077956635] transaction","detail":"{read_only:false; response_revision:344; number_of_response:1; }","duration":"310.885584ms","start":"2026-02-02T17:42:57.951381Z","end":"2026-02-02T17:42:58.262267Z","steps":["trace[1077956635] 'process raft request' (duration: 310.752043ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:58.262493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:57.951341Z","time spent":"311.060056ms","remote":"127.0.0.1:43242","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4865,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-02T17:42:58.80775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.941088ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-02T17:42:58.807845Z","caller":"traceutil/trace.go:171","msg":"trace[640487256] linearizableReadLoop","detail":"{readStateIndex:362; appliedIndex:361; }","duration":"405.030125ms","start":"2026-02-02T17:42:58.402801Z","end":"2026-02-02T17:42:58.807831Z","steps":["trace[640487256] 'read index received' (duration: 178.886306ms)","trace[640487256] 'applied index is now lower than readState.Index' (duration: 226.142839ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T17:42:58.807952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.148966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.215\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-02T17:42:58.807986Z","caller":"traceutil/trace.go:171","msg":"trace[326224135] range","detail":"{range_begin:/registry/masterleases/199.19.213.215; range_end:; response_count:1; response_revision:345; }","duration":"405.224746ms","start":"2026-02-02T17:42:58.402753Z","end":"2026-02-02T17:42:58.807977Z","steps":["trace[326224135] 'agreement among raft nodes before linearized reading' (duration: 405.133696ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:58.808024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:58.402731Z","time spent":"405.286157ms","remote":"127.0.0.1:43086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.215\" "} {"level":"info","ts":"2026-02-02T17:42:58.808357Z","caller":"traceutil/trace.go:171","msg":"trace[573045439] transaction","detail":"{read_only:false; response_revision:345; number_of_response:1; }","duration":"532.34305ms","start":"2026-02-02T17:42:58.276002Z","end":"2026-02-02T17:42:58.808345Z","steps":["trace[573045439] 'process raft request' (duration: 305.734227ms)","trace[573045439] 'compare' (duration: 225.823377ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T17:42:58.808423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:58.275963Z","time spent":"532.428332ms","remote":"127.0.0.1:43242","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6986,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-02T17:42:59.069622Z","caller":"traceutil/trace.go:171","msg":"trace[516268293] linearizableReadLoop","detail":"{readStateIndex:363; appliedIndex:362; }","duration":"254.380288ms","start":"2026-02-02T17:42:58.815218Z","end":"2026-02-02T17:42:59.069598Z","steps":["trace[516268293] 'read index received' (duration: 237.315323ms)","trace[516268293] 'applied index is now lower than readState.Index' (duration: 17.063515ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T17:42:59.069842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.59629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4068"} {"level":"info","ts":"2026-02-02T17:42:59.069882Z","caller":"traceutil/trace.go:171","msg":"trace[1772084093] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:345; }","duration":"254.67998ms","start":"2026-02-02T17:42:58.815191Z","end":"2026-02-02T17:42:59.069871Z","steps":["trace[1772084093] 'agreement among raft nodes before linearized reading' (duration: 254.501109ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:59.582102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.255112ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-02T17:42:59.582682Z","caller":"traceutil/trace.go:171","msg":"trace[882771846] transaction","detail":"{read_only:false; response_revision:347; number_of_response:1; }","duration":"497.647417ms","start":"2026-02-02T17:42:59.085003Z","end":"2026-02-02T17:42:59.58265Z","steps":["trace[882771846] 'process raft request' (duration: 235.722777ms)","trace[882771846] 'compare' (duration: 261.10659ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T17:42:59.582788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:59.084988Z","time spent":"497.751168ms","remote":"127.0.0.1:43242","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4347,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-02T17:42:59.583955Z","caller":"traceutil/trace.go:171","msg":"trace[1892268945] transaction","detail":"{read_only:false; response_revision:348; number_of_response:1; }","duration":"254.592864ms","start":"2026-02-02T17:42:59.329343Z","end":"2026-02-02T17:42:59.583936Z","steps":["trace[1892268945] 'process raft request' (duration: 252.871833ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T17:42:59.585672Z","caller":"traceutil/trace.go:171","msg":"trace[1302091886] linearizableReadLoop","detail":"{readStateIndex:365; appliedIndex:364; }","duration":"496.552748ms","start":"2026-02-02T17:42:59.08906Z","end":"2026-02-02T17:42:59.585612Z","steps":["trace[1302091886] 'read index received' (duration: 231.609552ms)","trace[1302091886] 'applied index is now lower than readState.Index' (duration: 264.938486ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T17:42:59.586009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.905152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-02-02T17:42:59.586053Z","caller":"traceutil/trace.go:171","msg":"trace[771274601] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:348; }","duration":"497.003623ms","start":"2026-02-02T17:42:59.089037Z","end":"2026-02-02T17:42:59.58604Z","steps":["trace[771274601] 'agreement among raft nodes before linearized reading' (duration: 496.843721ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:59.586109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:59.08903Z","time spent":"497.069533ms","remote":"127.0.0.1:43224","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":445,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"warn","ts":"2026-02-02T17:42:59.586377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.004314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-02-02T17:42:59.586415Z","caller":"traceutil/trace.go:171","msg":"trace[1898921882] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:348; }","duration":"165.074895ms","start":"2026-02-02T17:42:59.42133Z","end":"2026-02-02T17:42:59.586405Z","steps":["trace[1898921882] 'agreement among raft nodes before linearized reading' (duration: 165.008004ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:59.586668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.776832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-02T17:42:59.586708Z","caller":"traceutil/trace.go:171","msg":"trace[1168930995] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:348; }","duration":"164.835062ms","start":"2026-02-02T17:42:59.421864Z","end":"2026-02-02T17:42:59.586699Z","steps":["trace[1168930995] 'agreement among raft nodes before linearized reading' (duration: 164.770582ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:43:01.479336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.267797828s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-02T17:43:01.47945Z","caller":"traceutil/trace.go:171","msg":"trace[357980520] linearizableReadLoop","detail":"{readStateIndex:369; appliedIndex:368; }","duration":"1.861713863s","start":"2026-02-02T17:42:59.617718Z","end":"2026-02-02T17:43:01.479432Z","steps":["trace[357980520] 'read index received' (duration: 13.299325ms)","trace[357980520] 'applied index is now lower than readState.Index' (duration: 1.848413308s)"],"step_count":2} {"level":"info","ts":"2026-02-02T17:43:01.479517Z","caller":"traceutil/trace.go:171","msg":"trace[2024979911] transaction","detail":"{read_only:false; response_revision:351; number_of_response:1; }","duration":"1.865206546s","start":"2026-02-02T17:42:59.6143Z","end":"2026-02-02T17:43:01.479507Z","steps":["trace[2024979911] 'process raft request' (duration: 597.168559ms)","trace[2024979911] 'compare' (duration: 1.267583111s)"],"step_count":2} {"level":"warn","ts":"2026-02-02T17:43:01.479563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:59.614281Z","time spent":"1.865260828s","remote":"127.0.0.1:43144","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":696,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-02T17:43:01.479799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.862074996s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-02T17:43:01.479828Z","caller":"traceutil/trace.go:171","msg":"trace[249940676] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:351; }","duration":"1.862137438s","start":"2026-02-02T17:42:59.617682Z","end":"2026-02-02T17:43:01.479819Z","steps":["trace[249940676] 'agreement among raft nodes before linearized reading' (duration: 1.862049255s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:43:01.479858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:59.617671Z","time spent":"1.86217892s","remote":"127.0.0.1:43340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-02T17:43:01.487882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.844443366s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-02T17:43:01.487944Z","caller":"traceutil/trace.go:171","msg":"trace[1567485173] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:352; }","duration":"1.84454701s","start":"2026-02-02T17:42:59.643386Z","end":"2026-02-02T17:43:01.487933Z","steps":["trace[1567485173] 'agreement among raft nodes before linearized reading' (duration: 1.844449696s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:43:01.487966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:59.643358Z","time spent":"1.844603011s","remote":"127.0.0.1:43340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-02T17:43:01.487967Z","caller":"traceutil/trace.go:171","msg":"trace[1430766404] transaction","detail":"{read_only:false; response_revision:352; number_of_response:1; }","duration":"619.318989ms","start":"2026-02-02T17:43:00.868624Z","end":"2026-02-02T17:43:01.487943Z","steps":["trace[1430766404] 'process raft request' (duration: 619.149023ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:43:01.488088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:43:00.86859Z","time spent":"619.429354ms","remote":"127.0.0.1:43340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-02T17:43:01.488095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"872.583056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:4879"} {"level":"info","ts":"2026-02-02T17:43:01.488121Z","caller":"traceutil/trace.go:171","msg":"trace[1671422781] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:352; }","duration":"872.652819ms","start":"2026-02-02T17:43:00.615459Z","end":"2026-02-02T17:43:01.488112Z","steps":["trace[1671422781] 'agreement among raft nodes before linearized reading' (duration: 872.602107ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:43:01.488139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:43:00.615407Z","time spent":"872.727042ms","remote":"127.0.0.1:43242","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":4902,"request content":"key:\"/registry/pods/kube-system/kube-vip-instance\" "} {"level":"info","ts":"2026-02-02T17:43:22.30008Z","caller":"traceutil/trace.go:171","msg":"trace[1725921892] transaction","detail":"{read_only:false; response_revision:432; number_of_response:1; }","duration":"519.689577ms","start":"2026-02-02T17:43:21.780347Z","end":"2026-02-02T17:43:22.300036Z","steps":["trace[1725921892] 'process raft request' (duration: 519.557334ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:43:22.300312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:43:21.780321Z","time spent":"519.853742ms","remote":"127.0.0.1:43574","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2125,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-02T17:43:22.437814Z","caller":"traceutil/trace.go:171","msg":"trace[115114890] linearizableReadLoop","detail":"{readStateIndex:456; appliedIndex:454; }","duration":"620.513029ms","start":"2026-02-02T17:43:21.81728Z","end":"2026-02-02T17:43:22.437793Z","steps":["trace[115114890] 'read index received' (duration: 482.713999ms)","trace[115114890] 'applied index is now lower than readState.Index' (duration: 137.79816ms)"],"step_count":2} {"level":"info","ts":"2026-02-02T17:43:22.438084Z","caller":"traceutil/trace.go:171","msg":"trace[581481654] transaction","detail":"{read_only:false; response_revision:434; number_of_response:1; }","duration":"334.169443ms","start":"2026-02-02T17:43:22.103898Z","end":"2026-02-02T17:43:22.438068Z","steps":["trace[581481654] 'process raft request' (duration: 333.841124ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:43:22.43828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:43:22.103877Z","time spent":"334.249755ms","remote":"127.0.0.1:43340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-02T17:43:22.438709Z","caller":"traceutil/trace.go:171","msg":"trace[1143925258] transaction","detail":"{read_only:false; response_revision:433; number_of_response:1; }","duration":"658.312882ms","start":"2026-02-02T17:43:21.780383Z","end":"2026-02-02T17:43:22.438696Z","steps":["trace[1143925258] 'process raft request' (duration: 657.247461ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:43:22.439071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:43:21.780364Z","time spent":"658.381124ms","remote":"127.0.0.1:43574","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10217,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-02T17:43:22.439279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"622.012542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-02-02T17:43:22.439321Z","caller":"traceutil/trace.go:171","msg":"trace[1746393104] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:434; }","duration":"622.082624ms","start":"2026-02-02T17:43:21.817228Z","end":"2026-02-02T17:43:22.43931Z","steps":["trace[1746393104] 'agreement among raft nodes before linearized reading' (duration: 621.740334ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:43:22.439951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:43:21.817204Z","time spent":"622.675032ms","remote":"127.0.0.1:43262","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":218,"request content":"key:\"/registry/serviceaccounts/kube-system/node-controller\" "} {"level":"warn","ts":"2026-02-02T17:43:22.446839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"597.936958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:4 size:329936"} {"level":"warn","ts":"2026-02-02T17:43:22.447515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.62207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T17:43:22.446886Z","caller":"traceutil/trace.go:171","msg":"trace[680835026] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:4; response_revision:434; }","duration":"598.00896ms","start":"2026-02-02T17:43:21.84886Z","end":"2026-02-02T17:43:22.446869Z","steps":["trace[680835026] 'agreement among raft nodes before linearized reading' (duration: 591.764979ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T17:43:22.447759Z","caller":"traceutil/trace.go:171","msg":"trace[229064596] range","detail":"{range_begin:/registry/limitranges/kube-system/; range_end:/registry/limitranges/kube-system0; response_count:0; response_revision:434; }","duration":"142.684041ms","start":"2026-02-02T17:43:22.304863Z","end":"2026-02-02T17:43:22.447547Z","steps":["trace[229064596] 'agreement among raft nodes before linearized reading' (duration: 142.62204ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:43:22.447864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:43:21.848844Z","time spent":"598.831334ms","remote":"127.0.0.1:43160","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":4,"response size":329959,"request content":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" "} {"level":"warn","ts":"2026-02-02T17:43:22.44987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.272517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"info","ts":"2026-02-02T17:43:22.450101Z","caller":"traceutil/trace.go:171","msg":"trace[487219240] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:434; }","duration":"333.499014ms","start":"2026-02-02T17:43:22.116536Z","end":"2026-02-02T17:43:22.450035Z","steps":["trace[487219240] 'agreement among raft nodes before linearized reading' (duration: 331.655911ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:43:22.450247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:43:22.116519Z","time spent":"333.697149ms","remote":"127.0.0.1:43262","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":257,"request content":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" "} {"level":"warn","ts":"2026-02-02T17:43:22.451608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.077954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-02-02T17:43:22.452359Z","caller":"traceutil/trace.go:171","msg":"trace[719359761] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:434; }","duration":"384.866347ms","start":"2026-02-02T17:43:22.067484Z","end":"2026-02-02T17:43:22.45235Z","steps":["trace[719359761] 'agreement among raft nodes before linearized reading' (duration: 384.061404ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:43:22.452391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:43:22.067392Z","time spent":"384.993501ms","remote":"127.0.0.1:43262","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":216,"request content":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" "} {"level":"warn","ts":"2026-02-02T17:43:22.451854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.314294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-02-02T17:43:22.453145Z","caller":"traceutil/trace.go:171","msg":"trace[1423899564] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:434; }","duration":"436.517939ms","start":"2026-02-02T17:43:22.016495Z","end":"2026-02-02T17:43:22.453013Z","steps":["trace[1423899564] 'agreement among raft nodes before linearized reading' (duration: 435.227892ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:43:22.453174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:43:22.016475Z","time spent":"436.691514ms","remote":"127.0.0.1:43262","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":230,"request content":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" "} {"level":"warn","ts":"2026-02-02T17:43:22.451952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"466.551613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T17:43:22.453509Z","caller":"traceutil/trace.go:171","msg":"trace[294274655] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:434; }","duration":"468.144139ms","start":"2026-02-02T17:43:21.985358Z","end":"2026-02-02T17:43:22.453502Z","steps":["trace[294274655] 'agreement among raft nodes before linearized reading' (duration: 466.517152ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:43:22.453626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:43:21.985344Z","time spent":"468.274913ms","remote":"127.0.0.1:43054","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-02-02T17:43:22.452041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"485.016592ms","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-02-02T17:43:22.454098Z","caller":"traceutil/trace.go:171","msg":"trace[114012361] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:434; }","duration":"487.107672ms","start":"2026-02-02T17:43:21.966981Z","end":"2026-02-02T17:43:22.454088Z","steps":["trace[114012361] 'agreement among raft nodes before linearized reading' (duration: 484.980421ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:43:22.454229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:43:21.966962Z","time spent":"487.258336ms","remote":"127.0.0.1:43262","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":232,"request content":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" "} {"level":"warn","ts":"2026-02-02T17:43:22.452168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"535.434882ms","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-02-02T17:43:22.457105Z","caller":"traceutil/trace.go:171","msg":"trace[1566420520] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:434; }","duration":"540.402356ms","start":"2026-02-02T17:43:21.91669Z","end":"2026-02-02T17:43:22.457092Z","steps":["trace[1566420520] 'agreement among raft nodes before linearized reading' (duration: 535.35929ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:43:22.45806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:43:21.916671Z","time spent":"541.377655ms","remote":"127.0.0.1:43262","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":237,"request content":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" "} {"level":"warn","ts":"2026-02-02T17:43:22.452336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"585.352839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:1 size:197"} {"level":"info","ts":"2026-02-02T17:43:22.459268Z","caller":"traceutil/trace.go:171","msg":"trace[39484894] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:434; }","duration":"592.308531ms","start":"2026-02-02T17:43:21.866948Z","end":"2026-02-02T17:43:22.459257Z","steps":["trace[39484894] 'agreement among raft nodes before linearized reading' (duration: 585.228666ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:43:22.459401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:43:21.866931Z","time spent":"592.416363ms","remote":"127.0.0.1:43262","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":220,"request content":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" "} {"level":"info","ts":"2026-02-02T17:43:27.585741Z","caller":"traceutil/trace.go:171","msg":"trace[35873614] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"220.319991ms","start":"2026-02-02T17:43:27.365403Z","end":"2026-02-02T17:43:27.585723Z","steps":["trace[35873614] 'process raft request' (duration: 218.180252ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:43:27.95336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.653133ms","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-02-02T17:43:27.953499Z","caller":"traceutil/trace.go:171","msg":"trace[818414638] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:549; }","duration":"267.805147ms","start":"2026-02-02T17:43:27.685678Z","end":"2026-02-02T17:43:27.953483Z","steps":["trace[818414638] 'range keys from in-memory index tree' (duration: 267.640772ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T17:43:27.954467Z","caller":"traceutil/trace.go:171","msg":"trace[1163371737] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"342.990745ms","start":"2026-02-02T17:43:27.611464Z","end":"2026-02-02T17:43:27.954454Z","steps":["trace[1163371737] 'process raft request' (duration: 335.56787ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:43:27.954565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:43:27.611456Z","time spent":"343.051646ms","remote":"127.0.0.1:43116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10353,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-02T17:43:27.955033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.395813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-02T17:43:27.95508Z","caller":"traceutil/trace.go:171","msg":"trace[919174449] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:550; }","duration":"264.468084ms","start":"2026-02-02T17:43:27.690598Z","end":"2026-02-02T17:43:27.955066Z","steps":["trace[919174449] 'agreement among raft nodes before linearized reading' (duration: 264.31818ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T17:43:27.955439Z","caller":"traceutil/trace.go:171","msg":"trace[1134167107] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"227.763395ms","start":"2026-02-02T17:43:27.727665Z","end":"2026-02-02T17:43:27.955428Z","steps":["trace[1134167107] 'process raft request' (duration: 227.715544ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T17:43:27.953999Z","caller":"traceutil/trace.go:171","msg":"trace[2140967564] linearizableReadLoop","detail":"{readStateIndex:575; appliedIndex:574; }","duration":"263.354784ms","start":"2026-02-02T17:43:27.690633Z","end":"2026-02-02T17:43:27.953988Z","steps":["trace[2140967564] 'read index received' (duration: 256.402972ms)","trace[2140967564] 'applied index is now lower than readState.Index' (duration: 6.951102ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T17:43:27.956363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.334646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-02T17:43:27.95641Z","caller":"traceutil/trace.go:171","msg":"trace[1258793772] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:551; }","duration":"258.411368ms","start":"2026-02-02T17:43:27.697975Z","end":"2026-02-02T17:43:27.956386Z","steps":["trace[1258793772] 'agreement among raft nodes before linearized reading' (duration: 258.294925ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T17:43:34.488609Z","caller":"traceutil/trace.go:171","msg":"trace[2144589029] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"175.508439ms","start":"2026-02-02T17:43:34.313076Z","end":"2026-02-02T17:43:34.488585Z","steps":["trace[2144589029] 'process raft request' (duration: 175.396886ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T17:43:34.51669Z","caller":"traceutil/trace.go:171","msg":"trace[1150553800] linearizableReadLoop","detail":"{readStateIndex:638; appliedIndex:636; }","duration":"149.802298ms","start":"2026-02-02T17:43:34.36687Z","end":"2026-02-02T17:43:34.516673Z","steps":["trace[1150553800] 'read index received' (duration: 121.612432ms)","trace[1150553800] 'applied index is now lower than readState.Index' (duration: 28.188966ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T17:43:34.51687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.970492ms","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-02-02T17:43:34.516892Z","caller":"traceutil/trace.go:171","msg":"trace[1919648440] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:611; }","duration":"150.058904ms","start":"2026-02-02T17:43:34.366826Z","end":"2026-02-02T17:43:34.516885Z","steps":["trace[1919648440] 'agreement among raft nodes before linearized reading' (duration: 149.91831ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T17:43:34.517074Z","caller":"traceutil/trace.go:171","msg":"trace[1034830258] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"203.552451ms","start":"2026-02-02T17:43:34.313515Z","end":"2026-02-02T17:43:34.517068Z","steps":["trace[1034830258] 'process raft request' (duration: 203.040568ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T17:43:34.700776Z","caller":"traceutil/trace.go:171","msg":"trace[975193052] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"180.726743ms","start":"2026-02-02T17:43:34.520027Z","end":"2026-02-02T17:43:34.700754Z","steps":["trace[975193052] 'process raft request' (duration: 179.077711ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:43:35.238603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.474645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T17:43:35.238654Z","caller":"traceutil/trace.go:171","msg":"trace[1741143825] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:615; }","duration":"257.529986ms","start":"2026-02-02T17:43:34.981109Z","end":"2026-02-02T17:43:35.238639Z","steps":["trace[1741143825] 'range keys from in-memory index tree' (duration: 257.418964ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T17:43:35.791293Z","caller":"traceutil/trace.go:171","msg":"trace[742500270] linearizableReadLoop","detail":"{readStateIndex:643; appliedIndex:642; }","duration":"425.393133ms","start":"2026-02-02T17:43:35.36588Z","end":"2026-02-02T17:43:35.791273Z","steps":["trace[742500270] 'read index received' (duration: 425.211158ms)","trace[742500270] 'applied index is now lower than readState.Index' (duration: 181.105µs)"],"step_count":2} {"level":"info","ts":"2026-02-02T17:43:35.791548Z","caller":"traceutil/trace.go:171","msg":"trace[134845967] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"464.848999ms","start":"2026-02-02T17:43:35.326687Z","end":"2026-02-02T17:43:35.791536Z","steps":["trace[134845967] 'process raft request' (duration: 464.453569ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:43:35.791632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:43:35.326676Z","time spent":"464.897751ms","remote":"127.0.0.1:43340","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-02-02T17:43:35.791799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.922056ms","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-02-02T17:43:35.791819Z","caller":"traceutil/trace.go:171","msg":"trace[1968570084] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:616; }","duration":"425.992568ms","start":"2026-02-02T17:43:35.365821Z","end":"2026-02-02T17:43:35.791813Z","steps":["trace[1968570084] 'agreement among raft nodes before linearized reading' (duration: 425.942897ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:43:35.791832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:43:35.365801Z","time spent":"426.027629ms","remote":"127.0.0.1:43340","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-02-02T17:43:36.9138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.063429621s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-02T17:43:36.913909Z","caller":"traceutil/trace.go:171","msg":"trace[1443072074] linearizableReadLoop","detail":"{readStateIndex:645; appliedIndex:644; }","duration":"932.86341ms","start":"2026-02-02T17:43:35.98103Z","end":"2026-02-02T17:43:36.913893Z","steps":["trace[1443072074] 'read index received' (duration: 23.651µs)","trace[1443072074] 'applied index is now lower than readState.Index' (duration: 932.838309ms)"],"step_count":2} {"level":"info","ts":"2026-02-02T17:43:36.913958Z","caller":"traceutil/trace.go:171","msg":"trace[1295603350] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"1.111580708s","start":"2026-02-02T17:43:35.802355Z","end":"2026-02-02T17:43:36.913936Z","steps":["trace[1295603350] 'process raft request' (duration: 47.943992ms)","trace[1295603350] 'compare' (duration: 1.063073322s)"],"step_count":2} {"level":"warn","ts":"2026-02-02T17:43:36.914011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"932.976643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T17:43:36.914047Z","caller":"traceutil/trace.go:171","msg":"trace[2080910445] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:618; }","duration":"933.033293ms","start":"2026-02-02T17:43:35.981004Z","end":"2026-02-02T17:43:36.914037Z","steps":["trace[2080910445] 'agreement among raft nodes before linearized reading' (duration: 932.971242ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:43:36.914064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:43:35.802339Z","time spent":"1.111667781s","remote":"127.0.0.1:43242","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-02T17:43:36.914081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:43:35.980991Z","time spent":"933.080915ms","remote":"127.0.0.1:43054","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-02-02T17:43:36.914332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.078931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-02T17:43:36.914368Z","caller":"traceutil/trace.go:171","msg":"trace[615725530] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:618; }","duration":"422.137673ms","start":"2026-02-02T17:43:36.49222Z","end":"2026-02-02T17:43:36.914357Z","steps":["trace[615725530] 'agreement among raft nodes before linearized reading' (duration: 422.03124ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:43:36.914393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:43:36.492207Z","time spent":"422.179823ms","remote":"127.0.0.1:43340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-02T17:43:36.914451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.263761ms","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-02-02T17:43:36.914484Z","caller":"traceutil/trace.go:171","msg":"trace[431031530] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:618; }","duration":"209.319002ms","start":"2026-02-02T17:43:36.705156Z","end":"2026-02-02T17:43:36.914475Z","steps":["trace[431031530] 'agreement among raft nodes before linearized reading' (duration: 209.200569ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:43:36.914559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.723848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-02T17:43:36.914582Z","caller":"traceutil/trace.go:171","msg":"trace[267851093] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:618; }","duration":"392.76944ms","start":"2026-02-02T17:43:36.521806Z","end":"2026-02-02T17:43:36.914575Z","steps":["trace[267851093] 'agreement among raft nodes before linearized reading' (duration: 392.706078ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:43:36.914602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:43:36.521791Z","time spent":"392.806531ms","remote":"127.0.0.1:43340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-02T17:43:37.484173Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10400672048345590631,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-02T17:43:37.985258Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10400672048345590631,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-02T17:43:38.024502Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.096458493s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-02T17:43:38.102467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.459382ms","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-02-02T17:43:38.10257Z","caller":"traceutil/trace.go:171","msg":"trace[696965041] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:618; }","duration":"416.575965ms","start":"2026-02-02T17:43:37.68598Z","end":"2026-02-02T17:43:38.102556Z","steps":["trace[696965041] 'range keys from in-memory index tree' (duration: 416.446881ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T17:43:38.103022Z","caller":"traceutil/trace.go:171","msg":"trace[692200073] linearizableReadLoop","detail":"{readStateIndex:648; appliedIndex:645; }","duration":"1.119863968s","start":"2026-02-02T17:43:36.983149Z","end":"2026-02-02T17:43:38.103013Z","steps":["trace[692200073] 'read index received' (duration: 1.041575545s)","trace[692200073] 'applied index is now lower than readState.Index' (duration: 78.287853ms)"],"step_count":2} {"level":"info","ts":"2026-02-02T17:43:38.103155Z","caller":"traceutil/trace.go:171","msg":"trace[1887085956] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"1.175187478s","start":"2026-02-02T17:43:36.92796Z","end":"2026-02-02T17:43:38.103148Z","steps":["trace[1887085956] 'process raft request' (duration: 1.09674714s)","trace[1887085956] 'compare' (duration: 78.175401ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T17:43:38.103212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:43:36.927942Z","time spent":"1.175231498s","remote":"127.0.0.1:43340","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-02-02T17:43:38.103306Z","caller":"traceutil/trace.go:171","msg":"trace[1099954446] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"1.175195548s","start":"2026-02-02T17:43:36.928105Z","end":"2026-02-02T17:43:38.1033Z","steps":["trace[1099954446] 'process raft request' (duration: 1.174856509s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:43:38.103338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:43:36.928098Z","time spent":"1.175222828s","remote":"127.0.0.1:43340","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-02T17:43:38.103406Z","caller":"traceutil/trace.go:171","msg":"trace[2104513832] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"1.171980756s","start":"2026-02-02T17:43:36.93142Z","end":"2026-02-02T17:43:38.1034Z","steps":["trace[2104513832] 'process raft request' (duration: 1.171568076s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:43:38.103436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:43:36.931406Z","time spent":"1.172013107s","remote":"127.0.0.1:43340","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-02-02T17:43:38.103516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.120365982s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T17:43:38.103532Z","caller":"traceutil/trace.go:171","msg":"trace[1526082273] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:621; }","duration":"1.120408422s","start":"2026-02-02T17:43:36.983118Z","end":"2026-02-02T17:43:38.103527Z","steps":["trace[1526082273] 'agreement among raft nodes before linearized reading' (duration: 1.120380461s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:43:38.103548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:43:36.983104Z","time spent":"1.120440734s","remote":"127.0.0.1:43054","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-02-02T17:43:38.103635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"685.82398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T17:43:38.103653Z","caller":"traceutil/trace.go:171","msg":"trace[979806302] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:621; }","duration":"685.861761ms","start":"2026-02-02T17:43:37.417785Z","end":"2026-02-02T17:43:38.103647Z","steps":["trace[979806302] 'agreement among raft nodes before linearized reading' (duration: 685.83334ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:43:38.103669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:43:37.417769Z","time spent":"685.894792ms","remote":"127.0.0.1:43492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":28,"request content":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true "} {"level":"warn","ts":"2026-02-02T17:43:38.104783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.06134ms","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-02-02T17:43:38.104811Z","caller":"traceutil/trace.go:171","msg":"trace[1754328394] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:621; }","duration":"293.111851ms","start":"2026-02-02T17:43:37.811693Z","end":"2026-02-02T17:43:38.104805Z","steps":["trace[1754328394] 'agreement among raft nodes before linearized reading' (duration: 293.026099ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:43:38.104925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.298212ms","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-02-02T17:43:38.104944Z","caller":"traceutil/trace.go:171","msg":"trace[2022383268] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:621; }","duration":"310.334912ms","start":"2026-02-02T17:43:37.794603Z","end":"2026-02-02T17:43:38.104938Z","steps":["trace[2022383268] 'agreement among raft nodes before linearized reading' (duration: 310.282281ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:43:38.104963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:43:37.79459Z","time spent":"310.366623ms","remote":"127.0.0.1:43340","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-02-02T17:45:00.99661Z","caller":"traceutil/trace.go:171","msg":"trace[1963074264] linearizableReadLoop","detail":"{readStateIndex:1021; appliedIndex:1020; }","duration":"329.636259ms","start":"2026-02-02T17:45:00.666947Z","end":"2026-02-02T17:45:00.996584Z","steps":["trace[1963074264] 'read index received' (duration: 284.045435ms)","trace[1963074264] 'applied index is now lower than readState.Index' (duration: 45.589233ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T17:45:00.996943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.978083ms","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-02-02T17:45:00.997013Z","caller":"traceutil/trace.go:171","msg":"trace[407564468] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:973; }","duration":"330.090155ms","start":"2026-02-02T17:45:00.666909Z","end":"2026-02-02T17:45:00.996999Z","steps":["trace[407564468] 'agreement among raft nodes before linearized reading' (duration: 329.936802ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:45:00.997039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.927903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-02-02T17:45:00.997057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:45:00.666891Z","time spent":"330.157746ms","remote":"127.0.0.1:43340","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":"info","ts":"2026-02-02T17:45:00.997091Z","caller":"traceutil/trace.go:171","msg":"trace[1858697447] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:973; }","duration":"330.007534ms","start":"2026-02-02T17:45:00.667071Z","end":"2026-02-02T17:45:00.997078Z","steps":["trace[1858697447] 'agreement among raft nodes before linearized reading' (duration: 329.850431ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:45:00.997131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:45:00.667065Z","time spent":"330.058104ms","remote":"127.0.0.1:43340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-02T17:45:00.997193Z","caller":"traceutil/trace.go:171","msg":"trace[1291016469] transaction","detail":"{read_only:false; response_revision:973; number_of_response:1; }","duration":"443.137021ms","start":"2026-02-02T17:45:00.554008Z","end":"2026-02-02T17:45:00.997145Z","steps":["trace[1291016469] 'process raft request' (duration: 397.019989ms)","trace[1291016469] 'compare' (duration: 45.267519ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T17:45:00.997282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:45:00.553984Z","time spent":"443.256132ms","remote":"127.0.0.1:43340","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-02-02T17:45:00.997351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.330775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-02T17:45:00.997393Z","caller":"traceutil/trace.go:171","msg":"trace[1889944354] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:973; }","duration":"316.381075ms","start":"2026-02-02T17:45:00.680998Z","end":"2026-02-02T17:45:00.997379Z","steps":["trace[1889944354] 'agreement among raft nodes before linearized reading' (duration: 316.290304ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:45:00.997458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:45:00.68099Z","time spent":"316.460766ms","remote":"127.0.0.1:43340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "}