{"level":"warn","ts":"2026-05-21T03:23:49.010756Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-21T03:23:49.010866Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.78: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.204.45.78:2380","--initial-cluster=instance=https://199.204.45.78:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.78:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.78: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-05-21T03:23:49.010945Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-21T03:23:49.010957Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.78:2380"]} {"level":"info","ts":"2026-05-21T03:23:49.010976Z","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-05-21T03:23:49.011822Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.78:2379"]} {"level":"info","ts":"2026-05-21T03:23:49.011907Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.78:2380"],"listen-peer-urls":["https://199.204.45.78:2380"],"advertise-client-urls":["https://199.204.45.78:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.78:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.78: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-05-21T03:23:49.016354Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.21448ms"} {"level":"info","ts":"2026-05-21T03:23:49.022533Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"6eb8766b1ec86e92","cluster-id":"9bbfe9e5d45d3718"} {"level":"info","ts":"2026-05-21T03:23:49.022617Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 switched to configuration voters=()"} {"level":"info","ts":"2026-05-21T03:23:49.022644Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 became follower at term 0"} {"level":"info","ts":"2026-05-21T03:23:49.022658Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 6eb8766b1ec86e92 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-21T03:23:49.022667Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 became follower at term 1"} {"level":"info","ts":"2026-05-21T03:23:49.022716Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 switched to configuration voters=(7978256942336863890)"} {"level":"warn","ts":"2026-05-21T03:23:49.030307Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-21T03:23:49.032559Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-21T03:23:49.038704Z","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-05-21T03:23:49.040682Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"6eb8766b1ec86e92","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-21T03:23:49.040798Z","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-05-21T03:23:49.040907Z","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-05-21T03:23:49.040918Z","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-05-21T03:23:49.041404Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"6eb8766b1ec86e92","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-21T03:23:49.043412Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 switched to configuration voters=(7978256942336863890)"} {"level":"info","ts":"2026-05-21T03:23:49.043654Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"9bbfe9e5d45d3718","local-member-id":"6eb8766b1ec86e92","added-peer-id":"6eb8766b1ec86e92","added-peer-peer-urls":["https://199.204.45.78:2380"]} {"level":"info","ts":"2026-05-21T03:23:49.044087Z","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-05-21T03:23:49.04421Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.78:2380"} {"level":"info","ts":"2026-05-21T03:23:49.044254Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.78:2380"} {"level":"info","ts":"2026-05-21T03:23:49.044315Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"6eb8766b1ec86e92","initial-advertise-peer-urls":["https://199.204.45.78:2380"],"listen-peer-urls":["https://199.204.45.78:2380"],"advertise-client-urls":["https://199.204.45.78:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.78:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-21T03:23:49.044353Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-21T03:23:49.423701Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 is starting a new election at term 1"} {"level":"info","ts":"2026-05-21T03:23:49.423753Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-21T03:23:49.42377Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 received MsgPreVoteResp from 6eb8766b1ec86e92 at term 1"} {"level":"info","ts":"2026-05-21T03:23:49.423783Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 became candidate at term 2"} {"level":"info","ts":"2026-05-21T03:23:49.42379Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 received MsgVoteResp from 6eb8766b1ec86e92 at term 2"} {"level":"info","ts":"2026-05-21T03:23:49.423799Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6eb8766b1ec86e92 became leader at term 2"} {"level":"info","ts":"2026-05-21T03:23:49.423809Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 6eb8766b1ec86e92 elected leader 6eb8766b1ec86e92 at term 2"} {"level":"info","ts":"2026-05-21T03:23:49.425307Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-21T03:23:49.426922Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"9bbfe9e5d45d3718","local-member-id":"6eb8766b1ec86e92","cluster-version":"3.5"} {"level":"info","ts":"2026-05-21T03:23:49.426927Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-21T03:23:49.426895Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"6eb8766b1ec86e92","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.78:2379]}","request-path":"/0/members/6eb8766b1ec86e92/attributes","cluster-id":"9bbfe9e5d45d3718","publish-timeout":"7s"} {"level":"info","ts":"2026-05-21T03:23:49.427054Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-21T03:23:49.427087Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-21T03:23:49.427121Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-21T03:23:49.427325Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-21T03:23:49.427367Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-21T03:23:49.428928Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.78:2379"} {"level":"info","ts":"2026-05-21T03:23:49.436355Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-21T03:23:51.980995Z","caller":"traceutil/trace.go:171","msg":"trace[1109242494] linearizableReadLoop","detail":"{readStateIndex:78; appliedIndex:77; }","duration":"296.208857ms","start":"2026-05-21T03:23:51.684754Z","end":"2026-05-21T03:23:51.980963Z","steps":["trace[1109242494] 'read index received' (duration: 295.970796ms)","trace[1109242494] 'applied index is now lower than readState.Index' (duration: 236.791µs)"],"step_count":2} {"level":"info","ts":"2026-05-21T03:23:51.981124Z","caller":"traceutil/trace.go:171","msg":"trace[1866625129] transaction","detail":"{read_only:false; response_revision:74; number_of_response:1; }","duration":"297.862629ms","start":"2026-05-21T03:23:51.683213Z","end":"2026-05-21T03:23:51.981076Z","steps":["trace[1866625129] 'process raft request' (duration: 297.587977ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:23:51.981309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.512769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-admin\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-21T03:23:51.981398Z","caller":"traceutil/trace.go:171","msg":"trace[1646274933] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-admin; range_end:; response_count:0; response_revision:74; }","duration":"296.65695ms","start":"2026-05-21T03:23:51.684726Z","end":"2026-05-21T03:23:51.981383Z","steps":["trace[1646274933] 'agreement among raft nodes before linearized reading' (duration: 296.430569ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:23:51.981396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.803808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-21T03:23:51.981804Z","caller":"traceutil/trace.go:171","msg":"trace[1107133145] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:74; }","duration":"288.048869ms","start":"2026-05-21T03:23:51.693528Z","end":"2026-05-21T03:23:51.981577Z","steps":["trace[1107133145] 'agreement among raft nodes before linearized reading' (duration: 287.750467ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:23:55.039049Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7967604725255348236,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-21T03:23:55.305336Z","caller":"traceutil/trace.go:171","msg":"trace[1821101079] transaction","detail":"{read_only:false; response_revision:224; number_of_response:1; }","duration":"777.027801ms","start":"2026-05-21T03:23:54.528275Z","end":"2026-05-21T03:23:55.305302Z","steps":["trace[1821101079] 'process raft request' (duration: 776.839509ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:23:55.306214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:23:54.528261Z","time spent":"777.178842ms","remote":"127.0.0.1:42106","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":597,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-21T03:23:55.985678Z","caller":"traceutil/trace.go:171","msg":"trace[354944154] transaction","detail":"{read_only:false; number_of_response:0; response_revision:224; }","duration":"1.279351949s","start":"2026-05-21T03:23:54.706295Z","end":"2026-05-21T03:23:55.985647Z","steps":["trace[354944154] 'process raft request' (duration: 1.279158328s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:23:55.985813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:23:54.706013Z","time spent":"1.279730542s","remote":"127.0.0.1:42170","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-21T03:23:55.985809Z","caller":"traceutil/trace.go:171","msg":"trace[1724381695] linearizableReadLoop","detail":"{readStateIndex:236; appliedIndex:228; }","duration":"1.44678843s","start":"2026-05-21T03:23:54.538968Z","end":"2026-05-21T03:23:55.985757Z","steps":["trace[1724381695] 'read index received' (duration: 766.280555ms)","trace[1724381695] 'applied index is now lower than readState.Index' (duration: 680.506925ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T03:23:55.985928Z","caller":"traceutil/trace.go:171","msg":"trace[211858906] transaction","detail":"{read_only:false; number_of_response:0; response_revision:226; }","duration":"1.263023176s","start":"2026-05-21T03:23:54.722899Z","end":"2026-05-21T03:23:55.985922Z","steps":["trace[211858906] 'process raft request' (duration: 1.262827354s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:23:55.986018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:23:54.722883Z","time spent":"1.263072426s","remote":"127.0.0.1:42178","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-21T03:23:55.986071Z","caller":"traceutil/trace.go:171","msg":"trace[132822723] transaction","detail":"{read_only:false; number_of_response:0; response_revision:226; }","duration":"1.262387371s","start":"2026-05-21T03:23:54.723647Z","end":"2026-05-21T03:23:55.986035Z","steps":["trace[132822723] 'process raft request' (duration: 1.26209163s)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:23:55.986117Z","caller":"traceutil/trace.go:171","msg":"trace[754162674] transaction","detail":"{read_only:false; number_of_response:0; response_revision:226; }","duration":"1.264238455s","start":"2026-05-21T03:23:54.721843Z","end":"2026-05-21T03:23:55.986081Z","steps":["trace[754162674] 'process raft request' (duration: 1.263865142s)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:23:55.986221Z","caller":"traceutil/trace.go:171","msg":"trace[439038631] transaction","detail":"{read_only:false; response_revision:226; number_of_response:1; }","duration":"1.264709959s","start":"2026-05-21T03:23:54.721504Z","end":"2026-05-21T03:23:55.986214Z","steps":["trace[439038631] 'process raft request' (duration: 1.264182024s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:23:55.986242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:23:54.723636Z","time spent":"1.262526372s","remote":"127.0.0.1:42178","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-21T03:23:55.986271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:23:54.721463Z","time spent":"1.26478269s","remote":"127.0.0.1:42178","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3959,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-21T03:23:55.986276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.447316254s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T03:23:55.986322Z","caller":"traceutil/trace.go:171","msg":"trace[1058763811] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:0; response_revision:226; }","duration":"1.447415164s","start":"2026-05-21T03:23:54.538893Z","end":"2026-05-21T03:23:55.986308Z","steps":["trace[1058763811] 'agreement among raft nodes before linearized reading' (duration: 1.447305774s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:23:55.986357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:23:54.538865Z","time spent":"1.447485866s","remote":"127.0.0.1:42248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-21T03:23:55.986177Z","caller":"traceutil/trace.go:171","msg":"trace[187789115] transaction","detail":"{read_only:false; response_revision:225; number_of_response:1; }","duration":"1.264724208s","start":"2026-05-21T03:23:54.721442Z","end":"2026-05-21T03:23:55.986166Z","steps":["trace[187789115] 'process raft request' (duration: 1.264122234s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:23:55.986429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:23:54.721827Z","time spent":"1.264395897s","remote":"127.0.0.1:42178","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-21T03:23:55.986498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:23:54.721397Z","time spent":"1.265052301s","remote":"127.0.0.1:42178","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5498,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-21T03:23:55.98558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:23:54.602695Z","time spent":"1.382859356s","remote":"127.0.0.1:42084","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-05-21T03:23:56.220799Z","caller":"traceutil/trace.go:171","msg":"trace[1344542607] linearizableReadLoop","detail":"{readStateIndex:237; appliedIndex:236; }","duration":"234.879537ms","start":"2026-05-21T03:23:55.985889Z","end":"2026-05-21T03:23:56.220768Z","steps":["trace[1344542607] 'read index received' (duration: 225.411542ms)","trace[1344542607] 'applied index is now lower than readState.Index' (duration: 9.466775ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T03:23:56.221149Z","caller":"traceutil/trace.go:171","msg":"trace[251929275] transaction","detail":"{read_only:false; response_revision:227; number_of_response:1; }","duration":"909.955753ms","start":"2026-05-21T03:23:55.311132Z","end":"2026-05-21T03:23:56.221088Z","steps":["trace[251929275] 'process raft request' (duration: 900.124076ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:23:56.221188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.386441341s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-05-21T03:23:56.22122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.618225927s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T03:23:56.221286Z","caller":"traceutil/trace.go:171","msg":"trace[310736370] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:227; }","duration":"1.386551212s","start":"2026-05-21T03:23:54.834712Z","end":"2026-05-21T03:23:56.221263Z","steps":["trace[310736370] 'agreement among raft nodes before linearized reading' (duration: 1.38631472s)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:23:56.221349Z","caller":"traceutil/trace.go:171","msg":"trace[1936054714] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:0; response_revision:227; }","duration":"1.618393028s","start":"2026-05-21T03:23:54.602917Z","end":"2026-05-21T03:23:56.22131Z","steps":["trace[1936054714] 'agreement among raft nodes before linearized reading' (duration: 1.618180117s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:23:56.221358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:23:54.834651Z","time spent":"1.386696424s","remote":"127.0.0.1:42248","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-05-21T03:23:56.221351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:23:55.311105Z","time spent":"910.151904ms","remote":"127.0.0.1:42350","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":373,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-21T03:23:56.221411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:23:54.602898Z","time spent":"1.618501729s","remote":"127.0.0.1:42248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-node-lease/instance\" "} {"level":"warn","ts":"2026-05-21T03:23:56.221439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.135008773s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-21T03:23:56.221502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.619306965s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-05-21T03:23:56.221557Z","caller":"traceutil/trace.go:171","msg":"trace[1438917693] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:227; }","duration":"1.135181695s","start":"2026-05-21T03:23:55.086339Z","end":"2026-05-21T03:23:56.221521Z","steps":["trace[1438917693] 'agreement among raft nodes before linearized reading' (duration: 1.134960092s)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:23:56.221617Z","caller":"traceutil/trace.go:171","msg":"trace[179813836] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:227; }","duration":"1.619459587s","start":"2026-05-21T03:23:54.602124Z","end":"2026-05-21T03:23:56.221583Z","steps":["trace[179813836] 'agreement among raft nodes before linearized reading' (duration: 1.619297285s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:23:56.221683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:23:54.602098Z","time spent":"1.619576678s","remote":"127.0.0.1:42402","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":687,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"warn","ts":"2026-05-21T03:23:56.221616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:23:55.086317Z","time spent":"1.135288925s","remote":"127.0.0.1:42248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-21T03:23:56.221222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.391523875s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T03:23:56.221874Z","caller":"traceutil/trace.go:171","msg":"trace[238982648] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:227; }","duration":"1.392251822s","start":"2026-05-21T03:23:54.8296Z","end":"2026-05-21T03:23:56.221852Z","steps":["trace[238982648] 'agreement among raft nodes before linearized reading' (duration: 1.391438056s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:23:56.222312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:23:54.829572Z","time spent":"1.392702474s","remote":"127.0.0.1:42248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-21T03:23:56.225223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.449511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4016"} {"level":"info","ts":"2026-05-21T03:23:56.2253Z","caller":"traceutil/trace.go:171","msg":"trace[528272985] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:229; }","duration":"238.575162ms","start":"2026-05-21T03:23:55.986706Z","end":"2026-05-21T03:23:56.225281Z","steps":["trace[528272985] 'agreement among raft nodes before linearized reading' (duration: 238.417891ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:23:56.225468Z","caller":"traceutil/trace.go:171","msg":"trace[46126665] transaction","detail":"{read_only:false; response_revision:228; number_of_response:1; }","duration":"237.159463ms","start":"2026-05-21T03:23:55.988301Z","end":"2026-05-21T03:23:56.22546Z","steps":["trace[46126665] 'process raft request' (duration: 236.68109ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:23:56.225665Z","caller":"traceutil/trace.go:171","msg":"trace[1762828163] transaction","detail":"{read_only:false; response_revision:229; number_of_response:1; }","duration":"235.188649ms","start":"2026-05-21T03:23:55.990469Z","end":"2026-05-21T03:23:56.225658Z","steps":["trace[1762828163] 'process raft request' (duration: 234.613055ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:23:56.225769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.492855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:3466"} {"level":"info","ts":"2026-05-21T03:23:56.225792Z","caller":"traceutil/trace.go:171","msg":"trace[1925741061] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:229; }","duration":"237.578415ms","start":"2026-05-21T03:23:55.988208Z","end":"2026-05-21T03:23:56.225786Z","steps":["trace[1925741061] 'agreement among raft nodes before linearized reading' (duration: 237.531775ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:23:56.225915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.766177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:5892"} {"level":"info","ts":"2026-05-21T03:23:56.225938Z","caller":"traceutil/trace.go:171","msg":"trace[769558174] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:229; }","duration":"237.811427ms","start":"2026-05-21T03:23:55.988121Z","end":"2026-05-21T03:23:56.225933Z","steps":["trace[769558174] 'agreement among raft nodes before linearized reading' (duration: 237.763477ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:23:56.226056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.924818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:2918"} {"level":"info","ts":"2026-05-21T03:23:56.226079Z","caller":"traceutil/trace.go:171","msg":"trace[525431524] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:229; }","duration":"237.969988ms","start":"2026-05-21T03:23:55.988103Z","end":"2026-05-21T03:23:56.226073Z","steps":["trace[525431524] 'agreement among raft nodes before linearized reading' (duration: 237.924248ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:24:16.440718Z","caller":"traceutil/trace.go:171","msg":"trace[1406675368] linearizableReadLoop","detail":"{readStateIndex:449; appliedIndex:447; }","duration":"105.859174ms","start":"2026-05-21T03:24:16.334827Z","end":"2026-05-21T03:24:16.440686Z","steps":["trace[1406675368] 'read index received' (duration: 105.548882ms)","trace[1406675368] 'applied index is now lower than readState.Index' (duration: 309.442µs)"],"step_count":2} {"level":"info","ts":"2026-05-21T03:24:16.440777Z","caller":"traceutil/trace.go:171","msg":"trace[2067115024] transaction","detail":"{read_only:false; response_revision:433; number_of_response:1; }","duration":"107.635574ms","start":"2026-05-21T03:24:16.333107Z","end":"2026-05-21T03:24:16.440742Z","steps":["trace[2067115024] 'process raft request' (duration: 107.531803ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:24:16.440779Z","caller":"traceutil/trace.go:171","msg":"trace[1351718179] transaction","detail":"{read_only:false; response_revision:432; number_of_response:1; }","duration":"107.623724ms","start":"2026-05-21T03:24:16.333107Z","end":"2026-05-21T03:24:16.44073Z","steps":["trace[1351718179] 'process raft request' (duration: 107.298012ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:24:16.440912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.060865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-21T03:24:16.44095Z","caller":"traceutil/trace.go:171","msg":"trace[1726450986] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:433; }","duration":"106.146345ms","start":"2026-05-21T03:24:16.334794Z","end":"2026-05-21T03:24:16.44094Z","steps":["trace[1726450986] 'agreement among raft nodes before linearized reading' (duration: 106.050075ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:24:22.574502Z","caller":"traceutil/trace.go:171","msg":"trace[610524662] linearizableReadLoop","detail":"{readStateIndex:463; appliedIndex:462; }","duration":"105.939218ms","start":"2026-05-21T03:24:22.46851Z","end":"2026-05-21T03:24:22.574449Z","steps":["trace[610524662] 'read index received' (duration: 105.76064ms)","trace[610524662] 'applied index is now lower than readState.Index' (duration: 177.418µs)"],"step_count":2} {"level":"info","ts":"2026-05-21T03:24:22.574583Z","caller":"traceutil/trace.go:171","msg":"trace[1351713854] transaction","detail":"{read_only:false; response_revision:446; number_of_response:1; }","duration":"106.213391ms","start":"2026-05-21T03:24:22.468336Z","end":"2026-05-21T03:24:22.574549Z","steps":["trace[1351713854] 'process raft request' (duration: 105.930047ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:24:22.574816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.259753ms","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-05-21T03:24:22.574951Z","caller":"traceutil/trace.go:171","msg":"trace[1945112533] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:446; }","duration":"106.423931ms","start":"2026-05-21T03:24:22.468485Z","end":"2026-05-21T03:24:22.574909Z","steps":["trace[1945112533] 'agreement among raft nodes before linearized reading' (duration: 106.175509ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:24:26.37583Z","caller":"traceutil/trace.go:171","msg":"trace[1442930628] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"104.930232ms","start":"2026-05-21T03:24:26.270875Z","end":"2026-05-21T03:24:26.375805Z","steps":["trace[1442930628] 'process raft request' (duration: 104.791766ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:24:26.407725Z","caller":"traceutil/trace.go:171","msg":"trace[2022133055] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"133.57856ms","start":"2026-05-21T03:24:26.274104Z","end":"2026-05-21T03:24:26.407682Z","steps":["trace[2022133055] 'process raft request' (duration: 113.630016ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:24:26.408186Z","caller":"traceutil/trace.go:171","msg":"trace[156174080] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"121.580636ms","start":"2026-05-21T03:24:26.286597Z","end":"2026-05-21T03:24:26.408177Z","steps":["trace[156174080] 'process raft request' (duration: 101.325299ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:24:26.408199Z","caller":"traceutil/trace.go:171","msg":"trace[2145452769] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"131.672963ms","start":"2026-05-21T03:24:26.276493Z","end":"2026-05-21T03:24:26.408166Z","steps":["trace[2145452769] 'process raft request' (duration: 111.358363ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:24:26.408309Z","caller":"traceutil/trace.go:171","msg":"trace[1759867590] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"116.566408ms","start":"2026-05-21T03:24:26.291729Z","end":"2026-05-21T03:24:26.408296Z","steps":["trace[1759867590] 'process raft request' (duration: 96.318711ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:24:26.40845Z","caller":"traceutil/trace.go:171","msg":"trace[1465815966] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"120.372552ms","start":"2026-05-21T03:24:26.288069Z","end":"2026-05-21T03:24:26.408441Z","steps":["trace[1465815966] 'process raft request' (duration: 99.949247ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:24:26.408557Z","caller":"traceutil/trace.go:171","msg":"trace[167193401] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"113.069081ms","start":"2026-05-21T03:24:26.29548Z","end":"2026-05-21T03:24:26.408538Z","steps":["trace[167193401] 'process raft request' (duration: 92.649756ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:24:26.408673Z","caller":"traceutil/trace.go:171","msg":"trace[1892124721] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"109.184925ms","start":"2026-05-21T03:24:26.299455Z","end":"2026-05-21T03:24:26.40864Z","steps":["trace[1892124721] 'process raft request' (duration: 96.534712ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:24:26.408807Z","caller":"traceutil/trace.go:171","msg":"trace[1067116028] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"116.304397ms","start":"2026-05-21T03:24:26.292477Z","end":"2026-05-21T03:24:26.408781Z","steps":["trace[1067116028] 'process raft request' (duration: 95.60055ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:24:26.408963Z","caller":"traceutil/trace.go:171","msg":"trace[1435906411] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"102.479571ms","start":"2026-05-21T03:24:26.285695Z","end":"2026-05-21T03:24:26.388175Z","steps":["trace[1435906411] 'process raft request' (duration: 102.187158ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:24:27.658012Z","caller":"traceutil/trace.go:171","msg":"trace[1143478391] linearizableReadLoop","detail":"{readStateIndex:584; appliedIndex:583; }","duration":"165.863811ms","start":"2026-05-21T03:24:27.492092Z","end":"2026-05-21T03:24:27.657955Z","steps":["trace[1143478391] 'read index received' (duration: 101.351635ms)","trace[1143478391] 'applied index is now lower than readState.Index' (duration: 64.510636ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T03:24:27.658051Z","caller":"traceutil/trace.go:171","msg":"trace[817042118] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"183.855355ms","start":"2026-05-21T03:24:27.474151Z","end":"2026-05-21T03:24:27.658006Z","steps":["trace[817042118] 'process raft request' (duration: 119.403822ms)","trace[817042118] 'compare' (duration: 64.062616ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T03:24:27.658304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.176375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/backendtlspolicies.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:29570"} {"level":"info","ts":"2026-05-21T03:24:27.658349Z","caller":"traceutil/trace.go:171","msg":"trace[1638848420] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/backendtlspolicies.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:564; }","duration":"166.29194ms","start":"2026-05-21T03:24:27.492043Z","end":"2026-05-21T03:24:27.658335Z","steps":["trace[1638848420] 'agreement among raft nodes before linearized reading' (duration: 166.0647ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:24:28.640602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"866.296788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/httproutes.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:151236"} {"level":"info","ts":"2026-05-21T03:24:28.64239Z","caller":"traceutil/trace.go:171","msg":"trace[24356802] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/httproutes.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:565; }","duration":"866.524267ms","start":"2026-05-21T03:24:27.774199Z","end":"2026-05-21T03:24:28.640724Z","steps":["trace[24356802] 'range keys from in-memory index tree' (duration: 866.029026ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:24:28.64267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:24:27.77417Z","time spent":"868.428452ms","remote":"127.0.0.1:42044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":95,"response count":1,"response size":151259,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/httproutes.gateway.networking.k8s.io\" "} {"level":"warn","ts":"2026-05-21T03:24:28.646295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.250659ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T03:24:28.646689Z","caller":"traceutil/trace.go:171","msg":"trace[1202492421] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"872.222289ms","start":"2026-05-21T03:24:27.77444Z","end":"2026-05-21T03:24:28.646662Z","steps":["trace[1202492421] 'process raft request' (duration: 649.811078ms)","trace[1202492421] 'compare' (duration: 216.000328ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T03:24:28.646774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:24:27.774414Z","time spent":"872.315723ms","remote":"127.0.0.1:42044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":208461,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T03:24:28.646779Z","caller":"traceutil/trace.go:171","msg":"trace[339310892] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"860.297516ms","start":"2026-05-21T03:24:27.78642Z","end":"2026-05-21T03:24:28.646718Z","steps":["trace[339310892] 'process raft request' (duration: 859.991572ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:24:28.647073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:24:27.786394Z","time spent":"860.473743ms","remote":"127.0.0.1:42044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":15673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T03:24:33.186796Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7967604725255349023,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-21T03:24:33.41888Z","caller":"traceutil/trace.go:171","msg":"trace[1211549595] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"774.257052ms","start":"2026-05-21T03:24:32.644575Z","end":"2026-05-21T03:24:33.418832Z","steps":["trace[1211549595] 'process raft request' (duration: 766.342242ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:24:33.418933Z","caller":"traceutil/trace.go:171","msg":"trace[1876158646] linearizableReadLoop","detail":"{readStateIndex:612; appliedIndex:611; }","duration":"732.337706ms","start":"2026-05-21T03:24:32.68649Z","end":"2026-05-21T03:24:33.418828Z","steps":["trace[1876158646] 'read index received' (duration: 723.29468ms)","trace[1876158646] 'applied index is now lower than readState.Index' (duration: 9.040486ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T03:24:33.419073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:24:32.644541Z","time spent":"774.434449ms","remote":"127.0.0.1:42092","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036229,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T03:24:33.419362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"621.567187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.78\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-05-21T03:24:33.419447Z","caller":"traceutil/trace.go:171","msg":"trace[1120376302] range","detail":"{range_begin:/registry/masterleases/199.204.45.78; range_end:; response_count:1; response_revision:591; }","duration":"621.699463ms","start":"2026-05-21T03:24:32.797728Z","end":"2026-05-21T03:24:33.419427Z","steps":["trace[1120376302] 'agreement among raft nodes before linearized reading' (duration: 621.520505ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:24:33.419695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"733.114538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-05-21T03:24:33.419757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"732.443218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-21T03:24:33.419802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"733.309226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-21T03:24:33.419835Z","caller":"traceutil/trace.go:171","msg":"trace[1284732843] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:591; }","duration":"733.344627ms","start":"2026-05-21T03:24:32.68648Z","end":"2026-05-21T03:24:33.419825Z","steps":["trace[1284732843] 'agreement among raft nodes before linearized reading' (duration: 732.44097ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:24:33.419754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"730.320089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-21T03:24:33.419871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:24:32.686406Z","time spent":"733.458962ms","remote":"127.0.0.1:42248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-21T03:24:33.41994Z","caller":"traceutil/trace.go:171","msg":"trace[376746894] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:591; }","duration":"732.548693ms","start":"2026-05-21T03:24:32.687263Z","end":"2026-05-21T03:24:33.419812Z","steps":["trace[376746894] 'agreement among raft nodes before linearized reading' (duration: 732.370805ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:24:33.419479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:24:32.797711Z","time spent":"621.760955ms","remote":"127.0.0.1:42026","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.78\" "} {"level":"info","ts":"2026-05-21T03:24:33.419933Z","caller":"traceutil/trace.go:171","msg":"trace[1037135733] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:591; }","duration":"730.530318ms","start":"2026-05-21T03:24:32.689364Z","end":"2026-05-21T03:24:33.419895Z","steps":["trace[1037135733] 'agreement among raft nodes before linearized reading' (duration: 730.114471ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:24:33.419867Z","caller":"traceutil/trace.go:171","msg":"trace[1343913414] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:591; }","duration":"733.331697ms","start":"2026-05-21T03:24:32.686465Z","end":"2026-05-21T03:24:33.419796Z","steps":["trace[1343913414] 'agreement among raft nodes before linearized reading' (duration: 732.626348ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:24:33.420058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:24:32.689328Z","time spent":"730.696095ms","remote":"127.0.0.1:42248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-21T03:24:33.420069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:24:32.687253Z","time spent":"732.743171ms","remote":"127.0.0.1:42248","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-05-21T03:24:33.420189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:24:32.686432Z","time spent":"733.65666ms","remote":"127.0.0.1:42248","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-05-21T03:24:33.420203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"678.645822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-57t5n\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-05-21T03:24:33.42045Z","caller":"traceutil/trace.go:171","msg":"trace[585878548] range","detail":"{range_begin:/registry/pods/kube-system/cilium-57t5n; range_end:; response_count:1; response_revision:591; }","duration":"679.171154ms","start":"2026-05-21T03:24:32.741177Z","end":"2026-05-21T03:24:33.420348Z","steps":["trace[585878548] 'agreement among raft nodes before linearized reading' (duration: 678.516437ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:24:33.420521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:24:32.741155Z","time spent":"679.349611ms","remote":"127.0.0.1:42178","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16984,"request content":"key:\"/registry/pods/kube-system/cilium-57t5n\" "} {"level":"warn","ts":"2026-05-21T03:24:34.188723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"692.969788ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T03:24:34.188977Z","caller":"traceutil/trace.go:171","msg":"trace[1898975192] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"762.591648ms","start":"2026-05-21T03:24:33.426368Z","end":"2026-05-21T03:24:34.18896Z","steps":["trace[1898975192] 'process raft request' (duration: 762.546076ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:24:34.189056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:24:33.426338Z","time spent":"762.685181ms","remote":"127.0.0.1:42248","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-05-21T03:24:34.189149Z","caller":"traceutil/trace.go:171","msg":"trace[1872236228] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"762.919121ms","start":"2026-05-21T03:24:33.42622Z","end":"2026-05-21T03:24:34.18914Z","steps":["trace[1872236228] 'process raft request' (duration: 762.662351ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:24:34.189153Z","caller":"traceutil/trace.go:171","msg":"trace[1221646890] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"764.131891ms","start":"2026-05-21T03:24:33.424962Z","end":"2026-05-21T03:24:34.189094Z","steps":["trace[1221646890] 'process raft request' (duration: 70.705515ms)","trace[1221646890] 'compare' (duration: 692.80299ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T03:24:34.189195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:24:33.426211Z","time spent":"762.957683ms","remote":"127.0.0.1:42248","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-05-21T03:24:34.189134Z","caller":"traceutil/trace.go:171","msg":"trace[156081506] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"763.925313ms","start":"2026-05-21T03:24:33.425153Z","end":"2026-05-21T03:24:34.189079Z","steps":["trace[156081506] 'process raft request' (duration: 763.676752ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:24:34.189338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:24:33.42494Z","time spent":"764.296938ms","remote":"127.0.0.1:42248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T03:24:34.189393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:24:33.425145Z","time spent":"764.11821ms","remote":"127.0.0.1:42248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T03:24:34.689204Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.193489976s","expected-duration":"1s"} {"level":"info","ts":"2026-05-21T03:24:34.719461Z","caller":"traceutil/trace.go:171","msg":"trace[1638245411] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"1.287059868s","start":"2026-05-21T03:24:33.432368Z","end":"2026-05-21T03:24:34.719428Z","steps":["trace[1638245411] 'process raft request' (duration: 1.286877211s)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:24:34.719443Z","caller":"traceutil/trace.go:171","msg":"trace[1004765913] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"1.287502047s","start":"2026-05-21T03:24:33.431888Z","end":"2026-05-21T03:24:34.71939Z","steps":["trace[1004765913] 'process raft request' (duration: 1.25768945s)","trace[1004765913] 'compare' (duration: 29.498715ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T03:24:34.719692Z","caller":"traceutil/trace.go:171","msg":"trace[2118834855] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"1.233861013s","start":"2026-05-21T03:24:33.485801Z","end":"2026-05-21T03:24:34.719662Z","steps":["trace[2118834855] 'process raft request' (duration: 1.233510818s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:24:34.719721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:24:33.432355Z","time spent":"1.287213725s","remote":"127.0.0.1:42026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T03:24:34.719726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:24:33.43187Z","time spent":"1.287748178s","remote":"127.0.0.1:42178","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T03:24:34.719674Z","caller":"traceutil/trace.go:171","msg":"trace[1290175244] linearizableReadLoop","detail":"{readStateIndex:620; appliedIndex:617; }","duration":"484.591873ms","start":"2026-05-21T03:24:34.235037Z","end":"2026-05-21T03:24:34.719629Z","steps":["trace[1290175244] 'read index received' (duration: 454.38878ms)","trace[1290175244] 'applied index is now lower than readState.Index' (duration: 30.200223ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T03:24:34.719852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:24:33.485763Z","time spent":"1.233997579s","remote":"127.0.0.1:42350","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1092,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-21T03:24:34.7199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"484.821973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T03:24:34.719961Z","caller":"traceutil/trace.go:171","msg":"trace[274896645] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:598; }","duration":"484.964479ms","start":"2026-05-21T03:24:34.234981Z","end":"2026-05-21T03:24:34.719946Z","steps":["trace[274896645] 'agreement among raft nodes before linearized reading' (duration: 484.767111ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:24:34.720015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T03:24:34.234947Z","time spent":"485.054552ms","remote":"127.0.0.1:41978","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-05-21T03:24:35.960041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.987627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-57t5n\" ","response":"range_response_count:1 size:17243"} {"level":"info","ts":"2026-05-21T03:24:35.960164Z","caller":"traceutil/trace.go:171","msg":"trace[1738262872] range","detail":"{range_begin:/registry/pods/kube-system/cilium-57t5n; range_end:; response_count:1; response_revision:605; }","duration":"204.141123ms","start":"2026-05-21T03:24:35.755992Z","end":"2026-05-21T03:24:35.960133Z","steps":["trace[1738262872] 'range keys from in-memory index tree' (duration: 203.665375ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:24:35.960418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.376564ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-21T03:24:35.960685Z","caller":"traceutil/trace.go:171","msg":"trace[1696111515] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"186.129511ms","start":"2026-05-21T03:24:35.774533Z","end":"2026-05-21T03:24:35.960663Z","steps":["trace[1696111515] 'process raft request' (duration: 186.025796ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T03:24:35.960744Z","caller":"traceutil/trace.go:171","msg":"trace[905053328] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"200.497505ms","start":"2026-05-21T03:24:35.7602Z","end":"2026-05-21T03:24:35.960697Z","steps":["trace[905053328] 'process raft request' (duration: 24.747556ms)","trace[905053328] 'compare' (duration: 174.983358ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T03:24:36.433001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.456236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-21T03:24:36.433174Z","caller":"traceutil/trace.go:171","msg":"trace[1862838856] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:612; }","duration":"238.700636ms","start":"2026-05-21T03:24:36.194453Z","end":"2026-05-21T03:24:36.433154Z","steps":["trace[1862838856] 'range keys from in-memory index tree' (duration: 238.241527ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:24:36.433555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.781809ms","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-05-21T03:24:36.433672Z","caller":"traceutil/trace.go:171","msg":"trace[173823927] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:612; }","duration":"238.925055ms","start":"2026-05-21T03:24:36.194717Z","end":"2026-05-21T03:24:36.433642Z","steps":["trace[173823927] 'range keys from in-memory index tree' (duration: 238.619263ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:24:36.433742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.24195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T03:24:36.4339Z","caller":"traceutil/trace.go:171","msg":"trace[650122706] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:612; }","duration":"199.435438ms","start":"2026-05-21T03:24:36.234436Z","end":"2026-05-21T03:24:36.433871Z","steps":["trace[650122706] 'range keys from in-memory index tree' (duration: 199.117835ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:24:36.433866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.463906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-21T03:24:36.434015Z","caller":"traceutil/trace.go:171","msg":"trace[1044012611] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:612; }","duration":"237.644614ms","start":"2026-05-21T03:24:36.19635Z","end":"2026-05-21T03:24:36.433995Z","steps":["trace[1044012611] 'range keys from in-memory index tree' (duration: 237.258639ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T03:24:36.434429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.619092ms","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-05-21T03:24:36.434497Z","caller":"traceutil/trace.go:171","msg":"trace[2062481193] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:612; }","duration":"238.708846ms","start":"2026-05-21T03:24:36.195773Z","end":"2026-05-21T03:24:36.434482Z","steps":["trace[2062481193] 'range keys from in-memory index tree' (duration: 238.456546ms)"],"step_count":1}