{"level":"warn","ts":"2026-07-24T17:26:08.309272Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-24T17:26:08.309351Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.205: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.205:2380","--initial-cluster=instance=https://199.204.45.205:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.205:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.205:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-07-24T17:26:08.309411Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-24T17:26:08.309421Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.205:2380"]} {"level":"info","ts":"2026-07-24T17:26:08.309439Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-24T17:26:08.310054Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.205:2379"]} {"level":"info","ts":"2026-07-24T17:26:08.310123Z","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.205:2380"],"listen-peer-urls":["https://199.204.45.205:2380"],"advertise-client-urls":["https://199.204.45.205:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.205:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.205:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-07-24T17:26:08.667581Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"357.260619ms"} {"level":"info","ts":"2026-07-24T17:26:08.691594Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"71d28e9ac1bc4e5d","cluster-id":"4a4ae660c7a9a6a7"} {"level":"info","ts":"2026-07-24T17:26:08.691731Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d switched to configuration voters=()"} {"level":"info","ts":"2026-07-24T17:26:08.691768Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d became follower at term 0"} {"level":"info","ts":"2026-07-24T17:26:08.691784Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 71d28e9ac1bc4e5d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-24T17:26:08.691799Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d became follower at term 1"} {"level":"info","ts":"2026-07-24T17:26:08.691861Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d switched to configuration voters=(8201774666721545821)"} {"level":"warn","ts":"2026-07-24T17:26:08.703917Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-24T17:26:08.7056Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-24T17:26:08.707072Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-07-24T17:26:08.710158Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"71d28e9ac1bc4e5d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-24T17:26:08.71039Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-24T17:26:08.710521Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-24T17:26:08.710539Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-24T17:26:08.711342Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"71d28e9ac1bc4e5d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-24T17:26:08.712224Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d switched to configuration voters=(8201774666721545821)"} {"level":"info","ts":"2026-07-24T17:26:08.712438Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"4a4ae660c7a9a6a7","local-member-id":"71d28e9ac1bc4e5d","added-peer-id":"71d28e9ac1bc4e5d","added-peer-peer-urls":["https://199.204.45.205:2380"]} {"level":"info","ts":"2026-07-24T17:26:08.716066Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-24T17:26:08.716262Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"71d28e9ac1bc4e5d","initial-advertise-peer-urls":["https://199.204.45.205:2380"],"listen-peer-urls":["https://199.204.45.205:2380"],"advertise-client-urls":["https://199.204.45.205:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.205:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-24T17:26:08.716293Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-24T17:26:08.716407Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.205:2380"} {"level":"info","ts":"2026-07-24T17:26:08.716421Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.205:2380"} {"level":"info","ts":"2026-07-24T17:26:09.292628Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d is starting a new election at term 1"} {"level":"info","ts":"2026-07-24T17:26:09.292685Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d became pre-candidate at term 1"} {"level":"info","ts":"2026-07-24T17:26:09.292722Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d received MsgPreVoteResp from 71d28e9ac1bc4e5d at term 1"} {"level":"info","ts":"2026-07-24T17:26:09.292736Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d became candidate at term 2"} {"level":"info","ts":"2026-07-24T17:26:09.292744Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d received MsgVoteResp from 71d28e9ac1bc4e5d at term 2"} {"level":"info","ts":"2026-07-24T17:26:09.292755Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"71d28e9ac1bc4e5d became leader at term 2"} {"level":"info","ts":"2026-07-24T17:26:09.292765Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 71d28e9ac1bc4e5d elected leader 71d28e9ac1bc4e5d at term 2"} {"level":"info","ts":"2026-07-24T17:26:09.293654Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-24T17:26:09.294537Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"71d28e9ac1bc4e5d","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.205:2379]}","request-path":"/0/members/71d28e9ac1bc4e5d/attributes","cluster-id":"4a4ae660c7a9a6a7","publish-timeout":"7s"} {"level":"info","ts":"2026-07-24T17:26:09.294734Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-24T17:26:09.294766Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-24T17:26:09.294845Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-24T17:26:09.294886Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-24T17:26:09.295029Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"4a4ae660c7a9a6a7","local-member-id":"71d28e9ac1bc4e5d","cluster-version":"3.5"} {"level":"info","ts":"2026-07-24T17:26:09.29512Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-24T17:26:09.295146Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-24T17:26:09.296787Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.205:2379"} {"level":"info","ts":"2026-07-24T17:26:09.298031Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-07-24T17:26:42.91785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.89518ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-24T17:26:42.917955Z","caller":"traceutil/trace.go:171","msg":"trace[2025499344] linearizableReadLoop","detail":"{readStateIndex:468; appliedIndex:467; }","duration":"214.191297ms","start":"2026-07-24T17:26:42.703749Z","end":"2026-07-24T17:26:42.91794Z","steps":["trace[2025499344] 'read index received' (duration: 45.931µs)","trace[2025499344] 'applied index is now lower than readState.Index' (duration: 214.144196ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T17:26:42.917983Z","caller":"traceutil/trace.go:171","msg":"trace[1280032824] transaction","detail":"{read_only:false; response_revision:448; number_of_response:1; }","duration":"647.961157ms","start":"2026-07-24T17:26:42.270004Z","end":"2026-07-24T17:26:42.917965Z","steps":["trace[1280032824] 'process raft request' (duration: 265.220836ms)","trace[1280032824] 'compare' (duration: 381.614893ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T17:26:42.91808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.335351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T17:26:42.918117Z","caller":"traceutil/trace.go:171","msg":"trace[439323829] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:448; }","duration":"214.415773ms","start":"2026-07-24T17:26:42.703692Z","end":"2026-07-24T17:26:42.918107Z","steps":["trace[439323829] 'agreement among raft nodes before linearized reading' (duration: 214.328221ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:26:42.91811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:26:42.269983Z","time spent":"648.036739ms","remote":"127.0.0.1:41500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T17:26:42.918236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.720953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T17:26:42.918451Z","caller":"traceutil/trace.go:171","msg":"trace[1693488755] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium; range_end:; response_count:0; response_revision:448; }","duration":"155.9825ms","start":"2026-07-24T17:26:42.762445Z","end":"2026-07-24T17:26:42.918428Z","steps":["trace[1693488755] 'agreement among raft nodes before linearized reading' (duration: 155.650761ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:26:45.20361Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5646844970791745616,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-24T17:26:45.403703Z","caller":"traceutil/trace.go:171","msg":"trace[177687765] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"762.249525ms","start":"2026-07-24T17:26:44.641427Z","end":"2026-07-24T17:26:45.403677Z","steps":["trace[177687765] 'process raft request' (duration: 762.098972ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:26:45.403881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:26:44.64141Z","time spent":"762.389669ms","remote":"127.0.0.1:41518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-24T17:26:45.410707Z","caller":"traceutil/trace.go:171","msg":"trace[1707103537] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"768.565642ms","start":"2026-07-24T17:26:44.642127Z","end":"2026-07-24T17:26:45.410693Z","steps":["trace[1707103537] 'process raft request' (duration: 768.297046ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:26:45.41079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:26:44.642093Z","time spent":"768.660184ms","remote":"127.0.0.1:41518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-24T17:26:45.411374Z","caller":"traceutil/trace.go:171","msg":"trace[1487333714] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"758.079912ms","start":"2026-07-24T17:26:44.653282Z","end":"2026-07-24T17:26:45.411362Z","steps":["trace[1487333714] 'process raft request' (duration: 757.389065ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:26:45.411436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:26:44.653265Z","time spent":"758.142113ms","remote":"127.0.0.1:41518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-24T17:26:45.411684Z","caller":"traceutil/trace.go:171","msg":"trace[1270651021] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"758.269386ms","start":"2026-07-24T17:26:44.653401Z","end":"2026-07-24T17:26:45.41167Z","steps":["trace[1270651021] 'process raft request' (duration: 757.357714ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:26:45.411738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:26:44.653384Z","time spent":"758.332488ms","remote":"127.0.0.1:41518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-24T17:26:45.410916Z","caller":"traceutil/trace.go:171","msg":"trace[153290174] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"759.423485ms","start":"2026-07-24T17:26:44.651456Z","end":"2026-07-24T17:26:45.410879Z","steps":["trace[153290174] 'process raft request' (duration: 759.122508ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:26:45.413674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:26:44.651447Z","time spent":"762.135502ms","remote":"127.0.0.1:41518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-24T17:26:45.415355Z","caller":"traceutil/trace.go:171","msg":"trace[421931162] linearizableReadLoop","detail":"{readStateIndex:519; appliedIndex:507; }","duration":"712.815808ms","start":"2026-07-24T17:26:44.70252Z","end":"2026-07-24T17:26:45.415336Z","steps":["trace[421931162] 'read index received' (duration: 701.094397ms)","trace[421931162] 'applied index is now lower than readState.Index' (duration: 11.720321ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T17:26:45.415621Z","caller":"traceutil/trace.go:171","msg":"trace[1737899079] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"758.864031ms","start":"2026-07-24T17:26:44.656749Z","end":"2026-07-24T17:26:45.415613Z","steps":["trace[1737899079] 'process raft request' (duration: 757.455246ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:26:45.415663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:26:44.656741Z","time spent":"758.895912ms","remote":"127.0.0.1:41518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-24T17:26:45.415773Z","caller":"traceutil/trace.go:171","msg":"trace[1566930031] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"758.948502ms","start":"2026-07-24T17:26:44.656816Z","end":"2026-07-24T17:26:45.415764Z","steps":["trace[1566930031] 'process raft request' (duration: 757.581888ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:26:45.41581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:26:44.656807Z","time spent":"758.986335ms","remote":"127.0.0.1:41518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-24T17:26:45.415905Z","caller":"traceutil/trace.go:171","msg":"trace[1109018882] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"758.671616ms","start":"2026-07-24T17:26:44.657227Z","end":"2026-07-24T17:26:45.415899Z","steps":["trace[1109018882] 'process raft request' (duration: 757.242471ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:26:45.415945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:26:44.657221Z","time spent":"758.705957ms","remote":"127.0.0.1:41518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-24T17:26:45.415995Z","caller":"traceutil/trace.go:171","msg":"trace[1575278131] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"601.189905ms","start":"2026-07-24T17:26:44.814786Z","end":"2026-07-24T17:26:45.415976Z","steps":["trace[1575278131] 'process raft request' (duration: 600.387194ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:26:45.41608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:26:44.81477Z","time spent":"601.267687ms","remote":"127.0.0.1:41518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-24T17:26:45.416134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"713.603487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T17:26:45.416166Z","caller":"traceutil/trace.go:171","msg":"trace[1981805313] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:499; }","duration":"713.665169ms","start":"2026-07-24T17:26:44.702493Z","end":"2026-07-24T17:26:45.416159Z","steps":["trace[1981805313] 'agreement among raft nodes before linearized reading' (duration: 713.544896ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:26:45.416187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:26:44.702482Z","time spent":"713.70102ms","remote":"127.0.0.1:41476","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-24T17:26:45.416202Z","caller":"traceutil/trace.go:171","msg":"trace[921908744] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"742.898155ms","start":"2026-07-24T17:26:44.673293Z","end":"2026-07-24T17:26:45.416191Z","steps":["trace[921908744] 'process raft request' (duration: 741.266185ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:26:45.417103Z","caller":"traceutil/trace.go:171","msg":"trace[1280684930] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"718.18695ms","start":"2026-07-24T17:26:44.698907Z","end":"2026-07-24T17:26:45.417094Z","steps":["trace[1280684930] 'process raft request' (duration: 715.936884ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:26:45.417152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:26:44.6989Z","time spent":"718.232401ms","remote":"127.0.0.1:41518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-24T17:26:45.417237Z","caller":"traceutil/trace.go:171","msg":"trace[702866902] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"727.83493ms","start":"2026-07-24T17:26:44.689395Z","end":"2026-07-24T17:26:45.41723Z","steps":["trace[702866902] 'process raft request' (duration: 725.309287ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:26:45.417276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:26:44.689381Z","time spent":"727.877301ms","remote":"127.0.0.1:41518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-24T17:26:45.417781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:26:44.673282Z","time spent":"742.940076ms","remote":"127.0.0.1:41518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-24T17:26:45.422604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.138909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-24T17:26:45.422657Z","caller":"traceutil/trace.go:171","msg":"trace[1122771530] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:501; }","duration":"449.237621ms","start":"2026-07-24T17:26:44.973408Z","end":"2026-07-24T17:26:45.422646Z","steps":["trace[1122771530] 'agreement among raft nodes before linearized reading' (duration: 449.122658ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:26:45.422681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:26:44.973387Z","time spent":"449.289602ms","remote":"127.0.0.1:41768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-24T17:26:45.422985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.102401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-24T17:26:45.423061Z","caller":"traceutil/trace.go:171","msg":"trace[2027063735] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:501; }","duration":"116.307257ms","start":"2026-07-24T17:26:45.306737Z","end":"2026-07-24T17:26:45.423044Z","steps":["trace[2027063735] 'agreement among raft nodes before linearized reading' (duration: 116.116512ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:26:45.42286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.479003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-24T17:26:45.423163Z","caller":"traceutil/trace.go:171","msg":"trace[314633368] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:501; }","duration":"382.797381ms","start":"2026-07-24T17:26:45.040358Z","end":"2026-07-24T17:26:45.423156Z","steps":["trace[314633368] 'agreement among raft nodes before linearized reading' (duration: 382.437443ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:26:45.423215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:26:45.040348Z","time spent":"382.860253ms","remote":"127.0.0.1:41768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-24T17:26:45.422894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.319357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-24T17:26:45.423301Z","caller":"traceutil/trace.go:171","msg":"trace[737515874] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:501; }","duration":"421.752989ms","start":"2026-07-24T17:26:45.001543Z","end":"2026-07-24T17:26:45.423296Z","steps":["trace[737515874] 'agreement among raft nodes before linearized reading' (duration: 421.334738ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:26:45.423326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:26:45.001531Z","time spent":"421.787679ms","remote":"127.0.0.1:41768","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":"info","ts":"2026-07-24T17:26:51.561695Z","caller":"traceutil/trace.go:171","msg":"trace[807673915] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"252.788779ms","start":"2026-07-24T17:26:51.308885Z","end":"2026-07-24T17:26:51.561674Z","steps":["trace[807673915] 'process raft request' (duration: 251.241301ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:26:51.997764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.651093ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-24T17:26:51.997917Z","caller":"traceutil/trace.go:171","msg":"trace[975791481] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"420.602528ms","start":"2026-07-24T17:26:51.577293Z","end":"2026-07-24T17:26:51.997895Z","steps":["trace[975791481] 'process raft request' (duration: 155.699889ms)","trace[975791481] 'compare' (duration: 264.51758ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T17:26:51.997988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:26:51.577271Z","time spent":"420.68732ms","remote":"127.0.0.1:41768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T17:26:52.356943Z","caller":"traceutil/trace.go:171","msg":"trace[1983928062] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"779.376431ms","start":"2026-07-24T17:26:51.5775Z","end":"2026-07-24T17:26:52.356877Z","steps":["trace[1983928062] 'process raft request' (duration: 711.356861ms)","trace[1983928062] 'compare' (duration: 67.674662ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T17:26:52.357113Z","caller":"traceutil/trace.go:171","msg":"trace[465174541] linearizableReadLoop","detail":"{readStateIndex:626; appliedIndex:621; }","duration":"651.243193ms","start":"2026-07-24T17:26:51.70585Z","end":"2026-07-24T17:26:52.357094Z","steps":["trace[465174541] 'read index received' (duration: 27.165581ms)","trace[465174541] 'applied index is now lower than readState.Index' (duration: 624.076152ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T17:26:52.35731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:26:51.577473Z","time spent":"779.626146ms","remote":"127.0.0.1:41768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T17:26:52.357377Z","caller":"traceutil/trace.go:171","msg":"trace[1199266103] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"743.69299ms","start":"2026-07-24T17:26:51.613601Z","end":"2026-07-24T17:26:52.357294Z","steps":["trace[1199266103] 'process raft request' (duration: 743.413232ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:26:52.35742Z","caller":"traceutil/trace.go:171","msg":"trace[2092811986] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"779.363841ms","start":"2026-07-24T17:26:51.578043Z","end":"2026-07-24T17:26:52.357406Z","steps":["trace[2092811986] 'process raft request' (duration: 778.935ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:26:52.357508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:26:51.578019Z","time spent":"779.441574ms","remote":"127.0.0.1:41768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T17:26:52.357585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:26:51.613582Z","time spent":"743.906484ms","remote":"127.0.0.1:42022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-24T17:26:52.357615Z","caller":"traceutil/trace.go:171","msg":"trace[601694431] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"780.013737ms","start":"2026-07-24T17:26:51.577543Z","end":"2026-07-24T17:26:52.357557Z","steps":["trace[601694431] 'process raft request' (duration: 779.144115ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:26:52.35772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.978537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.205\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-24T17:26:52.357761Z","caller":"traceutil/trace.go:171","msg":"trace[1614084966] range","detail":"{range_begin:/registry/masterleases/199.204.45.205; range_end:; response_count:1; response_revision:598; }","duration":"144.076029ms","start":"2026-07-24T17:26:52.213671Z","end":"2026-07-24T17:26:52.357747Z","steps":["trace[1614084966] 'agreement among raft nodes before linearized reading' (duration: 144.002348ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:26:52.357805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:26:51.577503Z","time spent":"780.206802ms","remote":"127.0.0.1:41768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T17:26:52.357931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"525.170327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-t4xph\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-07-24T17:26:52.358108Z","caller":"traceutil/trace.go:171","msg":"trace[1701914675] range","detail":"{range_begin:/registry/pods/kube-system/cilium-t4xph; range_end:; response_count:1; response_revision:598; }","duration":"525.345131ms","start":"2026-07-24T17:26:51.832669Z","end":"2026-07-24T17:26:52.358014Z","steps":["trace[1701914675] 'agreement among raft nodes before linearized reading' (duration: 524.935431ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:26:52.358238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"652.399813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T17:26:52.358286Z","caller":"traceutil/trace.go:171","msg":"trace[1253518505] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:598; }","duration":"652.472475ms","start":"2026-07-24T17:26:51.705795Z","end":"2026-07-24T17:26:52.358267Z","steps":["trace[1253518505] 'agreement among raft nodes before linearized reading' (duration: 651.506711ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:26:52.358252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:26:51.832656Z","time spent":"525.581838ms","remote":"127.0.0.1:41666","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16987,"request content":"key:\"/registry/pods/kube-system/cilium-t4xph\" "} {"level":"warn","ts":"2026-07-24T17:26:52.358324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:26:51.705745Z","time spent":"652.572277ms","remote":"127.0.0.1:41478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-24T17:26:52.580592Z","caller":"traceutil/trace.go:171","msg":"trace[1027570515] linearizableReadLoop","detail":"{readStateIndex:629; appliedIndex:628; }","duration":"170.38151ms","start":"2026-07-24T17:26:52.410182Z","end":"2026-07-24T17:26:52.580564Z","steps":["trace[1027570515] 'read index received' (duration: 170.063981ms)","trace[1027570515] 'applied index is now lower than readState.Index' (duration: 316.318µs)"],"step_count":2} {"level":"info","ts":"2026-07-24T17:26:52.580656Z","caller":"traceutil/trace.go:171","msg":"trace[798175798] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"206.497051ms","start":"2026-07-24T17:26:52.374128Z","end":"2026-07-24T17:26:52.580625Z","steps":["trace[798175798] 'process raft request' (duration: 206.248175ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:26:52.580778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.568334ms","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-07-24T17:26:52.580877Z","caller":"traceutil/trace.go:171","msg":"trace[612264470] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:600; }","duration":"170.691106ms","start":"2026-07-24T17:26:52.410148Z","end":"2026-07-24T17:26:52.580839Z","steps":["trace[612264470] 'agreement among raft nodes before linearized reading' (duration: 170.520632ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:26:54.207756Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5646844970791745874,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-24T17:26:54.373392Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.567884478s","expected-duration":"1s"} {"level":"info","ts":"2026-07-24T17:26:54.374388Z","caller":"traceutil/trace.go:171","msg":"trace[1955609116] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"1.571188959s","start":"2026-07-24T17:26:52.803177Z","end":"2026-07-24T17:26:54.374366Z","steps":["trace[1955609116] 'process raft request' (duration: 1.570689837s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:26:54.374602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:26:52.803133Z","time spent":"1.571323663s","remote":"127.0.0.1:41572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036309,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T17:26:54.380957Z","caller":"traceutil/trace.go:171","msg":"trace[1151976406] linearizableReadLoop","detail":"{readStateIndex:631; appliedIndex:629; }","duration":"673.990914ms","start":"2026-07-24T17:26:53.706949Z","end":"2026-07-24T17:26:54.38094Z","steps":["trace[1151976406] 'read index received' (duration: 666.696625ms)","trace[1151976406] 'applied index is now lower than readState.Index' (duration: 7.293399ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T17:26:54.381068Z","caller":"traceutil/trace.go:171","msg":"trace[1506648638] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"336.944044ms","start":"2026-07-24T17:26:54.044054Z","end":"2026-07-24T17:26:54.380998Z","steps":["trace[1506648638] 'process raft request' (duration: 336.809701ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:26:54.381096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"674.131978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T17:26:54.381122Z","caller":"traceutil/trace.go:171","msg":"trace[726562033] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:602; }","duration":"674.2045ms","start":"2026-07-24T17:26:53.70691Z","end":"2026-07-24T17:26:54.381115Z","steps":["trace[726562033] 'agreement among raft nodes before linearized reading' (duration: 674.147228ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:26:54.381146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:26:53.706893Z","time spent":"674.247671ms","remote":"127.0.0.1:41478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-24T17:26:54.381202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.95632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-24T17:26:54.381256Z","caller":"traceutil/trace.go:171","msg":"trace[1501422884] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:602; }","duration":"379.042163ms","start":"2026-07-24T17:26:54.0022Z","end":"2026-07-24T17:26:54.381242Z","steps":["trace[1501422884] 'agreement among raft nodes before linearized reading' (duration: 378.896879ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:26:54.381285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:26:54.002186Z","time spent":"379.092433ms","remote":"127.0.0.1:41768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-24T17:26:54.381267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:26:54.044029Z","time spent":"337.119118ms","remote":"127.0.0.1:41768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T17:26:57.942462Z","caller":"traceutil/trace.go:171","msg":"trace[1760273363] transaction","detail":"{read_only:false; response_revision:664; number_of_response:1; }","duration":"120.896058ms","start":"2026-07-24T17:26:57.821541Z","end":"2026-07-24T17:26:57.942437Z","steps":["trace[1760273363] 'process raft request' (duration: 120.759175ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:26:58.286671Z","caller":"traceutil/trace.go:171","msg":"trace[687432325] transaction","detail":"{read_only:false; response_revision:668; number_of_response:1; }","duration":"111.320091ms","start":"2026-07-24T17:26:58.175322Z","end":"2026-07-24T17:26:58.286642Z","steps":["trace[687432325] 'process raft request' (duration: 110.733797ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:27:00.028363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.866075ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-24T17:27:00.028574Z","caller":"traceutil/trace.go:171","msg":"trace[213057982] transaction","detail":"{read_only:false; response_revision:685; number_of_response:1; }","duration":"164.612219ms","start":"2026-07-24T17:26:59.86392Z","end":"2026-07-24T17:27:00.028532Z","steps":["trace[213057982] 'process raft request' (duration: 21.603931ms)","trace[213057982] 'compare' (duration: 142.066404ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T17:27:00.028654Z","caller":"traceutil/trace.go:171","msg":"trace[500694633] linearizableReadLoop","detail":"{readStateIndex:715; appliedIndex:714; }","duration":"134.197721ms","start":"2026-07-24T17:26:59.894405Z","end":"2026-07-24T17:27:00.028603Z","steps":["trace[500694633] 'read index received' (duration: 28.691µs)","trace[500694633] 'applied index is now lower than readState.Index' (duration: 134.16634ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T17:27:00.028944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.51621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-t4xph\" ","response":"range_response_count:1 size:18337"} {"level":"info","ts":"2026-07-24T17:27:00.029053Z","caller":"traceutil/trace.go:171","msg":"trace[1739779083] range","detail":"{range_begin:/registry/pods/kube-system/cilium-t4xph; range_end:; response_count:1; response_revision:685; }","duration":"134.638243ms","start":"2026-07-24T17:26:59.894371Z","end":"2026-07-24T17:27:00.02901Z","steps":["trace[1739779083] 'agreement among raft nodes before linearized reading' (duration: 134.358055ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:27:00.029046Z","caller":"traceutil/trace.go:171","msg":"trace[1448358324] transaction","detail":"{read_only:false; response_revision:686; number_of_response:1; }","duration":"133.820462ms","start":"2026-07-24T17:26:59.895167Z","end":"2026-07-24T17:27:00.028987Z","steps":["trace[1448358324] 'process raft request' (duration: 133.310489ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:27:10.652684Z","caller":"traceutil/trace.go:171","msg":"trace[45750634] linearizableReadLoop","detail":"{readStateIndex:799; appliedIndex:798; }","duration":"119.453443ms","start":"2026-07-24T17:27:10.533208Z","end":"2026-07-24T17:27:10.652661Z","steps":["trace[45750634] 'read index received' (duration: 119.240917ms)","trace[45750634] 'applied index is now lower than readState.Index' (duration: 211.636µs)"],"step_count":2} {"level":"warn","ts":"2026-07-24T17:27:10.652952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.693028ms","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-07-24T17:27:10.652993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.491874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-07-24T17:27:10.652976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.627752ms","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-07-24T17:27:10.653058Z","caller":"traceutil/trace.go:171","msg":"trace[1951739780] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:766; }","duration":"119.611357ms","start":"2026-07-24T17:27:10.533435Z","end":"2026-07-24T17:27:10.653046Z","steps":["trace[1951739780] 'agreement among raft nodes before linearized reading' (duration: 119.408692ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:27:10.653053Z","caller":"traceutil/trace.go:171","msg":"trace[1779298025] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:766; }","duration":"119.828932ms","start":"2026-07-24T17:27:10.533183Z","end":"2026-07-24T17:27:10.653012Z","steps":["trace[1779298025] 'agreement among raft nodes before linearized reading' (duration: 119.608007ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:27:10.653074Z","caller":"traceutil/trace.go:171","msg":"trace[1656192903] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:766; }","duration":"118.750435ms","start":"2026-07-24T17:27:10.534306Z","end":"2026-07-24T17:27:10.653057Z","steps":["trace[1656192903] 'agreement among raft nodes before linearized reading' (duration: 118.52027ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:27:10.653496Z","caller":"traceutil/trace.go:171","msg":"trace[950507835] transaction","detail":"{read_only:false; response_revision:766; number_of_response:1; }","duration":"121.898732ms","start":"2026-07-24T17:27:10.53087Z","end":"2026-07-24T17:27:10.652768Z","steps":["trace[950507835] 'process raft request' (duration: 121.652326ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:27:11.207337Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5646844970791746188,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-24T17:27:11.473223Z","caller":"traceutil/trace.go:171","msg":"trace[1374248531] transaction","detail":"{read_only:false; response_revision:767; number_of_response:1; }","duration":"815.882202ms","start":"2026-07-24T17:27:10.657264Z","end":"2026-07-24T17:27:11.473146Z","steps":["trace[1374248531] 'process raft request' (duration: 815.571455ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:27:11.473562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:27:10.657246Z","time spent":"816.122128ms","remote":"127.0.0.1:41768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T17:27:11.482441Z","caller":"traceutil/trace.go:171","msg":"trace[1285963881] linearizableReadLoop","detail":"{readStateIndex:804; appliedIndex:800; }","duration":"775.962816ms","start":"2026-07-24T17:27:10.706459Z","end":"2026-07-24T17:27:11.482422Z","steps":["trace[1285963881] 'read index received' (duration: 767.15751ms)","trace[1285963881] 'applied index is now lower than readState.Index' (duration: 8.804846ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T17:27:11.482591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"776.12302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T17:27:11.482611Z","caller":"traceutil/trace.go:171","msg":"trace[256197381] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:771; }","duration":"776.194542ms","start":"2026-07-24T17:27:10.706409Z","end":"2026-07-24T17:27:11.482604Z","steps":["trace[256197381] 'agreement among raft nodes before linearized reading' (duration: 776.09201ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:27:11.48263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:27:10.706387Z","time spent":"776.239523ms","remote":"127.0.0.1:41478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-24T17:27:11.482741Z","caller":"traceutil/trace.go:171","msg":"trace[2714210] transaction","detail":"{read_only:false; response_revision:769; number_of_response:1; }","duration":"824.524384ms","start":"2026-07-24T17:27:10.658174Z","end":"2026-07-24T17:27:11.482698Z","steps":["trace[2714210] 'process raft request' (duration: 824.168065ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:27:11.482778Z","caller":"traceutil/trace.go:171","msg":"trace[1046810214] transaction","detail":"{read_only:false; response_revision:771; number_of_response:1; }","duration":"277.261518ms","start":"2026-07-24T17:27:11.205511Z","end":"2026-07-24T17:27:11.482772Z","steps":["trace[1046810214] 'process raft request' (duration: 276.887569ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:27:11.482767Z","caller":"traceutil/trace.go:171","msg":"trace[292268307] transaction","detail":"{read_only:false; response_revision:770; number_of_response:1; }","duration":"507.783135ms","start":"2026-07-24T17:27:10.974961Z","end":"2026-07-24T17:27:11.482744Z","steps":["trace[292268307] 'process raft request' (duration: 507.413166ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:27:11.482852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:27:10.658165Z","time spent":"824.608916ms","remote":"127.0.0.1:41768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T17:27:11.482948Z","caller":"traceutil/trace.go:171","msg":"trace[1020427618] transaction","detail":"{read_only:false; response_revision:768; number_of_response:1; }","duration":"824.920053ms","start":"2026-07-24T17:27:10.658023Z","end":"2026-07-24T17:27:11.482943Z","steps":["trace[1020427618] 'process raft request' (duration: 824.081254ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:27:11.482964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:27:10.974924Z","time spent":"507.930279ms","remote":"127.0.0.1:41730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T17:27:11.482988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:27:10.658011Z","time spent":"824.958104ms","remote":"127.0.0.1:41768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T17:28:00.325874Z","caller":"traceutil/trace.go:171","msg":"trace[5774503] transaction","detail":"{read_only:false; response_revision:1066; number_of_response:1; }","duration":"191.899677ms","start":"2026-07-24T17:28:00.133946Z","end":"2026-07-24T17:28:00.325845Z","steps":["trace[5774503] 'process raft request' (duration: 191.736182ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:28:02.444812Z","caller":"traceutil/trace.go:171","msg":"trace[766430089] transaction","detail":"{read_only:false; response_revision:1072; number_of_response:1; }","duration":"220.914833ms","start":"2026-07-24T17:28:02.223855Z","end":"2026-07-24T17:28:02.44477Z","steps":["trace[766430089] 'process raft request' (duration: 180.593363ms)","trace[766430089] 'compare' (duration: 40.027402ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T17:28:02.444802Z","caller":"traceutil/trace.go:171","msg":"trace[620183859] transaction","detail":"{read_only:false; response_revision:1073; number_of_response:1; }","duration":"113.829857ms","start":"2026-07-24T17:28:02.330934Z","end":"2026-07-24T17:28:02.444764Z","steps":["trace[620183859] 'process raft request' (duration: 113.756876ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:29:01.078952Z","caller":"traceutil/trace.go:171","msg":"trace[1541340276] transaction","detail":"{read_only:false; response_revision:1304; number_of_response:1; }","duration":"215.840668ms","start":"2026-07-24T17:29:00.863086Z","end":"2026-07-24T17:29:01.078926Z","steps":["trace[1541340276] 'process raft request' (duration: 215.692674ms)"],"step_count":1}