{"level":"warn","ts":"2026-04-08T00:30:51.241668Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-08T00:30:51.243126Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.89: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.89:2380","--initial-cluster=instance=https://199.204.45.89:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.89:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.89: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-04-08T00:30:51.243229Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-08T00:30:51.243254Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.89:2380"]} {"level":"info","ts":"2026-04-08T00:30:51.243287Z","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-04-08T00:30:51.244236Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.89:2379"]} {"level":"info","ts":"2026-04-08T00:30:51.244372Z","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.89:2380"],"listen-peer-urls":["https://199.204.45.89:2380"],"advertise-client-urls":["https://199.204.45.89:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.89:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.89: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-04-08T00:30:51.249612Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.893476ms"} {"level":"info","ts":"2026-04-08T00:30:51.257392Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"c3b8446219deb8c1","cluster-id":"fe02a46d8aa9751f"} {"level":"info","ts":"2026-04-08T00:30:51.257571Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 switched to configuration voters=()"} {"level":"info","ts":"2026-04-08T00:30:51.257596Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became follower at term 0"} {"level":"info","ts":"2026-04-08T00:30:51.257607Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft c3b8446219deb8c1 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-08T00:30:51.257619Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became follower at term 1"} {"level":"info","ts":"2026-04-08T00:30:51.257665Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 switched to configuration voters=(14103097421242218689)"} {"level":"warn","ts":"2026-04-08T00:30:51.265134Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-08T00:30:51.268917Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-08T00:30:51.270933Z","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-04-08T00:30:51.27324Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"c3b8446219deb8c1","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-08T00:30:51.273408Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"c3b8446219deb8c1","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-08T00:30:51.273908Z","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-04-08T00:30:51.274038Z","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-04-08T00:30:51.27408Z","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-04-08T00:30:51.274777Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 switched to configuration voters=(14103097421242218689)"} {"level":"info","ts":"2026-04-08T00:30:51.275174Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"fe02a46d8aa9751f","local-member-id":"c3b8446219deb8c1","added-peer-id":"c3b8446219deb8c1","added-peer-peer-urls":["https://199.204.45.89:2380"]} {"level":"info","ts":"2026-04-08T00:30:51.278867Z","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-04-08T00:30:51.278976Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.89:2380"} {"level":"info","ts":"2026-04-08T00:30:51.279033Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.89:2380"} {"level":"info","ts":"2026-04-08T00:30:51.279186Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"c3b8446219deb8c1","initial-advertise-peer-urls":["https://199.204.45.89:2380"],"listen-peer-urls":["https://199.204.45.89:2380"],"advertise-client-urls":["https://199.204.45.89:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.89:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-08T00:30:51.279219Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-08T00:30:51.558815Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 is starting a new election at term 1"} {"level":"info","ts":"2026-04-08T00:30:51.558881Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-08T00:30:51.55892Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 received MsgPreVoteResp from c3b8446219deb8c1 at term 1"} {"level":"info","ts":"2026-04-08T00:30:51.558944Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became candidate at term 2"} {"level":"info","ts":"2026-04-08T00:30:51.558963Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 received MsgVoteResp from c3b8446219deb8c1 at term 2"} {"level":"info","ts":"2026-04-08T00:30:51.558979Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became leader at term 2"} {"level":"info","ts":"2026-04-08T00:30:51.558998Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: c3b8446219deb8c1 elected leader c3b8446219deb8c1 at term 2"} {"level":"info","ts":"2026-04-08T00:30:51.560011Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-08T00:30:51.560834Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"c3b8446219deb8c1","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.89:2379]}","request-path":"/0/members/c3b8446219deb8c1/attributes","cluster-id":"fe02a46d8aa9751f","publish-timeout":"7s"} {"level":"info","ts":"2026-04-08T00:30:51.560979Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-08T00:30:51.561016Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-08T00:30:51.561448Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"fe02a46d8aa9751f","local-member-id":"c3b8446219deb8c1","cluster-version":"3.5"} {"level":"info","ts":"2026-04-08T00:30:51.561579Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-08T00:30:51.561615Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-08T00:30:51.56345Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.89:2379"} {"level":"info","ts":"2026-04-08T00:30:51.563446Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-08T00:30:51.563618Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-08T00:30:51.563648Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-08T00:30:56.579055Z","caller":"traceutil/trace.go:171","msg":"trace[484424148] linearizableReadLoop","detail":"{readStateIndex:247; appliedIndex:246; }","duration":"131.29776ms","start":"2026-04-08T00:30:56.447734Z","end":"2026-04-08T00:30:56.579032Z","steps":["trace[484424148] 'read index received' (duration: 131.174739ms)","trace[484424148] 'applied index is now lower than readState.Index' (duration: 121.951µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:30:56.579158Z","caller":"traceutil/trace.go:171","msg":"trace[1695666913] transaction","detail":"{read_only:false; response_revision:241; number_of_response:1; }","duration":"148.990223ms","start":"2026-04-08T00:30:56.43013Z","end":"2026-04-08T00:30:56.57912Z","steps":["trace[1695666913] 'process raft request' (duration: 148.793181ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:30:56.57948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.717056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/\" range_end:\"/registry/deployments/kube-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T00:30:56.579559Z","caller":"traceutil/trace.go:171","msg":"trace[1351200440] range","detail":"{range_begin:/registry/deployments/kube-system/; range_end:/registry/deployments/kube-system0; response_count:0; response_revision:241; }","duration":"131.823897ms","start":"2026-04-08T00:30:56.447709Z","end":"2026-04-08T00:30:56.579533Z","steps":["trace[1351200440] 'agreement among raft nodes before linearized reading' (duration: 131.433402ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:30:56.602338Z","caller":"traceutil/trace.go:171","msg":"trace[1842553787] transaction","detail":"{read_only:false; response_revision:242; number_of_response:1; }","duration":"123.211228ms","start":"2026-04-08T00:30:56.479106Z","end":"2026-04-08T00:30:56.602317Z","steps":["trace[1842553787] 'process raft request' (duration: 108.224738ms)","trace[1842553787] 'compare' (duration: 14.756817ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:31:00.60831Z","caller":"traceutil/trace.go:171","msg":"trace[882590525] linearizableReadLoop","detail":"{readStateIndex:334; appliedIndex:333; }","duration":"141.949606ms","start":"2026-04-08T00:31:00.466331Z","end":"2026-04-08T00:31:00.60828Z","steps":["trace[882590525] 'read index received' (duration: 42.541954ms)","trace[882590525] 'applied index is now lower than readState.Index' (duration: 99.406292ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:31:00.608595Z","caller":"traceutil/trace.go:171","msg":"trace[953956076] transaction","detail":"{read_only:false; response_revision:322; number_of_response:1; }","duration":"164.973145ms","start":"2026-04-08T00:31:00.443593Z","end":"2026-04-08T00:31:00.608566Z","steps":["trace[953956076] 'process raft request' (duration: 65.234139ms)","trace[953956076] 'compare' (duration: 99.31724ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:31:00.608659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.312211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/kube-proxy\" ","response":"range_response_count:1 size:185"} {"level":"info","ts":"2026-04-08T00:31:00.608859Z","caller":"traceutil/trace.go:171","msg":"trace[2123389288] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/kube-proxy; range_end:; response_count:1; response_revision:322; }","duration":"142.522744ms","start":"2026-04-08T00:31:00.466317Z","end":"2026-04-08T00:31:00.60884Z","steps":["trace[2123389288] 'agreement among raft nodes before linearized reading' (duration: 142.20406ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:31:00.609267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.844683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-04-08T00:31:00.609388Z","caller":"traceutil/trace.go:171","msg":"trace[1259897356] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:322; }","duration":"131.416089ms","start":"2026-04-08T00:31:00.477949Z","end":"2026-04-08T00:31:00.609365Z","steps":["trace[1259897356] 'agreement among raft nodes before linearized reading' (duration: 130.833772ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:31:00.80707Z","caller":"traceutil/trace.go:171","msg":"trace[681926065] transaction","detail":"{read_only:false; response_revision:330; number_of_response:1; }","duration":"101.535928ms","start":"2026-04-08T00:31:00.705504Z","end":"2026-04-08T00:31:00.80704Z","steps":["trace[681926065] 'process raft request' (duration: 101.425656ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:31:00.807215Z","caller":"traceutil/trace.go:171","msg":"trace[174168041] transaction","detail":"{read_only:false; response_revision:329; number_of_response:1; }","duration":"102.491129ms","start":"2026-04-08T00:31:00.704679Z","end":"2026-04-08T00:31:00.80717Z","steps":["trace[174168041] 'process raft request' (duration: 102.181586ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:31:00.807281Z","caller":"traceutil/trace.go:171","msg":"trace[2125363325] transaction","detail":"{read_only:false; response_revision:331; number_of_response:1; }","duration":"101.309815ms","start":"2026-04-08T00:31:00.705949Z","end":"2026-04-08T00:31:00.807259Z","steps":["trace[2125363325] 'process raft request' (duration: 101.018972ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:31:01.360224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.402262ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-08T00:31:01.360675Z","caller":"traceutil/trace.go:171","msg":"trace[318433651] transaction","detail":"{read_only:false; response_revision:335; number_of_response:1; }","duration":"550.518071ms","start":"2026-04-08T00:31:00.810078Z","end":"2026-04-08T00:31:01.360596Z","steps":["trace[318433651] 'process raft request' (duration: 199.078596ms)","trace[318433651] 'compare' (duration: 350.15975ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:31:01.360857Z","caller":"traceutil/trace.go:171","msg":"trace[1749876120] transaction","detail":"{read_only:false; response_revision:336; number_of_response:1; }","duration":"549.398328ms","start":"2026-04-08T00:31:00.811392Z","end":"2026-04-08T00:31:01.36079Z","steps":["trace[1749876120] 'process raft request' (duration: 549.106905ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:31:01.361043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:31:00.811373Z","time spent":"549.5794ms","remote":"127.0.0.1:47022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":164,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-08T00:31:01.360966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:31:00.810065Z","time spent":"550.721244ms","remote":"127.0.0.1:46926","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1731,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-08T00:31:01.361284Z","caller":"traceutil/trace.go:171","msg":"trace[27020443] transaction","detail":"{read_only:false; response_revision:337; number_of_response:1; }","duration":"549.350677ms","start":"2026-04-08T00:31:00.811924Z","end":"2026-04-08T00:31:01.361275Z","steps":["trace[27020443] 'process raft request' (duration: 548.642839ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:31:01.361332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:31:00.811912Z","time spent":"549.396828ms","remote":"127.0.0.1:46888","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-08T00:31:01.361453Z","caller":"traceutil/trace.go:171","msg":"trace[1892284011] transaction","detail":"{read_only:false; response_revision:338; number_of_response:1; }","duration":"548.580138ms","start":"2026-04-08T00:31:00.812813Z","end":"2026-04-08T00:31:01.361393Z","steps":["trace[1892284011] 'process raft request' (duration: 548.395665ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:31:01.361533Z","caller":"traceutil/trace.go:171","msg":"trace[592164068] transaction","detail":"{read_only:false; response_revision:339; number_of_response:1; }","duration":"548.342935ms","start":"2026-04-08T00:31:00.813165Z","end":"2026-04-08T00:31:01.361508Z","steps":["trace[592164068] 'process raft request' (duration: 548.108772ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:31:01.361522Z","caller":"traceutil/trace.go:171","msg":"trace[428930351] linearizableReadLoop","detail":"{readStateIndex:352; appliedIndex:346; }","duration":"189.499988ms","start":"2026-04-08T00:31:01.171998Z","end":"2026-04-08T00:31:01.361498Z","steps":["trace[428930351] 'read index received' (duration: 101.847409ms)","trace[428930351] 'applied index is now lower than readState.Index' (duration: 87.621309ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:31:01.361686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:31:00.812806Z","time spent":"548.74518ms","remote":"127.0.0.1:46888","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":830,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-08T00:31:01.361749Z","caller":"traceutil/trace.go:171","msg":"trace[409784110] transaction","detail":"{read_only:false; response_revision:340; number_of_response:1; }","duration":"546.391142ms","start":"2026-04-08T00:31:00.815344Z","end":"2026-04-08T00:31:01.361735Z","steps":["trace[409784110] 'process raft request' (duration: 546.070458ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:31:01.361882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.881482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/kube-proxy-t42bk.18a43a4e324acbb2\" ","response":"range_response_count:1 size:769"} {"level":"info","ts":"2026-04-08T00:31:01.361964Z","caller":"traceutil/trace.go:171","msg":"trace[1570162278] range","detail":"{range_begin:/registry/events/kube-system/kube-proxy-t42bk.18a43a4e324acbb2; range_end:; response_count:1; response_revision:340; }","duration":"190.019203ms","start":"2026-04-08T00:31:01.171929Z","end":"2026-04-08T00:31:01.361948Z","steps":["trace[1570162278] 'agreement among raft nodes before linearized reading' (duration: 189.851891ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:31:01.362036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:31:00.813159Z","time spent":"548.446776ms","remote":"127.0.0.1:47328","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3759,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T00:31:01.362245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:31:00.815336Z","time spent":"546.459602ms","remote":"127.0.0.1:46998","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4001,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T00:31:03.201267Z","caller":"traceutil/trace.go:171","msg":"trace[11911876] transaction","detail":"{read_only:false; response_revision:353; number_of_response:1; }","duration":"113.756693ms","start":"2026-04-08T00:31:03.087473Z","end":"2026-04-08T00:31:03.20123Z","steps":["trace[11911876] 'process raft request' (duration: 65.822471ms)","trace[11911876] 'compare' (duration: 47.74468ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:31:04.770647Z","caller":"traceutil/trace.go:171","msg":"trace[2009158200] transaction","detail":"{read_only:false; response_revision:361; number_of_response:1; }","duration":"159.692833ms","start":"2026-04-08T00:31:04.610913Z","end":"2026-04-08T00:31:04.770605Z","steps":["trace[2009158200] 'process raft request' (duration: 94.168563ms)","trace[2009158200] 'compare' (duration: 65.319148ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:31:05.674315Z","caller":"traceutil/trace.go:171","msg":"trace[780293186] transaction","detail":"{read_only:false; response_revision:364; number_of_response:1; }","duration":"104.210938ms","start":"2026-04-08T00:31:05.570074Z","end":"2026-04-08T00:31:05.674285Z","steps":["trace[780293186] 'process raft request' (duration: 104.029986ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:31:12.353849Z","caller":"traceutil/trace.go:171","msg":"trace[265541285] transaction","detail":"{read_only:false; response_revision:401; number_of_response:1; }","duration":"124.89018ms","start":"2026-04-08T00:31:12.22893Z","end":"2026-04-08T00:31:12.35382Z","steps":["trace[265541285] 'process raft request' (duration: 83.868207ms)","trace[265541285] 'compare' (duration: 40.902382ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:31:12.357047Z","caller":"traceutil/trace.go:171","msg":"trace[1264709794] transaction","detail":"{read_only:false; response_revision:402; number_of_response:1; }","duration":"128.072885ms","start":"2026-04-08T00:31:12.228951Z","end":"2026-04-08T00:31:12.357024Z","steps":["trace[1264709794] 'process raft request' (duration: 127.900683ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:31:12.357588Z","caller":"traceutil/trace.go:171","msg":"trace[759204984] transaction","detail":"{read_only:false; response_revision:403; number_of_response:1; }","duration":"128.61116ms","start":"2026-04-08T00:31:12.228965Z","end":"2026-04-08T00:31:12.357576Z","steps":["trace[759204984] 'process raft request' (duration: 128.013963ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:31:12.357735Z","caller":"traceutil/trace.go:171","msg":"trace[801216580] transaction","detail":"{read_only:false; response_revision:404; number_of_response:1; }","duration":"128.733891ms","start":"2026-04-08T00:31:12.228992Z","end":"2026-04-08T00:31:12.357726Z","steps":["trace[801216580] 'process raft request' (duration: 128.265606ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:31:12.357855Z","caller":"traceutil/trace.go:171","msg":"trace[1813524757] transaction","detail":"{read_only:false; response_revision:405; number_of_response:1; }","duration":"127.70423ms","start":"2026-04-08T00:31:12.230126Z","end":"2026-04-08T00:31:12.35783Z","steps":["trace[1813524757] 'process raft request' (duration: 127.210045ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:31:12.357943Z","caller":"traceutil/trace.go:171","msg":"trace[1845137148] transaction","detail":"{read_only:false; response_revision:406; number_of_response:1; }","duration":"125.87969ms","start":"2026-04-08T00:31:12.232052Z","end":"2026-04-08T00:31:12.357932Z","steps":["trace[1845137148] 'process raft request' (duration: 125.380185ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:31:13.114561Z","caller":"traceutil/trace.go:171","msg":"trace[1366841218] transaction","detail":"{read_only:false; response_revision:415; number_of_response:1; }","duration":"121.416032ms","start":"2026-04-08T00:31:12.993121Z","end":"2026-04-08T00:31:13.114537Z","steps":["trace[1366841218] 'process raft request' (duration: 121.274611ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:31:17.23281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.481860895s","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-04-08T00:31:17.232914Z","caller":"traceutil/trace.go:171","msg":"trace[819998850] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:422; }","duration":"1.482006787s","start":"2026-04-08T00:31:15.750886Z","end":"2026-04-08T00:31:17.232892Z","steps":["trace[819998850] 'range keys from in-memory index tree' (duration: 1.481672703s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:31:17.232966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:31:15.750867Z","time spent":"1.482086238s","remote":"127.0.0.1:47078","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-08T00:31:17.233033Z","caller":"traceutil/trace.go:171","msg":"trace[1831850116] transaction","detail":"{read_only:false; response_revision:423; number_of_response:1; }","duration":"364.293409ms","start":"2026-04-08T00:31:16.868724Z","end":"2026-04-08T00:31:17.233017Z","steps":["trace[1831850116] 'process raft request' (duration: 279.385436ms)","trace[1831850116] 'compare' (duration: 84.65128ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:31:17.233068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.011877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T00:31:17.23312Z","caller":"traceutil/trace.go:171","msg":"trace[2079965624] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:423; }","duration":"345.083067ms","start":"2026-04-08T00:31:16.888025Z","end":"2026-04-08T00:31:17.233108Z","steps":["trace[2079965624] 'agreement among raft nodes before linearized reading' (duration: 344.976846ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:31:17.232862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"850.090758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-08T00:31:17.233131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:31:16.868684Z","time spent":"364.390089ms","remote":"127.0.0.1:47078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T00:31:17.233159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:31:16.887983Z","time spent":"345.168138ms","remote":"127.0.0.1:46802","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-08T00:31:17.233167Z","caller":"traceutil/trace.go:171","msg":"trace[1525624602] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:422; }","duration":"850.426111ms","start":"2026-04-08T00:31:16.382729Z","end":"2026-04-08T00:31:17.233155Z","steps":["trace[1525624602] 'range keys from in-memory index tree' (duration: 849.984067ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:31:17.233206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:31:16.382712Z","time spent":"850.485351ms","remote":"127.0.0.1:47078","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-08T00:31:17.232929Z","caller":"traceutil/trace.go:171","msg":"trace[1499387935] linearizableReadLoop","detail":"{readStateIndex:440; appliedIndex:439; }","duration":"344.848805ms","start":"2026-04-08T00:31:16.888032Z","end":"2026-04-08T00:31:17.23288Z","steps":["trace[1499387935] 'read index received' (duration: 260.059623ms)","trace[1499387935] 'applied index is now lower than readState.Index' (duration: 84.787822ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:31:17.366315Z","caller":"traceutil/trace.go:171","msg":"trace[531145202] transaction","detail":"{read_only:false; response_revision:425; number_of_response:1; }","duration":"127.895159ms","start":"2026-04-08T00:31:17.23839Z","end":"2026-04-08T00:31:17.366285Z","steps":["trace[531145202] 'process raft request' (duration: 111.395378ms)","trace[531145202] 'compare' (duration: 16.346ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:31:17.36658Z","caller":"traceutil/trace.go:171","msg":"trace[716756169] transaction","detail":"{read_only:false; response_revision:426; number_of_response:1; }","duration":"126.02692ms","start":"2026-04-08T00:31:17.240489Z","end":"2026-04-08T00:31:17.366516Z","steps":["trace[716756169] 'process raft request' (duration: 125.755887ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:31:21.923497Z","caller":"traceutil/trace.go:171","msg":"trace[1765764320] transaction","detail":"{read_only:false; response_revision:435; number_of_response:1; }","duration":"226.752734ms","start":"2026-04-08T00:31:21.696696Z","end":"2026-04-08T00:31:21.923449Z","steps":["trace[1765764320] 'process raft request' (duration: 226.530651ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:31:27.016215Z","caller":"traceutil/trace.go:171","msg":"trace[158008082] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"590.431192ms","start":"2026-04-08T00:31:26.425766Z","end":"2026-04-08T00:31:27.016197Z","steps":["trace[158008082] 'process raft request' (duration: 590.339341ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:31:27.016346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:31:26.425744Z","time spent":"590.534733ms","remote":"127.0.0.1:46852","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-08T00:31:27.023933Z","caller":"traceutil/trace.go:171","msg":"trace[1721071934] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"585.548034ms","start":"2026-04-08T00:31:26.438358Z","end":"2026-04-08T00:31:27.023906Z","steps":["trace[1721071934] 'process raft request' (duration: 585.21292ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:31:27.024123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:31:26.438352Z","time spent":"585.718065ms","remote":"127.0.0.1:46852","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-08T00:31:27.024278Z","caller":"traceutil/trace.go:171","msg":"trace[505180867] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"589.060498ms","start":"2026-04-08T00:31:26.4352Z","end":"2026-04-08T00:31:27.024261Z","steps":["trace[505180867] 'process raft request' (duration: 588.25587ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:31:27.024313Z","caller":"traceutil/trace.go:171","msg":"trace[1849883077] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"595.616462ms","start":"2026-04-08T00:31:26.428663Z","end":"2026-04-08T00:31:27.02428Z","steps":["trace[1849883077] 'process raft request' (duration: 594.595802ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:31:27.024371Z","caller":"traceutil/trace.go:171","msg":"trace[1090256774] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"586.16297ms","start":"2026-04-08T00:31:26.438149Z","end":"2026-04-08T00:31:27.024312Z","steps":["trace[1090256774] 'process raft request' (duration: 585.386032ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:31:27.024381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:31:26.435188Z","time spent":"589.141769ms","remote":"127.0.0.1:46852","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-08T00:31:27.024479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:31:26.438134Z","time spent":"586.317401ms","remote":"127.0.0.1:46852","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-08T00:31:27.024659Z","caller":"traceutil/trace.go:171","msg":"trace[1020435557] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"572.193943ms","start":"2026-04-08T00:31:26.452449Z","end":"2026-04-08T00:31:27.024643Z","steps":["trace[1020435557] 'process raft request' (duration: 571.279194ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:31:27.024729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:31:26.452442Z","time spent":"572.260853ms","remote":"127.0.0.1:46852","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-08T00:31:27.024883Z","caller":"traceutil/trace.go:171","msg":"trace[171898262] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"580.154261ms","start":"2026-04-08T00:31:26.444718Z","end":"2026-04-08T00:31:27.024872Z","steps":["trace[171898262] 'process raft request' (duration: 578.905929ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:31:27.024941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:31:26.444694Z","time spent":"580.224531ms","remote":"127.0.0.1:46852","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-08T00:31:27.025081Z","caller":"traceutil/trace.go:171","msg":"trace[1047289636] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"574.769058ms","start":"2026-04-08T00:31:26.450303Z","end":"2026-04-08T00:31:27.025072Z","steps":["trace[1047289636] 'process raft request' (duration: 573.371054ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:31:27.025146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:31:26.45029Z","time spent":"574.828958ms","remote":"127.0.0.1:46852","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-08T00:31:27.025617Z","caller":"traceutil/trace.go:171","msg":"trace[736888264] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"539.588673ms","start":"2026-04-08T00:31:26.486002Z","end":"2026-04-08T00:31:27.025591Z","steps":["trace[736888264] 'process raft request' (duration: 537.850266ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:31:27.025679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:31:26.48599Z","time spent":"539.663344ms","remote":"127.0.0.1:46852","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-08T00:31:27.025692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:31:26.428643Z","time spent":"595.714384ms","remote":"127.0.0.1:46852","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-08T00:31:27.025889Z","caller":"traceutil/trace.go:171","msg":"trace[1859963182] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"432.091948ms","start":"2026-04-08T00:31:26.593788Z","end":"2026-04-08T00:31:27.02588Z","steps":["trace[1859963182] 'process raft request' (duration: 430.183159ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:31:27.025935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:31:26.593755Z","time spent":"432.157708ms","remote":"127.0.0.1:46852","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-08T00:31:27.024402Z","caller":"traceutil/trace.go:171","msg":"trace[228254926] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"595.512862ms","start":"2026-04-08T00:31:26.428839Z","end":"2026-04-08T00:31:27.024352Z","steps":["trace[228254926] 'process raft request' (duration: 594.572843ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:31:27.025853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.979552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T00:31:27.026056Z","caller":"traceutil/trace.go:171","msg":"trace[1167312454] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:491; }","duration":"138.227284ms","start":"2026-04-08T00:31:26.88782Z","end":"2026-04-08T00:31:27.026048Z","steps":["trace[1167312454] 'agreement among raft nodes before linearized reading' (duration: 136.352276ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:31:27.024047Z","caller":"traceutil/trace.go:171","msg":"trace[1677104808] linearizableReadLoop","detail":"{readStateIndex:510; appliedIndex:498; }","duration":"136.162485ms","start":"2026-04-08T00:31:26.887865Z","end":"2026-04-08T00:31:27.024027Z","steps":["trace[1677104808] 'read index received' (duration: 128.272688ms)","trace[1677104808] 'applied index is now lower than readState.Index' (duration: 7.888847ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:31:27.02613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:31:26.428818Z","time spent":"597.239639ms","remote":"127.0.0.1:46852","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-08T00:31:27.026188Z","caller":"traceutil/trace.go:171","msg":"trace[424493005] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"568.990541ms","start":"2026-04-08T00:31:26.457187Z","end":"2026-04-08T00:31:27.026178Z","steps":["trace[424493005] 'process raft request' (duration: 566.602937ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:31:27.026273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:31:26.45717Z","time spent":"569.082391ms","remote":"127.0.0.1:46852","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-08T00:31:30.450018Z","caller":"traceutil/trace.go:171","msg":"trace[156060270] linearizableReadLoop","detail":"{readStateIndex:593; appliedIndex:592; }","duration":"135.095567ms","start":"2026-04-08T00:31:30.314901Z","end":"2026-04-08T00:31:30.449997Z","steps":["trace[156060270] 'read index received' (duration: 134.491021ms)","trace[156060270] 'applied index is now lower than readState.Index' (duration: 603.696µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:31:30.450153Z","caller":"traceutil/trace.go:171","msg":"trace[386523940] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"258.397971ms","start":"2026-04-08T00:31:30.191745Z","end":"2026-04-08T00:31:30.450143Z","steps":["trace[386523940] 'process raft request' (duration: 257.897256ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:31:30.450438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.519931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T00:31:30.450485Z","caller":"traceutil/trace.go:171","msg":"trace[1272210704] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:567; }","duration":"135.587852ms","start":"2026-04-08T00:31:30.314886Z","end":"2026-04-08T00:31:30.450474Z","steps":["trace[1272210704] 'agreement among raft nodes before linearized reading' (duration: 135.472101ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:31:30.450717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.767974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T00:31:30.450808Z","caller":"traceutil/trace.go:171","msg":"trace[1948424017] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:567; }","duration":"135.915235ms","start":"2026-04-08T00:31:30.314872Z","end":"2026-04-08T00:31:30.450787Z","steps":["trace[1948424017] 'agreement among raft nodes before linearized reading' (duration: 135.742093ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:31:33.080534Z","caller":"traceutil/trace.go:171","msg":"trace[468920528] linearizableReadLoop","detail":"{readStateIndex:606; appliedIndex:605; }","duration":"191.947961ms","start":"2026-04-08T00:31:32.888555Z","end":"2026-04-08T00:31:33.080503Z","steps":["trace[468920528] 'read index received' (duration: 190.473757ms)","trace[468920528] 'applied index is now lower than readState.Index' (duration: 1.473254ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:31:33.08087Z","caller":"traceutil/trace.go:171","msg":"trace[672222721] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"225.108396ms","start":"2026-04-08T00:31:32.855466Z","end":"2026-04-08T00:31:33.080575Z","steps":["trace[672222721] 'process raft request' (duration: 223.820454ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:31:33.081198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.606547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T00:31:33.081279Z","caller":"traceutil/trace.go:171","msg":"trace[619450267] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:580; }","duration":"192.762288ms","start":"2026-04-08T00:31:32.888487Z","end":"2026-04-08T00:31:33.081249Z","steps":["trace[619450267] 'agreement among raft nodes before linearized reading' (duration: 192.131492ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:31:33.343354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.846907ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-08T00:31:33.343454Z","caller":"traceutil/trace.go:171","msg":"trace[1442340212] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"203.481795ms","start":"2026-04-08T00:31:33.139956Z","end":"2026-04-08T00:31:33.343438Z","steps":["trace[1442340212] 'process raft request' (duration: 71.482026ms)","trace[1442340212] 'compare' (duration: 131.735146ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:31:33.614909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.557057ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T00:31:33.615036Z","caller":"traceutil/trace.go:171","msg":"trace[1495474979] linearizableReadLoop","detail":"{readStateIndex:608; appliedIndex:607; }","duration":"145.92788ms","start":"2026-04-08T00:31:33.469087Z","end":"2026-04-08T00:31:33.615015Z","steps":["trace[1495474979] 'read index received' (duration: 37.181651ms)","trace[1495474979] 'applied index is now lower than readState.Index' (duration: 108.744819ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:31:33.615118Z","caller":"traceutil/trace.go:171","msg":"trace[1424214749] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"175.350468ms","start":"2026-04-08T00:31:33.439758Z","end":"2026-04-08T00:31:33.615108Z","steps":["trace[1424214749] 'process raft request' (duration: 66.500198ms)","trace[1424214749] 'compare' (duration: 108.314685ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:31:33.615348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.265553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-08T00:31:33.615283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.092303ms","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-04-08T00:31:33.61543Z","caller":"traceutil/trace.go:171","msg":"trace[1563045177] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:582; }","duration":"144.291075ms","start":"2026-04-08T00:31:33.471124Z","end":"2026-04-08T00:31:33.615415Z","steps":["trace[1563045177] 'agreement among raft nodes before linearized reading' (duration: 144.011262ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:31:33.615387Z","caller":"traceutil/trace.go:171","msg":"trace[1143993559] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:582; }","duration":"146.345664ms","start":"2026-04-08T00:31:33.469027Z","end":"2026-04-08T00:31:33.615372Z","steps":["trace[1143993559] 'agreement among raft nodes before linearized reading' (duration: 146.266233ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:31:35.076615Z","caller":"traceutil/trace.go:171","msg":"trace[184521107] linearizableReadLoop","detail":"{readStateIndex:618; appliedIndex:617; }","duration":"481.069225ms","start":"2026-04-08T00:31:34.595518Z","end":"2026-04-08T00:31:35.076587Z","steps":["trace[184521107] 'read index received' (duration: 480.006195ms)","trace[184521107] 'applied index is now lower than readState.Index' (duration: 1.06216ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:31:35.076783Z","caller":"traceutil/trace.go:171","msg":"trace[1691975691] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"646.406421ms","start":"2026-04-08T00:31:34.430366Z","end":"2026-04-08T00:31:35.076772Z","steps":["trace[1691975691] 'process raft request' (duration: 645.521933ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:31:35.076934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:31:34.430323Z","time spent":"646.522553ms","remote":"127.0.0.1:46920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036309,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T00:31:35.077172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.045036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-rbhk9\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-04-08T00:31:35.077269Z","caller":"traceutil/trace.go:171","msg":"trace[1405542048] range","detail":"{range_begin:/registry/pods/kube-system/cilium-rbhk9; range_end:; response_count:1; response_revision:592; }","duration":"376.202798ms","start":"2026-04-08T00:31:34.701044Z","end":"2026-04-08T00:31:35.077247Z","steps":["trace[1405542048] 'agreement among raft nodes before linearized reading' (duration: 375.908686ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:31:35.07731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:31:34.701026Z","time spent":"376.277999ms","remote":"127.0.0.1:46998","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16985,"request content":"key:\"/registry/pods/kube-system/cilium-rbhk9\" "} {"level":"warn","ts":"2026-04-08T00:31:35.077159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.487981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T00:31:35.077416Z","caller":"traceutil/trace.go:171","msg":"trace[485694635] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:592; }","duration":"188.825434ms","start":"2026-04-08T00:31:34.888576Z","end":"2026-04-08T00:31:35.077402Z","steps":["trace[485694635] 'agreement among raft nodes before linearized reading' (duration: 188.45063ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:31:35.078293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"482.768961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.89\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-08T00:31:35.078372Z","caller":"traceutil/trace.go:171","msg":"trace[1494045594] range","detail":"{range_begin:/registry/masterleases/199.204.45.89; range_end:; response_count:1; response_revision:592; }","duration":"482.891542ms","start":"2026-04-08T00:31:34.595462Z","end":"2026-04-08T00:31:35.078353Z","steps":["trace[1494045594] 'agreement among raft nodes before linearized reading' (duration: 481.872572ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:31:35.078509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:31:34.595438Z","time spent":"483.018544ms","remote":"127.0.0.1:46832","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.89\" "} {"level":"warn","ts":"2026-04-08T00:31:35.565802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.533611ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T00:31:35.565919Z","caller":"traceutil/trace.go:171","msg":"trace[1494279149] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"467.702572ms","start":"2026-04-08T00:31:35.098196Z","end":"2026-04-08T00:31:35.565898Z","steps":["trace[1494279149] 'process raft request' (duration: 159.972749ms)","trace[1494279149] 'compare' (duration: 307.296919ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:31:35.565978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:31:35.098171Z","time spent":"467.781513ms","remote":"127.0.0.1:46998","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T00:31:35.827746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.023027ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-08T00:31:35.827998Z","caller":"traceutil/trace.go:171","msg":"trace[28169775] linearizableReadLoop","detail":"{readStateIndex:622; appliedIndex:620; }","duration":"208.348421ms","start":"2026-04-08T00:31:35.619626Z","end":"2026-04-08T00:31:35.827975Z","steps":["trace[28169775] 'read index received' (duration: 76.980151ms)","trace[28169775] 'applied index is now lower than readState.Index' (duration: 131.36703ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:31:35.828045Z","caller":"traceutil/trace.go:171","msg":"trace[1354488909] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"687.993755ms","start":"2026-04-08T00:31:35.140035Z","end":"2026-04-08T00:31:35.828029Z","steps":["trace[1354488909] 'process raft request' (duration: 556.600214ms)","trace[1354488909] 'compare' (duration: 130.799405ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:31:35.828097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:31:35.14Z","time spent":"688.074786ms","remote":"127.0.0.1:47050","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-08T00:31:35.828111Z","caller":"traceutil/trace.go:171","msg":"trace[1654440748] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"679.116941ms","start":"2026-04-08T00:31:35.148981Z","end":"2026-04-08T00:31:35.828098Z","steps":["trace[1654440748] 'process raft request' (duration: 678.904799ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:31:35.828426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.923711ms","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-04-08T00:31:35.828248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.416787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-08T00:31:35.828475Z","caller":"traceutil/trace.go:171","msg":"trace[273673200] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:595; }","duration":"155.025623ms","start":"2026-04-08T00:31:35.673433Z","end":"2026-04-08T00:31:35.828459Z","steps":["trace[273673200] 'agreement among raft nodes before linearized reading' (duration: 154.846871ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:31:35.828506Z","caller":"traceutil/trace.go:171","msg":"trace[1719116323] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:595; }","duration":"200.717389ms","start":"2026-04-08T00:31:35.627777Z","end":"2026-04-08T00:31:35.828494Z","steps":["trace[1719116323] 'agreement among raft nodes before linearized reading' (duration: 200.379436ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:31:35.828324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.696854ms","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-04-08T00:31:35.828515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:31:35.148952Z","time spent":"679.474405ms","remote":"127.0.0.1:46832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T00:31:35.828578Z","caller":"traceutil/trace.go:171","msg":"trace[841473630] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:595; }","duration":"209.011147ms","start":"2026-04-08T00:31:35.619547Z","end":"2026-04-08T00:31:35.828558Z","steps":["trace[841473630] 'agreement among raft nodes before linearized reading' (duration: 208.681654ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:31:36.334131Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313095054219509302,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-08T00:31:36.83452Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313095054219509302,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-08T00:31:37.267412Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.43481438s","expected-duration":"1s"} {"level":"info","ts":"2026-04-08T00:31:37.304351Z","caller":"traceutil/trace.go:171","msg":"trace[179176609] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"1.471800893s","start":"2026-04-08T00:31:35.832512Z","end":"2026-04-08T00:31:37.304313Z","steps":["trace[179176609] 'process raft request' (duration: 1.435391155s)","trace[179176609] 'compare' (duration: 35.403819ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T00:31:37.304631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:31:35.832498Z","time spent":"1.472040715s","remote":"127.0.0.1:47078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T00:31:37.311273Z","caller":"traceutil/trace.go:171","msg":"trace[614931166] linearizableReadLoop","detail":"{readStateIndex:625; appliedIndex:622; }","duration":"1.477632967s","start":"2026-04-08T00:31:35.83361Z","end":"2026-04-08T00:31:37.311243Z","steps":["trace[614931166] 'read index received' (duration: 1.434334035s)","trace[614931166] 'applied index is now lower than readState.Index' (duration: 43.297312ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:31:37.311338Z","caller":"traceutil/trace.go:171","msg":"trace[1832067157] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"1.478509346s","start":"2026-04-08T00:31:35.832788Z","end":"2026-04-08T00:31:37.311297Z","steps":["trace[1832067157] 'process raft request' (duration: 1.478150493s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:31:37.311586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.313387359s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-04-08T00:31:37.311564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.476941171s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:1 size:193"} {"level":"warn","ts":"2026-04-08T00:31:37.311601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.423342558s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-08T00:31:37.311566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.47791582s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-08T00:31:37.311636Z","caller":"traceutil/trace.go:171","msg":"trace[1166690288] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:598; }","duration":"1.313455769s","start":"2026-04-08T00:31:35.998164Z","end":"2026-04-08T00:31:37.31162Z","steps":["trace[1166690288] 'agreement among raft nodes before linearized reading' (duration: 1.313313648s)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:31:37.311653Z","caller":"traceutil/trace.go:171","msg":"trace[295748277] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:598; }","duration":"1.42346206s","start":"2026-04-08T00:31:35.888177Z","end":"2026-04-08T00:31:37.311639Z","steps":["trace[295748277] 'agreement among raft nodes before linearized reading' (duration: 1.423346719s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:31:37.311664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:31:35.99815Z","time spent":"1.313508439s","remote":"127.0.0.1:47078","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":"info","ts":"2026-04-08T00:31:37.311672Z","caller":"traceutil/trace.go:171","msg":"trace[1189258544] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:598; }","duration":"1.478046071s","start":"2026-04-08T00:31:35.833607Z","end":"2026-04-08T00:31:37.311653Z","steps":["trace[1189258544] 'agreement among raft nodes before linearized reading' (duration: 1.477787949s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:31:37.311708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:31:35.888154Z","time spent":"1.423544881s","remote":"127.0.0.1:46802","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-08T00:31:37.311651Z","caller":"traceutil/trace.go:171","msg":"trace[59317896] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:598; }","duration":"1.477054652s","start":"2026-04-08T00:31:35.834577Z","end":"2026-04-08T00:31:37.311631Z","steps":["trace[59317896] 'agreement among raft nodes before linearized reading' (duration: 1.476890561s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:31:37.311757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:31:35.833591Z","time spent":"1.478149022s","remote":"127.0.0.1:46832","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" "} {"level":"warn","ts":"2026-04-08T00:31:37.311802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:31:35.834572Z","time spent":"1.477220014s","remote":"127.0.0.1:47022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":217,"request content":"key:\"/registry/serviceaccounts/kube-system/job-controller\" "} {"level":"warn","ts":"2026-04-08T00:31:37.311916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:31:35.832779Z","time spent":"1.478753078s","remote":"127.0.0.1:47078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T00:31:37.313217Z","caller":"traceutil/trace.go:171","msg":"trace[1927253458] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"1.478509585s","start":"2026-04-08T00:31:35.832823Z","end":"2026-04-08T00:31:37.311333Z","steps":["trace[1927253458] 'process raft request' (duration: 1.478359984s)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:31:37.313436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:31:35.832819Z","time spent":"1.480536164s","remote":"127.0.0.1:47078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T00:31:37.896838Z","caller":"traceutil/trace.go:171","msg":"trace[1250949591] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"172.051997ms","start":"2026-04-08T00:31:37.72474Z","end":"2026-04-08T00:31:37.896792Z","steps":["trace[1250949591] 'process raft request' (duration: 171.26909ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:31:53.389343Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313095054219509610,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-08T00:31:53.420403Z","caller":"traceutil/trace.go:171","msg":"trace[1368530441] linearizableReadLoop","detail":"{readStateIndex:786; appliedIndex:785; }","duration":"531.152056ms","start":"2026-04-08T00:31:52.889223Z","end":"2026-04-08T00:31:53.420375Z","steps":["trace[1368530441] 'read index received' (duration: 530.914793ms)","trace[1368530441] 'applied index is now lower than readState.Index' (duration: 235.893µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:31:53.420576Z","caller":"traceutil/trace.go:171","msg":"trace[553935166] transaction","detail":"{read_only:false; response_revision:755; number_of_response:1; }","duration":"602.895104ms","start":"2026-04-08T00:31:52.81763Z","end":"2026-04-08T00:31:53.420525Z","steps":["trace[553935166] 'process raft request' (duration: 602.579637ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:31:53.420687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"531.42394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T00:31:53.420813Z","caller":"traceutil/trace.go:171","msg":"trace[1366623687] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:755; }","duration":"531.577128ms","start":"2026-04-08T00:31:52.889198Z","end":"2026-04-08T00:31:53.420776Z","steps":["trace[1366623687] 'agreement among raft nodes before linearized reading' (duration: 531.332575ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:31:53.420873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:31:52.889132Z","time spent":"531.718266ms","remote":"127.0.0.1:46802","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-08T00:31:53.421057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T00:31:52.81761Z","time spent":"603.143358ms","remote":"127.0.0.1:46998","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4027,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T00:34:10.396032Z","caller":"traceutil/trace.go:171","msg":"trace[572270861] transaction","detail":"{read_only:false; response_revision:1384; number_of_response:1; }","duration":"127.618792ms","start":"2026-04-08T00:34:10.268388Z","end":"2026-04-08T00:34:10.396007Z","steps":["trace[572270861] 'process raft request' (duration: 127.425249ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:34:10.397253Z","caller":"traceutil/trace.go:171","msg":"trace[718670568] linearizableReadLoop","detail":"{readStateIndex:1448; appliedIndex:1446; }","duration":"126.849577ms","start":"2026-04-08T00:34:10.270373Z","end":"2026-04-08T00:34:10.397222Z","steps":["trace[718670568] 'read index received' (duration: 125.446321ms)","trace[718670568] 'applied index is now lower than readState.Index' (duration: 1.401826ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:34:10.397418Z","caller":"traceutil/trace.go:171","msg":"trace[1704660671] transaction","detail":"{read_only:false; response_revision:1385; number_of_response:1; }","duration":"128.486439ms","start":"2026-04-08T00:34:10.268912Z","end":"2026-04-08T00:34:10.397398Z","steps":["trace[1704660671] 'process raft request' (duration: 128.145832ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:34:10.397682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.973062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-04-08T00:34:10.397735Z","caller":"traceutil/trace.go:171","msg":"trace[659772506] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1385; }","duration":"126.052983ms","start":"2026-04-08T00:34:10.27167Z","end":"2026-04-08T00:34:10.397723Z","steps":["trace[659772506] 'agreement among raft nodes before linearized reading' (duration: 125.791658ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:34:10.397989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.602852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-08T00:34:10.398088Z","caller":"traceutil/trace.go:171","msg":"trace[1416674700] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1385; }","duration":"127.747475ms","start":"2026-04-08T00:34:10.270318Z","end":"2026-04-08T00:34:10.398066Z","steps":["trace[1416674700] 'agreement among raft nodes before linearized reading' (duration: 127.554612ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:34:20.634087Z","caller":"traceutil/trace.go:171","msg":"trace[1040250106] transaction","detail":"{read_only:false; response_revision:1418; number_of_response:1; }","duration":"108.833536ms","start":"2026-04-08T00:34:20.525216Z","end":"2026-04-08T00:34:20.63405Z","steps":["trace[1040250106] 'process raft request' (duration: 106.503874ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:34:20.865677Z","caller":"traceutil/trace.go:171","msg":"trace[89827979] transaction","detail":"{read_only:false; response_revision:1419; number_of_response:1; }","duration":"226.773388ms","start":"2026-04-08T00:34:20.638872Z","end":"2026-04-08T00:34:20.865645Z","steps":["trace[89827979] 'process raft request' (duration: 185.89357ms)","trace[89827979] 'compare' (duration: 40.353589ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:34:20.86969Z","caller":"traceutil/trace.go:171","msg":"trace[1186547811] linearizableReadLoop","detail":"{readStateIndex:1486; appliedIndex:1483; }","duration":"225.307052ms","start":"2026-04-08T00:34:20.644355Z","end":"2026-04-08T00:34:20.869662Z","steps":["trace[1186547811] 'read index received' (duration: 180.34999ms)","trace[1186547811] 'applied index is now lower than readState.Index' (duration: 44.956032ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T00:34:20.869713Z","caller":"traceutil/trace.go:171","msg":"trace[254442066] transaction","detail":"{read_only:false; response_revision:1420; number_of_response:1; }","duration":"230.704619ms","start":"2026-04-08T00:34:20.638979Z","end":"2026-04-08T00:34:20.869684Z","steps":["trace[254442066] 'process raft request' (duration: 230.539506ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T00:34:20.869874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.501085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-rbhk9\" ","response":"range_response_count:1 size:18521"} {"level":"info","ts":"2026-04-08T00:34:20.869915Z","caller":"traceutil/trace.go:171","msg":"trace[918369733] range","detail":"{range_begin:/registry/pods/kube-system/cilium-rbhk9; range_end:; response_count:1; response_revision:1421; }","duration":"225.578316ms","start":"2026-04-08T00:34:20.644327Z","end":"2026-04-08T00:34:20.869905Z","steps":["trace[918369733] 'agreement among raft nodes before linearized reading' (duration: 225.410623ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T00:34:20.870094Z","caller":"traceutil/trace.go:171","msg":"trace[960327011] transaction","detail":"{read_only:false; response_revision:1421; number_of_response:1; }","duration":"230.997044ms","start":"2026-04-08T00:34:20.639087Z","end":"2026-04-08T00:34:20.870084Z","steps":["trace[960327011] 'process raft request' (duration: 230.526056ms)"],"step_count":1}