{"level":"warn","ts":"2026-05-08T05:51:49.851751Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-08T05:51:49.851874Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.238:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.238:2380","--initial-cluster=instance=https://199.19.213.238:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.238:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.238:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-08T05:51:49.852063Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-08T05:51:49.852084Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.238:2380"]} {"level":"info","ts":"2026-05-08T05:51:49.85211Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-08T05:51:49.85304Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.238:2379"]} {"level":"info","ts":"2026-05-08T05:51:49.853143Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.238:2380"],"listen-peer-urls":["https://199.19.213.238:2380"],"advertise-client-urls":["https://199.19.213.238:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.238:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.238:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-08T05:51:49.85871Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.64982ms"} {"level":"info","ts":"2026-05-08T05:51:49.866907Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"8cf6035374726417","cluster-id":"e837e6e114b087e2"} {"level":"info","ts":"2026-05-08T05:51:49.867015Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8cf6035374726417 switched to configuration voters=()"} {"level":"info","ts":"2026-05-08T05:51:49.867058Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8cf6035374726417 became follower at term 0"} {"level":"info","ts":"2026-05-08T05:51:49.867079Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 8cf6035374726417 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-08T05:51:49.867097Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8cf6035374726417 became follower at term 1"} {"level":"info","ts":"2026-05-08T05:51:49.867156Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8cf6035374726417 switched to configuration voters=(10157309666551555095)"} {"level":"warn","ts":"2026-05-08T05:51:49.880028Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-08T05:51:49.881425Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-08T05:51:49.882331Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-08T05:51:49.88454Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"8cf6035374726417","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-08T05:51:49.885119Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-08T05:51:49.885197Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-08T05:51:49.885208Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-08T05:51:49.885065Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"8cf6035374726417","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-08T05:51:49.886217Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8cf6035374726417 switched to configuration voters=(10157309666551555095)"} {"level":"info","ts":"2026-05-08T05:51:49.887297Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"e837e6e114b087e2","local-member-id":"8cf6035374726417","added-peer-id":"8cf6035374726417","added-peer-peer-urls":["https://199.19.213.238:2380"]} {"level":"info","ts":"2026-05-08T05:51:49.890665Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-08T05:51:49.891029Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"8cf6035374726417","initial-advertise-peer-urls":["https://199.19.213.238:2380"],"listen-peer-urls":["https://199.19.213.238:2380"],"advertise-client-urls":["https://199.19.213.238:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.238:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-08T05:51:49.891072Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-08T05:51:49.891966Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.238:2380"} {"level":"info","ts":"2026-05-08T05:51:49.892028Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.238:2380"} {"level":"info","ts":"2026-05-08T05:51:50.468518Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8cf6035374726417 is starting a new election at term 1"} {"level":"info","ts":"2026-05-08T05:51:50.4686Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8cf6035374726417 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-08T05:51:50.46862Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8cf6035374726417 received MsgPreVoteResp from 8cf6035374726417 at term 1"} {"level":"info","ts":"2026-05-08T05:51:50.468651Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8cf6035374726417 became candidate at term 2"} {"level":"info","ts":"2026-05-08T05:51:50.468657Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8cf6035374726417 received MsgVoteResp from 8cf6035374726417 at term 2"} {"level":"info","ts":"2026-05-08T05:51:50.468667Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8cf6035374726417 became leader at term 2"} {"level":"info","ts":"2026-05-08T05:51:50.468678Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 8cf6035374726417 elected leader 8cf6035374726417 at term 2"} {"level":"info","ts":"2026-05-08T05:51:50.471707Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-08T05:51:50.473457Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-08T05:51:50.473471Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"8cf6035374726417","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.238:2379]}","request-path":"/0/members/8cf6035374726417/attributes","cluster-id":"e837e6e114b087e2","publish-timeout":"7s"} {"level":"info","ts":"2026-05-08T05:51:50.47365Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-08T05:51:50.474654Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-08T05:51:50.474675Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-08T05:51:50.474791Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"e837e6e114b087e2","local-member-id":"8cf6035374726417","cluster-version":"3.5"} {"level":"info","ts":"2026-05-08T05:51:50.474923Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-08T05:51:50.474948Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-08T05:51:50.476592Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-08T05:51:50.47669Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.238:2379"} {"level":"info","ts":"2026-05-08T05:51:56.247151Z","caller":"traceutil/trace.go:171","msg":"trace[540016622] linearizableReadLoop","detail":"{readStateIndex:306; appliedIndex:305; }","duration":"106.633944ms","start":"2026-05-08T05:51:56.140489Z","end":"2026-05-08T05:51:56.247123Z","steps":["trace[540016622] 'read index received' (duration: 99.999734ms)","trace[540016622] 'applied index is now lower than readState.Index' (duration: 6.63311ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T05:51:56.247242Z","caller":"traceutil/trace.go:171","msg":"trace[556049012] transaction","detail":"{read_only:false; response_revision:299; number_of_response:1; }","duration":"130.942166ms","start":"2026-05-08T05:51:56.116267Z","end":"2026-05-08T05:51:56.247209Z","steps":["trace[556049012] 'process raft request' (duration: 124.210625ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:51:56.247454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.934189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:2920"} {"level":"info","ts":"2026-05-08T05:51:56.247542Z","caller":"traceutil/trace.go:171","msg":"trace[1842305427] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:299; }","duration":"107.076232ms","start":"2026-05-08T05:51:56.140452Z","end":"2026-05-08T05:51:56.247529Z","steps":["trace[1842305427] 'agreement among raft nodes before linearized reading' (duration: 106.867538ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T05:51:56.28188Z","caller":"traceutil/trace.go:171","msg":"trace[2014870034] transaction","detail":"{read_only:false; number_of_response:0; response_revision:299; }","duration":"140.043237ms","start":"2026-05-08T05:51:56.141811Z","end":"2026-05-08T05:51:56.281854Z","steps":["trace[2014870034] 'process raft request' (duration: 139.863784ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:51:56.281977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.167923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T05:51:56.282062Z","caller":"traceutil/trace.go:171","msg":"trace[1369730681] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:299; }","duration":"133.304166ms","start":"2026-05-08T05:51:56.148736Z","end":"2026-05-08T05:51:56.28204Z","steps":["trace[1369730681] 'agreement among raft nodes before linearized reading' (duration: 133.112512ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T05:51:58.327082Z","caller":"traceutil/trace.go:171","msg":"trace[1605290214] transaction","detail":"{read_only:false; response_revision:326; number_of_response:1; }","duration":"197.972271ms","start":"2026-05-08T05:51:58.129084Z","end":"2026-05-08T05:51:58.327056Z","steps":["trace[1605290214] 'process raft request' (duration: 197.807448ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T05:51:58.482503Z","caller":"traceutil/trace.go:171","msg":"trace[1519630059] transaction","detail":"{read_only:false; response_revision:327; number_of_response:1; }","duration":"112.201057ms","start":"2026-05-08T05:51:58.370274Z","end":"2026-05-08T05:51:58.482475Z","steps":["trace[1519630059] 'process raft request' (duration: 103.741968ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T05:51:58.565063Z","caller":"traceutil/trace.go:171","msg":"trace[941654533] transaction","detail":"{read_only:false; response_revision:328; number_of_response:1; }","duration":"186.093196ms","start":"2026-05-08T05:51:58.378948Z","end":"2026-05-08T05:51:58.565042Z","steps":["trace[941654533] 'process raft request' (duration: 185.934934ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T05:51:58.684493Z","caller":"traceutil/trace.go:171","msg":"trace[275532936] linearizableReadLoop","detail":"{readStateIndex:338; appliedIndex:336; }","duration":"113.410656ms","start":"2026-05-08T05:51:58.571059Z","end":"2026-05-08T05:51:58.684469Z","steps":["trace[275532936] 'read index received' (duration: 9.323632ms)","trace[275532936] 'applied index is now lower than readState.Index' (duration: 104.086304ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T05:51:58.684587Z","caller":"traceutil/trace.go:171","msg":"trace[489620390] transaction","detail":"{read_only:false; response_revision:330; number_of_response:1; }","duration":"115.280627ms","start":"2026-05-08T05:51:58.569285Z","end":"2026-05-08T05:51:58.684566Z","steps":["trace[489620390] 'process raft request' (duration: 36.137541ms)","trace[489620390] 'compare' (duration: 78.942632ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T05:51:58.684617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.535538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-05-08T05:51:58.684658Z","caller":"traceutil/trace.go:171","msg":"trace[1976764012] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:330; }","duration":"113.593319ms","start":"2026-05-08T05:51:58.571054Z","end":"2026-05-08T05:51:58.684647Z","steps":["trace[1976764012] 'agreement among raft nodes before linearized reading' (duration: 113.509368ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:52:00.350885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.312711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"info","ts":"2026-05-08T05:52:00.350983Z","caller":"traceutil/trace.go:171","msg":"trace[1920527883] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:339; }","duration":"222.479364ms","start":"2026-05-08T05:52:00.128481Z","end":"2026-05-08T05:52:00.350961Z","steps":["trace[1920527883] 'range keys from in-memory index tree' (duration: 222.149178ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:52:00.350982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.798323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"warn","ts":"2026-05-08T05:52:00.351142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.916977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-05-08T05:52:00.351094Z","caller":"traceutil/trace.go:171","msg":"trace[1622458250] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:339; }","duration":"321.971876ms","start":"2026-05-08T05:52:00.029092Z","end":"2026-05-08T05:52:00.351064Z","steps":["trace[1622458250] 'range keys from in-memory index tree' (duration: 321.668601ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T05:52:00.351211Z","caller":"traceutil/trace.go:171","msg":"trace[1865319785] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:339; }","duration":"372.039189ms","start":"2026-05-08T05:51:59.979157Z","end":"2026-05-08T05:52:00.351196Z","steps":["trace[1865319785] 'range keys from in-memory index tree' (duration: 371.772235ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:52:00.351255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.863403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-05-08T05:52:00.351299Z","caller":"traceutil/trace.go:171","msg":"trace[1696970560] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:339; }","duration":"271.954835ms","start":"2026-05-08T05:52:00.079335Z","end":"2026-05-08T05:52:00.35129Z","steps":["trace[1696970560] 'range keys from in-memory index tree' (duration: 271.729911ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:52:00.351254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T05:51:59.979128Z","time spent":"372.11291ms","remote":"127.0.0.1:57264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":227,"request content":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" "} {"level":"warn","ts":"2026-05-08T05:52:00.351307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.304691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-05-08T05:52:00.351361Z","caller":"traceutil/trace.go:171","msg":"trace[1491483127] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:339; }","duration":"122.405023ms","start":"2026-05-08T05:52:00.228942Z","end":"2026-05-08T05:52:00.351347Z","steps":["trace[1491483127] 'range keys from in-memory index tree' (duration: 122.178379ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:52:00.351249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.23629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"warn","ts":"2026-05-08T05:52:00.351448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T05:52:00.029056Z","time spent":"322.153359ms","remote":"127.0.0.1:57264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":233,"request content":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" "} {"level":"info","ts":"2026-05-08T05:52:00.351482Z","caller":"traceutil/trace.go:171","msg":"trace[1644336153] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:339; }","duration":"172.616346ms","start":"2026-05-08T05:52:00.178854Z","end":"2026-05-08T05:52:00.351471Z","steps":["trace[1644336153] 'range keys from in-memory index tree' (duration: 172.134038ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T05:52:00.535131Z","caller":"traceutil/trace.go:171","msg":"trace[948108555] linearizableReadLoop","detail":"{readStateIndex:349; appliedIndex:347; }","duration":"156.214081ms","start":"2026-05-08T05:52:00.378895Z","end":"2026-05-08T05:52:00.535109Z","steps":["trace[948108555] 'read index received' (duration: 77.033778ms)","trace[948108555] 'applied index is now lower than readState.Index' (duration: 79.179513ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T05:52:00.535235Z","caller":"traceutil/trace.go:171","msg":"trace[1942148626] transaction","detail":"{read_only:false; response_revision:341; number_of_response:1; }","duration":"173.721605ms","start":"2026-05-08T05:52:00.361486Z","end":"2026-05-08T05:52:00.535207Z","steps":["trace[1942148626] 'process raft request' (duration: 173.46492ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:52:00.535332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.860005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:1 size:197"} {"level":"warn","ts":"2026-05-08T05:52:00.535388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.467045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"info","ts":"2026-05-08T05:52:00.53547Z","caller":"traceutil/trace.go:171","msg":"trace[1971540707] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:341; }","duration":"156.660427ms","start":"2026-05-08T05:52:00.378788Z","end":"2026-05-08T05:52:00.535448Z","steps":["trace[1971540707] 'agreement among raft nodes before linearized reading' (duration: 156.437784ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T05:52:00.535378Z","caller":"traceutil/trace.go:171","msg":"trace[1125834001] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:341; }","duration":"105.969806ms","start":"2026-05-08T05:52:00.429399Z","end":"2026-05-08T05:52:00.535369Z","steps":["trace[1125834001] 'agreement among raft nodes before linearized reading' (duration: 105.842524ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:52:01.035825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.365923ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-08T05:52:01.036323Z","caller":"traceutil/trace.go:171","msg":"trace[794880343] transaction","detail":"{read_only:false; response_revision:366; number_of_response:1; }","duration":"199.453399ms","start":"2026-05-08T05:52:00.836846Z","end":"2026-05-08T05:52:01.036299Z","steps":["trace[794880343] 'process raft request' (duration: 199.253776ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:52:01.036585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.58981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-05-08T05:52:01.036244Z","caller":"traceutil/trace.go:171","msg":"trace[399320756] transaction","detail":"{read_only:false; response_revision:365; number_of_response:1; }","duration":"204.144575ms","start":"2026-05-08T05:52:00.831993Z","end":"2026-05-08T05:52:01.036137Z","steps":["trace[399320756] 'process raft request' (duration: 60.720344ms)","trace[399320756] 'compare' (duration: 142.16885ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T05:52:01.036689Z","caller":"traceutil/trace.go:171","msg":"trace[1639347500] transaction","detail":"{read_only:false; response_revision:368; number_of_response:1; }","duration":"199.634192ms","start":"2026-05-08T05:52:00.837018Z","end":"2026-05-08T05:52:01.036652Z","steps":["trace[1639347500] 'process raft request' (duration: 199.264936ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T05:52:01.036353Z","caller":"traceutil/trace.go:171","msg":"trace[1187455385] linearizableReadLoop","detail":"{readStateIndex:376; appliedIndex:372; }","duration":"157.340907ms","start":"2026-05-08T05:52:00.878992Z","end":"2026-05-08T05:52:01.036333Z","steps":["trace[1187455385] 'read index received' (duration: 13.605891ms)","trace[1187455385] 'applied index is now lower than readState.Index' (duration: 143.733886ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T05:52:01.036582Z","caller":"traceutil/trace.go:171","msg":"trace[1834923909] transaction","detail":"{read_only:false; response_revision:367; number_of_response:1; }","duration":"199.625831ms","start":"2026-05-08T05:52:00.836912Z","end":"2026-05-08T05:52:01.036538Z","steps":["trace[1834923909] 'process raft request' (duration: 199.302646ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T05:52:01.036927Z","caller":"traceutil/trace.go:171","msg":"trace[983066988] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:368; }","duration":"157.782804ms","start":"2026-05-08T05:52:00.878943Z","end":"2026-05-08T05:52:01.036726Z","steps":["trace[983066988] 'agreement among raft nodes before linearized reading' (duration: 157.447909ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:52:01.037024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.567214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"info","ts":"2026-05-08T05:52:01.037936Z","caller":"traceutil/trace.go:171","msg":"trace[727616577] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:368; }","duration":"107.51711ms","start":"2026-05-08T05:52:00.930367Z","end":"2026-05-08T05:52:01.037884Z","steps":["trace[727616577] 'agreement among raft nodes before linearized reading' (duration: 106.554914ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:52:01.480449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.296871ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-08T05:52:01.480626Z","caller":"traceutil/trace.go:171","msg":"trace[2103322398] transaction","detail":"{read_only:false; response_revision:385; number_of_response:1; }","duration":"196.185851ms","start":"2026-05-08T05:52:01.284361Z","end":"2026-05-08T05:52:01.480547Z","steps":["trace[2103322398] 'process raft request' (duration: 32.700297ms)","trace[2103322398] 'compare' (duration: 163.093588ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T05:52:01.519041Z","caller":"traceutil/trace.go:171","msg":"trace[2045105449] transaction","detail":"{read_only:false; response_revision:388; number_of_response:1; }","duration":"232.471116ms","start":"2026-05-08T05:52:01.286543Z","end":"2026-05-08T05:52:01.519014Z","steps":["trace[2045105449] 'process raft request' (duration: 232.418675ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T05:52:01.519207Z","caller":"traceutil/trace.go:171","msg":"trace[1671215988] transaction","detail":"{read_only:false; response_revision:386; number_of_response:1; }","duration":"234.344947ms","start":"2026-05-08T05:52:01.284838Z","end":"2026-05-08T05:52:01.519183Z","steps":["trace[1671215988] 'process raft request' (duration: 233.90473ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T05:52:01.519173Z","caller":"traceutil/trace.go:171","msg":"trace[3563189] transaction","detail":"{read_only:false; response_revision:387; number_of_response:1; }","duration":"232.758061ms","start":"2026-05-08T05:52:01.286365Z","end":"2026-05-08T05:52:01.519123Z","steps":["trace[3563189] 'process raft request' (duration: 232.529667ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T05:52:02.063915Z","caller":"traceutil/trace.go:171","msg":"trace[1239093799] linearizableReadLoop","detail":"{readStateIndex:398; appliedIndex:397; }","duration":"279.211264ms","start":"2026-05-08T05:52:01.78468Z","end":"2026-05-08T05:52:02.063891Z","steps":["trace[1239093799] 'read index received' (duration: 267.750461ms)","trace[1239093799] 'applied index is now lower than readState.Index' (duration: 11.459663ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T05:52:02.064095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.394957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-08T05:52:02.06415Z","caller":"traceutil/trace.go:171","msg":"trace[2078171337] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:390; }","duration":"279.515989ms","start":"2026-05-08T05:52:01.784619Z","end":"2026-05-08T05:52:02.064135Z","steps":["trace[2078171337] 'agreement among raft nodes before linearized reading' (duration: 279.399128ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T05:52:02.064144Z","caller":"traceutil/trace.go:171","msg":"trace[1581920999] transaction","detail":"{read_only:false; response_revision:390; number_of_response:1; }","duration":"498.478528ms","start":"2026-05-08T05:52:01.565635Z","end":"2026-05-08T05:52:02.064113Z","steps":["trace[1581920999] 'process raft request' (duration: 486.935643ms)","trace[1581920999] 'compare' (duration: 11.156899ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T05:52:02.064596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T05:52:01.565612Z","time spent":"498.59154ms","remote":"127.0.0.1:57494","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4041,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T05:52:02.815772Z","caller":"traceutil/trace.go:171","msg":"trace[191789258] transaction","detail":"{read_only:false; response_revision:395; number_of_response:1; }","duration":"150.683416ms","start":"2026-05-08T05:52:02.665064Z","end":"2026-05-08T05:52:02.815748Z","steps":["trace[191789258] 'process raft request' (duration: 109.15197ms)","trace[191789258] 'compare' (duration: 40.957657ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T05:52:03.428943Z","caller":"traceutil/trace.go:171","msg":"trace[1031522380] transaction","detail":"{read_only:false; response_revision:397; number_of_response:1; }","duration":"222.989397ms","start":"2026-05-08T05:52:03.205918Z","end":"2026-05-08T05:52:03.428907Z","steps":["trace[1031522380] 'process raft request' (duration: 222.779214ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T05:52:06.19901Z","caller":"traceutil/trace.go:171","msg":"trace[1811810774] transaction","detail":"{read_only:false; response_revision:410; number_of_response:1; }","duration":"121.701753ms","start":"2026-05-08T05:52:06.077265Z","end":"2026-05-08T05:52:06.198967Z","steps":["trace[1811810774] 'process raft request' (duration: 121.460049ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:52:06.41297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.562257ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-08T05:52:06.413117Z","caller":"traceutil/trace.go:171","msg":"trace[60733476] transaction","detail":"{read_only:false; response_revision:411; number_of_response:1; }","duration":"209.001685ms","start":"2026-05-08T05:52:06.20409Z","end":"2026-05-08T05:52:06.413092Z","steps":["trace[60733476] 'process raft request' (duration: 58.181794ms)","trace[60733476] 'compare' (duration: 150.346674ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T05:52:06.418675Z","caller":"traceutil/trace.go:171","msg":"trace[1110060476] transaction","detail":"{read_only:false; response_revision:412; number_of_response:1; }","duration":"207.640034ms","start":"2026-05-08T05:52:06.211015Z","end":"2026-05-08T05:52:06.418655Z","steps":["trace[1110060476] 'process raft request' (duration: 207.541022ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T05:52:07.044694Z","caller":"traceutil/trace.go:171","msg":"trace[553369419] transaction","detail":"{read_only:false; response_revision:416; number_of_response:1; }","duration":"159.985962ms","start":"2026-05-08T05:52:06.884668Z","end":"2026-05-08T05:52:07.044654Z","steps":["trace[553369419] 'process raft request' (duration: 120.483864ms)","trace[553369419] 'compare' (duration: 39.266964ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T05:52:07.364959Z","caller":"traceutil/trace.go:171","msg":"trace[537247102] transaction","detail":"{read_only:false; response_revision:418; number_of_response:1; }","duration":"259.204155ms","start":"2026-05-08T05:52:07.10572Z","end":"2026-05-08T05:52:07.364924Z","steps":["trace[537247102] 'process raft request' (duration: 258.957311ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T05:52:12.184612Z","caller":"traceutil/trace.go:171","msg":"trace[1649167555] transaction","detail":"{read_only:false; response_revision:428; number_of_response:1; }","duration":"181.008396ms","start":"2026-05-08T05:52:12.003579Z","end":"2026-05-08T05:52:12.184588Z","steps":["trace[1649167555] 'process raft request' (duration: 180.876034ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T05:52:12.372372Z","caller":"traceutil/trace.go:171","msg":"trace[594803275] transaction","detail":"{read_only:false; response_revision:429; number_of_response:1; }","duration":"180.475568ms","start":"2026-05-08T05:52:12.191865Z","end":"2026-05-08T05:52:12.372341Z","steps":["trace[594803275] 'process raft request' (duration: 168.911211ms)","trace[594803275] 'compare' (duration: 11.341104ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T05:52:22.710806Z","caller":"traceutil/trace.go:171","msg":"trace[728056549] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"197.331279ms","start":"2026-05-08T05:52:22.513455Z","end":"2026-05-08T05:52:22.710787Z","steps":["trace[728056549] 'process raft request' (duration: 197.227698ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:52:23.326282Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7212407077474955035,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-08T05:52:24.191527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"821.784292ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-08T05:52:24.19171Z","caller":"traceutil/trace.go:171","msg":"trace[1804916912] linearizableReadLoop","detail":"{readStateIndex:495; appliedIndex:494; }","duration":"1.365469151s","start":"2026-05-08T05:52:22.826212Z","end":"2026-05-08T05:52:24.191681Z","steps":["trace[1804916912] 'read index received' (duration: 542.442931ms)","trace[1804916912] 'applied index is now lower than readState.Index' (duration: 823.02387ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T05:52:24.191902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.208031595s","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-05-08T05:52:24.191981Z","caller":"traceutil/trace.go:171","msg":"trace[1276693372] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:481; }","duration":"1.208096527s","start":"2026-05-08T05:52:22.983833Z","end":"2026-05-08T05:52:24.19193Z","steps":["trace[1276693372] 'agreement among raft nodes before linearized reading' (duration: 1.207978745s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:52:24.191975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"776.430551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.238\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-05-08T05:52:24.192031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T05:52:22.983818Z","time spent":"1.208203418s","remote":"127.0.0.1:57324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-08T05:52:24.192087Z","caller":"traceutil/trace.go:171","msg":"trace[1554177657] range","detail":"{range_begin:/registry/masterleases/199.19.213.238; range_end:; response_count:1; response_revision:481; }","duration":"776.628104ms","start":"2026-05-08T05:52:23.415432Z","end":"2026-05-08T05:52:24.19206Z","steps":["trace[1554177657] 'agreement among raft nodes before linearized reading' (duration: 776.405711ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T05:52:24.191898Z","caller":"traceutil/trace.go:171","msg":"trace[1400475682] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"1.372730127s","start":"2026-05-08T05:52:22.819128Z","end":"2026-05-08T05:52:24.191858Z","steps":["trace[1400475682] 'process raft request' (duration: 549.537934ms)","trace[1400475682] 'compare' (duration: 821.269045ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T05:52:24.191991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.204513775s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-05-08T05:52:24.192162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T05:52:23.415412Z","time spent":"776.734055ms","remote":"127.0.0.1:57084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/199.19.213.238\" "} {"level":"info","ts":"2026-05-08T05:52:24.192177Z","caller":"traceutil/trace.go:171","msg":"trace[416463317] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:481; }","duration":"1.204737898s","start":"2026-05-08T05:52:22.98742Z","end":"2026-05-08T05:52:24.192158Z","steps":["trace[416463317] 'agreement among raft nodes before linearized reading' (duration: 1.204480845s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:52:24.192229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T05:52:22.987394Z","time spent":"1.204822609s","remote":"127.0.0.1:57324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-08T05:52:24.192009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.054685829s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-05-08T05:52:24.192234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.365974739s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:3 size:12673"} {"level":"info","ts":"2026-05-08T05:52:24.192307Z","caller":"traceutil/trace.go:171","msg":"trace[1642931048] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:481; }","duration":"1.055028963s","start":"2026-05-08T05:52:23.137256Z","end":"2026-05-08T05:52:24.192285Z","steps":["trace[1642931048] 'agreement among raft nodes before linearized reading' (duration: 1.054647318s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:52:24.192373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T05:52:23.13724Z","time spent":"1.055114585s","remote":"127.0.0.1:57324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-08T05:52:24.192387Z","caller":"traceutil/trace.go:171","msg":"trace[2105318096] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:3; response_revision:481; }","duration":"1.366175711s","start":"2026-05-08T05:52:22.826161Z","end":"2026-05-08T05:52:24.192336Z","steps":["trace[2105318096] 'agreement among raft nodes before linearized reading' (duration: 1.365638343s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:52:24.192487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T05:52:22.82614Z","time spent":"1.366332184s","remote":"127.0.0.1:57142","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":3,"response size":12697,"request content":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" "} {"level":"warn","ts":"2026-05-08T05:52:24.192414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T05:52:22.819104Z","time spent":"1.373140314s","remote":"127.0.0.1:57324","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T05:52:24.968704Z","caller":"traceutil/trace.go:171","msg":"trace[383801936] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"250.305666ms","start":"2026-05-08T05:52:24.718367Z","end":"2026-05-08T05:52:24.968672Z","steps":["trace[383801936] 'process raft request' (duration: 248.200986ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T05:52:27.509147Z","caller":"traceutil/trace.go:171","msg":"trace[1935190664] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"125.214748ms","start":"2026-05-08T05:52:27.383891Z","end":"2026-05-08T05:52:27.509105Z","steps":["trace[1935190664] 'process raft request' (duration: 125.129397ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T05:52:27.509722Z","caller":"traceutil/trace.go:171","msg":"trace[1070505933] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"134.214877ms","start":"2026-05-08T05:52:27.375496Z","end":"2026-05-08T05:52:27.509711Z","steps":["trace[1070505933] 'process raft request' (duration: 127.932166ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T05:52:28.420517Z","caller":"traceutil/trace.go:171","msg":"trace[1221451002] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"580.691932ms","start":"2026-05-08T05:52:27.839804Z","end":"2026-05-08T05:52:28.420496Z","steps":["trace[1221451002] 'process raft request' (duration: 580.357287ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:52:28.420652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T05:52:27.839779Z","time spent":"580.804074ms","remote":"127.0.0.1:57108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":570081,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T05:52:28.464128Z","caller":"traceutil/trace.go:171","msg":"trace[1906894687] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"562.288846ms","start":"2026-05-08T05:52:27.901813Z","end":"2026-05-08T05:52:28.464102Z","steps":["trace[1906894687] 'process raft request' (duration: 562.033932ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:52:28.46426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T05:52:27.901779Z","time spent":"562.423638ms","remote":"127.0.0.1:57108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9343,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-08T05:52:28.464364Z","caller":"traceutil/trace.go:171","msg":"trace[1631708992] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"576.205275ms","start":"2026-05-08T05:52:27.88815Z","end":"2026-05-08T05:52:28.464355Z","steps":["trace[1631708992] 'process raft request' (duration: 565.906188ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:52:28.464401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T05:52:27.888108Z","time spent":"576.276857ms","remote":"127.0.0.1:57108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":207462,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-08T05:52:28.464411Z","caller":"traceutil/trace.go:171","msg":"trace[648388866] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"364.403972ms","start":"2026-05-08T05:52:28.099991Z","end":"2026-05-08T05:52:28.464395Z","steps":["trace[648388866] 'process raft request' (duration: 363.881624ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T05:52:28.464462Z","caller":"traceutil/trace.go:171","msg":"trace[70657543] linearizableReadLoop","detail":"{readStateIndex:641; appliedIndex:637; }","duration":"224.882547ms","start":"2026-05-08T05:52:28.239571Z","end":"2026-05-08T05:52:28.464454Z","steps":["trace[70657543] 'read index received' (duration: 180.547853ms)","trace[70657543] 'applied index is now lower than readState.Index' (duration: 44.333004ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T05:52:28.464473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T05:52:28.099972Z","time spent":"364.472813ms","remote":"127.0.0.1:57108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3353,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-08T05:52:28.477646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.783041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T05:52:28.477727Z","caller":"traceutil/trace.go:171","msg":"trace[1490052375] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:0; response_revision:621; }","duration":"179.918143ms","start":"2026-05-08T05:52:28.297789Z","end":"2026-05-08T05:52:28.477707Z","steps":["trace[1490052375] 'agreement among raft nodes before linearized reading' (duration: 179.783261ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:52:28.477857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.253058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-05-08T05:52:28.477931Z","caller":"traceutil/trace.go:171","msg":"trace[1309148363] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:621; }","duration":"238.372439ms","start":"2026-05-08T05:52:28.239522Z","end":"2026-05-08T05:52:28.477894Z","steps":["trace[1309148363] 'agreement among raft nodes before linearized reading' (duration: 224.997688ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:52:28.478247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.92433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-08T05:52:28.478296Z","caller":"traceutil/trace.go:171","msg":"trace[752993650] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:621; }","duration":"206.975531ms","start":"2026-05-08T05:52:28.271309Z","end":"2026-05-08T05:52:28.478285Z","steps":["trace[752993650] 'agreement among raft nodes before linearized reading' (duration: 206.845739ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:52:28.478447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.505013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T05:52:28.47848Z","caller":"traceutil/trace.go:171","msg":"trace[308408112] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:621; }","duration":"192.575634ms","start":"2026-05-08T05:52:28.285895Z","end":"2026-05-08T05:52:28.47847Z","steps":["trace[308408112] 'agreement among raft nodes before linearized reading' (duration: 192.529294ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:52:28.478948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.778513ms","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-05-08T05:52:28.478993Z","caller":"traceutil/trace.go:171","msg":"trace[2121655557] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:621; }","duration":"212.863755ms","start":"2026-05-08T05:52:28.266119Z","end":"2026-05-08T05:52:28.478983Z","steps":["trace[2121655557] 'agreement among raft nodes before linearized reading' (duration: 211.14805ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:52:35.125003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"598.601709ms","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-05-08T05:52:35.125089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"600.85663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-08T05:52:35.125115Z","caller":"traceutil/trace.go:171","msg":"trace[887494940] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:688; }","duration":"598.776692ms","start":"2026-05-08T05:52:34.526316Z","end":"2026-05-08T05:52:35.125093Z","steps":["trace[887494940] 'range keys from in-memory index tree' (duration: 598.447257ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:52:35.125168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T05:52:34.526298Z","time spent":"598.861253ms","remote":"127.0.0.1:57324","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-05-08T05:52:35.125172Z","caller":"traceutil/trace.go:171","msg":"trace[16328062] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:688; }","duration":"600.978062ms","start":"2026-05-08T05:52:34.524171Z","end":"2026-05-08T05:52:35.125149Z","steps":["trace[16328062] 'range keys from in-memory index tree' (duration: 600.487635ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:52:35.125214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T05:52:34.524148Z","time spent":"601.057263ms","remote":"127.0.0.1:57324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-08T05:52:35.124999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"598.243534ms","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-05-08T05:52:35.127139Z","caller":"traceutil/trace.go:171","msg":"trace[60805906] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:688; }","duration":"600.407174ms","start":"2026-05-08T05:52:34.526711Z","end":"2026-05-08T05:52:35.127118Z","steps":["trace[60805906] 'range keys from in-memory index tree' (duration: 598.047431ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:52:35.127306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T05:52:34.526661Z","time spent":"600.632157ms","remote":"127.0.0.1:57324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-08T05:52:35.413531Z","caller":"traceutil/trace.go:171","msg":"trace[653157893] transaction","detail":"{read_only:false; response_revision:689; number_of_response:1; }","duration":"283.785608ms","start":"2026-05-08T05:52:35.129717Z","end":"2026-05-08T05:52:35.413503Z","steps":["trace[653157893] 'process raft request' (duration: 283.639026ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T05:52:35.61961Z","caller":"traceutil/trace.go:171","msg":"trace[1040915607] transaction","detail":"{read_only:false; response_revision:690; number_of_response:1; }","duration":"489.861551ms","start":"2026-05-08T05:52:35.129718Z","end":"2026-05-08T05:52:35.619579Z","steps":["trace[1040915607] 'process raft request' (duration: 489.610058ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T05:52:35.619733Z","caller":"traceutil/trace.go:171","msg":"trace[1956128627] linearizableReadLoop","detail":"{readStateIndex:714; appliedIndex:710; }","duration":"469.918573ms","start":"2026-05-08T05:52:35.149788Z","end":"2026-05-08T05:52:35.619707Z","steps":["trace[1956128627] 'read index received' (duration: 263.562736ms)","trace[1956128627] 'applied index is now lower than readState.Index' (duration: 206.353947ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T05:52:35.619778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T05:52:35.129689Z","time spent":"489.995043ms","remote":"127.0.0.1:57324","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T05:52:35.619611Z","caller":"traceutil/trace.go:171","msg":"trace[402086931] transaction","detail":"{read_only:false; response_revision:691; number_of_response:1; }","duration":"489.344994ms","start":"2026-05-08T05:52:35.130236Z","end":"2026-05-08T05:52:35.619581Z","steps":["trace[402086931] 'process raft request' (duration: 489.243043ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:52:35.619963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.977277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T05:52:35.619894Z","caller":"traceutil/trace.go:171","msg":"trace[886615364] transaction","detail":"{read_only:false; response_revision:692; number_of_response:1; }","duration":"489.259002ms","start":"2026-05-08T05:52:35.130609Z","end":"2026-05-08T05:52:35.619868Z","steps":["trace[886615364] 'process raft request' (duration: 488.935178ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T05:52:35.620005Z","caller":"traceutil/trace.go:171","msg":"trace[754688103] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:692; }","duration":"334.072179ms","start":"2026-05-08T05:52:35.285919Z","end":"2026-05-08T05:52:35.619991Z","steps":["trace[754688103] 'agreement among raft nodes before linearized reading' (duration: 333.983528ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:52:35.620034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T05:52:35.285897Z","time spent":"334.13174ms","remote":"127.0.0.1:57060","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-05-08T05:52:35.62003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"470.225557ms","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-05-08T05:52:35.620083Z","caller":"traceutil/trace.go:171","msg":"trace[43103569] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:692; }","duration":"470.338169ms","start":"2026-05-08T05:52:35.149734Z","end":"2026-05-08T05:52:35.620072Z","steps":["trace[43103569] 'agreement among raft nodes before linearized reading' (duration: 470.107356ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:52:35.620117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T05:52:35.149714Z","time spent":"470.39477ms","remote":"127.0.0.1:57324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-08T05:52:35.620147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T05:52:35.130597Z","time spent":"489.414175ms","remote":"127.0.0.1:57324","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T05:52:35.620228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.88116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-w45s2\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-05-08T05:52:35.620292Z","caller":"traceutil/trace.go:171","msg":"trace[1324547974] range","detail":"{range_begin:/registry/pods/kube-system/cilium-w45s2; range_end:; response_count:1; response_revision:692; }","duration":"306.987911ms","start":"2026-05-08T05:52:35.313287Z","end":"2026-05-08T05:52:35.620275Z","steps":["trace[1324547974] 'agreement among raft nodes before linearized reading' (duration: 306.791368ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:52:35.620331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T05:52:35.313259Z","time spent":"307.064122ms","remote":"127.0.0.1:57240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16989,"request content":"key:\"/registry/pods/kube-system/cilium-w45s2\" "} {"level":"warn","ts":"2026-05-08T05:52:35.620378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T05:52:35.130226Z","time spent":"489.941313ms","remote":"127.0.0.1:57324","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-05-08T05:52:35.74967Z","caller":"traceutil/trace.go:171","msg":"trace[1137888551] transaction","detail":"{read_only:false; response_revision:694; number_of_response:1; }","duration":"116.976922ms","start":"2026-05-08T05:52:35.632657Z","end":"2026-05-08T05:52:35.749633Z","steps":["trace[1137888551] 'process raft request' (duration: 101.475656ms)","trace[1137888551] 'compare' (duration: 15.286153ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T05:52:38.125484Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7212407077474955494,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-08T05:52:38.3331Z","caller":"traceutil/trace.go:171","msg":"trace[269339] linearizableReadLoop","detail":"{readStateIndex:718; appliedIndex:717; }","duration":"708.357275ms","start":"2026-05-08T05:52:37.624706Z","end":"2026-05-08T05:52:38.333063Z","steps":["trace[269339] 'read index received' (duration: 708.081971ms)","trace[269339] 'applied index is now lower than readState.Index' (duration: 273.924µs)"],"step_count":2} {"level":"info","ts":"2026-05-08T05:52:38.333213Z","caller":"traceutil/trace.go:171","msg":"trace[1310543301] transaction","detail":"{read_only:false; response_revision:696; number_of_response:1; }","duration":"911.931244ms","start":"2026-05-08T05:52:37.421248Z","end":"2026-05-08T05:52:38.333179Z","steps":["trace[1310543301] 'process raft request' (duration: 911.59231ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:52:38.333407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T05:52:37.421208Z","time spent":"912.083336ms","remote":"127.0.0.1:57324","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T05:52:38.333461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"708.68437ms","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-05-08T05:52:38.333489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"708.01081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-05-08T05:52:38.333516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"708.75722ms","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-05-08T05:52:38.333515Z","caller":"traceutil/trace.go:171","msg":"trace[763078500] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:696; }","duration":"708.824981ms","start":"2026-05-08T05:52:37.624676Z","end":"2026-05-08T05:52:38.333501Z","steps":["trace[763078500] 'agreement among raft nodes before linearized reading' (duration: 708.582858ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T05:52:38.333563Z","caller":"traceutil/trace.go:171","msg":"trace[1252021554] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:696; }","duration":"708.098221ms","start":"2026-05-08T05:52:37.625447Z","end":"2026-05-08T05:52:38.333545Z","steps":["trace[1252021554] 'agreement among raft nodes before linearized reading' (duration: 707.952799ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:52:38.33359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T05:52:37.624645Z","time spent":"708.935243ms","remote":"127.0.0.1:57324","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-05-08T05:52:38.333599Z","caller":"traceutil/trace.go:171","msg":"trace[1105612462] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:696; }","duration":"708.914663ms","start":"2026-05-08T05:52:37.624643Z","end":"2026-05-08T05:52:38.333558Z","steps":["trace[1105612462] 'agreement among raft nodes before linearized reading' (duration: 708.594248ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:52:38.333607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T05:52:37.625437Z","time spent":"708.162172ms","remote":"127.0.0.1:57324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-08T05:52:38.333642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T05:52:37.624614Z","time spent":"709.017554ms","remote":"127.0.0.1:57324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-08T05:52:38.333777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"684.134609ms","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-05-08T05:52:38.333903Z","caller":"traceutil/trace.go:171","msg":"trace[1880792069] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:696; }","duration":"684.330442ms","start":"2026-05-08T05:52:37.649553Z","end":"2026-05-08T05:52:38.333883Z","steps":["trace[1880792069] 'agreement among raft nodes before linearized reading' (duration: 683.844575ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:52:38.333961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T05:52:37.6495Z","time spent":"684.446544ms","remote":"127.0.0.1:57324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-08T05:52:42.717693Z","caller":"traceutil/trace.go:171","msg":"trace[805936617] linearizableReadLoop","detail":"{readStateIndex:743; appliedIndex:740; }","duration":"343.913936ms","start":"2026-05-08T05:52:42.373758Z","end":"2026-05-08T05:52:42.717672Z","steps":["trace[805936617] 'read index received' (duration: 52.438475ms)","trace[805936617] 'applied index is now lower than readState.Index' (duration: 291.474511ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T05:52:42.717929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.153949ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-08T05:52:42.717886Z","caller":"traceutil/trace.go:171","msg":"trace[1638666935] transaction","detail":"{read_only:false; response_revision:719; number_of_response:1; }","duration":"368.512401ms","start":"2026-05-08T05:52:42.349338Z","end":"2026-05-08T05:52:42.71785Z","steps":["trace[1638666935] 'process raft request' (duration: 365.856714ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T05:52:42.717963Z","caller":"traceutil/trace.go:171","msg":"trace[630734861] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:720; }","duration":"344.22714ms","start":"2026-05-08T05:52:42.373727Z","end":"2026-05-08T05:52:42.717954Z","steps":["trace[630734861] 'agreement among raft nodes before linearized reading' (duration: 344.084198ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:52:42.717999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T05:52:42.373707Z","time spent":"344.28387ms","remote":"127.0.0.1:57324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-08T05:52:42.71809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T05:52:42.348602Z","time spent":"369.378962ms","remote":"127.0.0.1:57132","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":771,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-08T05:52:42.718089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.265488ms","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-05-08T05:52:42.718174Z","caller":"traceutil/trace.go:171","msg":"trace[1522807766] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:720; }","duration":"335.39641ms","start":"2026-05-08T05:52:42.382763Z","end":"2026-05-08T05:52:42.71816Z","steps":["trace[1522807766] 'agreement among raft nodes before linearized reading' (duration: 335.166017ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:52:42.71825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T05:52:42.382742Z","time spent":"335.496481ms","remote":"127.0.0.1:57324","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":"warn","ts":"2026-05-08T05:52:42.718159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.591571ms","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-05-08T05:52:42.718462Z","caller":"traceutil/trace.go:171","msg":"trace[2026398903] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:720; }","duration":"340.910325ms","start":"2026-05-08T05:52:42.37753Z","end":"2026-05-08T05:52:42.71844Z","steps":["trace[2026398903] 'agreement among raft nodes before linearized reading' (duration: 340.359427ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T05:52:42.717827Z","caller":"traceutil/trace.go:171","msg":"trace[169597455] transaction","detail":"{read_only:false; response_revision:720; number_of_response:1; }","duration":"356.169053ms","start":"2026-05-08T05:52:42.361636Z","end":"2026-05-08T05:52:42.717805Z","steps":["trace[169597455] 'process raft request' (duration: 355.96764ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:52:42.718524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T05:52:42.377518Z","time spent":"340.988805ms","remote":"127.0.0.1:57324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-08T05:52:42.718674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T05:52:42.361619Z","time spent":"356.921413ms","remote":"127.0.0.1:57240","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18052,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T05:52:42.718717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.489181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-08T05:52:42.71878Z","caller":"traceutil/trace.go:171","msg":"trace[137669191] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:720; }","duration":"335.603322ms","start":"2026-05-08T05:52:42.383164Z","end":"2026-05-08T05:52:42.718767Z","steps":["trace[137669191] 'agreement among raft nodes before linearized reading' (duration: 335.350379ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:52:42.718821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T05:52:42.383144Z","time spent":"335.669124ms","remote":"127.0.0.1:57324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-08T05:52:42.956895Z","caller":"traceutil/trace.go:171","msg":"trace[973626296] transaction","detail":"{read_only:false; response_revision:723; number_of_response:1; }","duration":"233.570532ms","start":"2026-05-08T05:52:42.7233Z","end":"2026-05-08T05:52:42.956871Z","steps":["trace[973626296] 'process raft request' (duration: 233.485561ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T05:52:42.956941Z","caller":"traceutil/trace.go:171","msg":"trace[279820379] transaction","detail":"{read_only:false; response_revision:724; number_of_response:1; }","duration":"232.745721ms","start":"2026-05-08T05:52:42.724171Z","end":"2026-05-08T05:52:42.956916Z","steps":["trace[279820379] 'process raft request' (duration: 232.6577ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T05:52:42.956928Z","caller":"traceutil/trace.go:171","msg":"trace[672981071] transaction","detail":"{read_only:false; response_revision:722; number_of_response:1; }","duration":"233.595592ms","start":"2026-05-08T05:52:42.723294Z","end":"2026-05-08T05:52:42.956889Z","steps":["trace[672981071] 'process raft request' (duration: 136.606941ms)","trace[672981071] 'compare' (duration: 96.761948ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T05:52:54.996785Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7212407077474955707,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-08T05:52:55.497861Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":7212407077474955707,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-08T05:52:55.798913Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.377125774s","expected-duration":"1s"} {"level":"info","ts":"2026-05-08T05:52:55.799362Z","caller":"traceutil/trace.go:171","msg":"trace[818419217] transaction","detail":"{read_only:false; response_revision:804; number_of_response:1; }","duration":"1.377643181s","start":"2026-05-08T05:52:54.421693Z","end":"2026-05-08T05:52:55.799336Z","steps":["trace[818419217] 'process raft request' (duration: 1.377483269s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:52:55.799506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T05:52:54.421682Z","time spent":"1.377736552s","remote":"127.0.0.1:57240","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4028,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T05:52:55.801907Z","caller":"traceutil/trace.go:171","msg":"trace[609222439] linearizableReadLoop","detail":"{readStateIndex:833; appliedIndex:831; }","duration":"1.306232372s","start":"2026-05-08T05:52:54.495653Z","end":"2026-05-08T05:52:55.801885Z","steps":["trace[609222439] 'read index received' (duration: 1.303485786s)","trace[609222439] 'applied index is now lower than readState.Index' (duration: 2.745546ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T05:52:55.802019Z","caller":"traceutil/trace.go:171","msg":"trace[972223613] transaction","detail":"{read_only:false; response_revision:805; number_of_response:1; }","duration":"1.368793434s","start":"2026-05-08T05:52:54.433214Z","end":"2026-05-08T05:52:55.802007Z","steps":["trace[972223613] 'process raft request' (duration: 1.368578561s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:52:55.80214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T05:52:54.433194Z","time spent":"1.368854665s","remote":"127.0.0.1:57324","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T05:52:55.802255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"792.337503ms","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-05-08T05:52:55.802341Z","caller":"traceutil/trace.go:171","msg":"trace[1823859892] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:805; }","duration":"792.442474ms","start":"2026-05-08T05:52:55.009881Z","end":"2026-05-08T05:52:55.802324Z","steps":["trace[1823859892] 'agreement among raft nodes before linearized reading' (duration: 792.253002ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:52:55.802394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T05:52:55.009875Z","time spent":"792.505795ms","remote":"127.0.0.1:57324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-08T05:52:55.802471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"795.93695ms","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-05-08T05:52:55.802515Z","caller":"traceutil/trace.go:171","msg":"trace[2115951806] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:805; }","duration":"796.020121ms","start":"2026-05-08T05:52:55.006485Z","end":"2026-05-08T05:52:55.802505Z","steps":["trace[2115951806] 'agreement among raft nodes before linearized reading' (duration: 795.925159ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:52:55.802546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T05:52:55.006464Z","time spent":"796.074102ms","remote":"127.0.0.1:57324","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":"warn","ts":"2026-05-08T05:52:55.802521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"985.705426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-05-08T05:52:55.802762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"798.22949ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-08T05:52:55.802824Z","caller":"traceutil/trace.go:171","msg":"trace[2127525162] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:805; }","duration":"798.314092ms","start":"2026-05-08T05:52:55.004496Z","end":"2026-05-08T05:52:55.80281Z","steps":["trace[2127525162] 'agreement among raft nodes before linearized reading' (duration: 798.19855ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:52:55.802867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T05:52:55.004481Z","time spent":"798.374412ms","remote":"127.0.0.1:57324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-08T05:52:55.802826Z","caller":"traceutil/trace.go:171","msg":"trace[1089028165] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:805; }","duration":"986.07536ms","start":"2026-05-08T05:52:54.816716Z","end":"2026-05-08T05:52:55.802791Z","steps":["trace[1089028165] 'agreement among raft nodes before linearized reading' (duration: 985.388351ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:52:55.802655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.307002182s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-08T05:52:55.802974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T05:52:54.816694Z","time spent":"986.245703ms","remote":"127.0.0.1:57324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-08T05:52:55.802979Z","caller":"traceutil/trace.go:171","msg":"trace[945775337] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:805; }","duration":"1.307347666s","start":"2026-05-08T05:52:54.495612Z","end":"2026-05-08T05:52:55.802959Z","steps":["trace[945775337] 'agreement among raft nodes before linearized reading' (duration: 1.306956541s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:52:55.803038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T05:52:54.495598Z","time spent":"1.307422026s","remote":"127.0.0.1:57324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-08T05:53:05.599135Z","caller":"traceutil/trace.go:171","msg":"trace[799614440] transaction","detail":"{read_only:false; response_revision:929; number_of_response:1; }","duration":"118.693129ms","start":"2026-05-08T05:53:05.480407Z","end":"2026-05-08T05:53:05.5991Z","steps":["trace[799614440] 'process raft request' (duration: 118.365564ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T05:53:12.704949Z","caller":"traceutil/trace.go:171","msg":"trace[75063124] transaction","detail":"{read_only:false; response_revision:998; number_of_response:1; }","duration":"120.641339ms","start":"2026-05-08T05:53:12.584286Z","end":"2026-05-08T05:53:12.704928Z","steps":["trace[75063124] 'process raft request' (duration: 120.488857ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:54:19.361002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.856553ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-05-08T05:54:19.36128Z","caller":"traceutil/trace.go:171","msg":"trace[183099649] linearizableReadLoop","detail":"{readStateIndex:1267; appliedIndex:1265; }","duration":"100.675075ms","start":"2026-05-08T05:54:19.260589Z","end":"2026-05-08T05:54:19.361264Z","steps":["trace[183099649] 'read index received' (duration: 37.04µs)","trace[183099649] 'applied index is now lower than readState.Index' (duration: 100.637185ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T05:54:19.361325Z","caller":"traceutil/trace.go:171","msg":"trace[1296295545] transaction","detail":"{read_only:false; response_revision:1222; number_of_response:1; }","duration":"258.105791ms","start":"2026-05-08T05:54:19.103186Z","end":"2026-05-08T05:54:19.361292Z","steps":["trace[1296295545] 'process raft request' (duration: 257.932269ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:54:19.361537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.943809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-08T05:54:19.361583Z","caller":"traceutil/trace.go:171","msg":"trace[1336697417] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:1222; }","duration":"101.00282ms","start":"2026-05-08T05:54:19.26057Z","end":"2026-05-08T05:54:19.361573Z","steps":["trace[1336697417] 'agreement among raft nodes before linearized reading' (duration: 100.765567ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:54:23.714409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.075071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T05:54:23.714469Z","caller":"traceutil/trace.go:171","msg":"trace[770363198] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:1234; }","duration":"118.186592ms","start":"2026-05-08T05:54:23.596269Z","end":"2026-05-08T05:54:23.714456Z","steps":["trace[770363198] 'agreement among raft nodes before linearized reading' (duration: 118.083361ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T05:54:23.714303Z","caller":"traceutil/trace.go:171","msg":"trace[972194657] linearizableReadLoop","detail":"{readStateIndex:1280; appliedIndex:1279; }","duration":"117.94745ms","start":"2026-05-08T05:54:23.596309Z","end":"2026-05-08T05:54:23.714257Z","steps":["trace[972194657] 'read index received' (duration: 117.733067ms)","trace[972194657] 'applied index is now lower than readState.Index' (duration: 213.153µs)"],"step_count":2} {"level":"warn","ts":"2026-05-08T05:54:23.886807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.212171ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-08T05:54:23.886909Z","caller":"traceutil/trace.go:171","msg":"trace[248964394] transaction","detail":"{read_only:false; response_revision:1235; number_of_response:1; }","duration":"171.591812ms","start":"2026-05-08T05:54:23.715298Z","end":"2026-05-08T05:54:23.88689Z","steps":["trace[248964394] 'process raft request' (duration: 54.21857ms)","trace[248964394] 'compare' (duration: 117.091429ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T05:54:24.569945Z","caller":"traceutil/trace.go:171","msg":"trace[875280556] transaction","detail":"{read_only:false; response_revision:1237; number_of_response:1; }","duration":"103.331851ms","start":"2026-05-08T05:54:24.466574Z","end":"2026-05-08T05:54:24.569905Z","steps":["trace[875280556] 'process raft request' (duration: 54.342891ms)","trace[875280556] 'compare' (duration: 48.662686ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T05:54:24.685589Z","caller":"traceutil/trace.go:171","msg":"trace[1502304855] transaction","detail":"{read_only:false; response_revision:1240; number_of_response:1; }","duration":"111.08967ms","start":"2026-05-08T05:54:24.57447Z","end":"2026-05-08T05:54:24.68556Z","steps":["trace[1502304855] 'process raft request' (duration: 111.018209ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T05:54:24.685633Z","caller":"traceutil/trace.go:171","msg":"trace[675772974] transaction","detail":"{read_only:false; response_revision:1239; number_of_response:1; }","duration":"111.238331ms","start":"2026-05-08T05:54:24.574364Z","end":"2026-05-08T05:54:24.685603Z","steps":["trace[675772974] 'process raft request' (duration: 97.943249ms)","trace[675772974] 'compare' (duration: 13.041919ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T05:54:29.034888Z","caller":"traceutil/trace.go:171","msg":"trace[886697726] transaction","detail":"{read_only:false; response_revision:1254; number_of_response:1; }","duration":"146.181456ms","start":"2026-05-08T05:54:28.888657Z","end":"2026-05-08T05:54:29.034838Z","steps":["trace[886697726] 'process raft request' (duration: 106.000278ms)","trace[886697726] 'compare' (duration: 39.779103ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T05:54:30.703733Z","caller":"traceutil/trace.go:171","msg":"trace[1386107334] transaction","detail":"{read_only:false; response_revision:1257; number_of_response:1; }","duration":"103.665205ms","start":"2026-05-08T05:54:30.600042Z","end":"2026-05-08T05:54:30.703707Z","steps":["trace[1386107334] 'process raft request' (duration: 38.83522ms)","trace[1386107334] 'compare' (duration: 64.702943ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T05:54:38.602118Z","caller":"traceutil/trace.go:171","msg":"trace[1953153908] transaction","detail":"{read_only:false; response_revision:1307; number_of_response:1; }","duration":"133.327303ms","start":"2026-05-08T05:54:38.468761Z","end":"2026-05-08T05:54:38.602088Z","steps":["trace[1953153908] 'process raft request' (duration: 133.111455ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:54:38.745071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.677162ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-08T05:54:38.745259Z","caller":"traceutil/trace.go:171","msg":"trace[45150674] transaction","detail":"{read_only:false; response_revision:1308; number_of_response:1; }","duration":"137.746196ms","start":"2026-05-08T05:54:38.607485Z","end":"2026-05-08T05:54:38.745231Z","steps":["trace[45150674] 'process raft request' (duration: 34.772634ms)","trace[45150674] 'compare' (duration: 102.414492ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T05:54:38.75162Z","caller":"traceutil/trace.go:171","msg":"trace[522786009] linearizableReadLoop","detail":"{readStateIndex:1359; appliedIndex:1357; }","duration":"104.607943ms","start":"2026-05-08T05:54:38.646991Z","end":"2026-05-08T05:54:38.751599Z","steps":["trace[522786009] 'read index received' (duration: 104.379404ms)","trace[522786009] 'applied index is now lower than readState.Index' (duration: 227.609µs)"],"step_count":2} {"level":"info","ts":"2026-05-08T05:54:38.751725Z","caller":"traceutil/trace.go:171","msg":"trace[1271707050] transaction","detail":"{read_only:false; response_revision:1309; number_of_response:1; }","duration":"143.733168ms","start":"2026-05-08T05:54:38.607976Z","end":"2026-05-08T05:54:38.75171Z","steps":["trace[1271707050] 'process raft request' (duration: 143.458368ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T05:54:38.752013Z","caller":"traceutil/trace.go:171","msg":"trace[1493897059] transaction","detail":"{read_only:false; response_revision:1310; number_of_response:1; }","duration":"139.285494ms","start":"2026-05-08T05:54:38.612693Z","end":"2026-05-08T05:54:38.751979Z","steps":["trace[1493897059] 'process raft request' (duration: 138.844007ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:54:38.752115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.082281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/default/test-pvc\" ","response":"range_response_count:1 size:1465"} {"level":"info","ts":"2026-05-08T05:54:38.752218Z","caller":"traceutil/trace.go:171","msg":"trace[694235990] range","detail":"{range_begin:/registry/persistentvolumeclaims/default/test-pvc; range_end:; response_count:1; response_revision:1310; }","duration":"105.238337ms","start":"2026-05-08T05:54:38.646957Z","end":"2026-05-08T05:54:38.752195Z","steps":["trace[694235990] 'agreement among raft nodes before linearized reading' (duration: 104.813251ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:54:48.185708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.110602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T05:54:48.185837Z","caller":"traceutil/trace.go:171","msg":"trace[1608902334] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:1351; }","duration":"220.285609ms","start":"2026-05-08T05:54:47.965526Z","end":"2026-05-08T05:54:48.185811Z","steps":["trace[1608902334] 'range keys from in-memory index tree' (duration: 219.952518ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:54:48.868718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.855226ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-05-08T05:54:48.974232Z","caller":"traceutil/trace.go:171","msg":"trace[41482014] transaction","detail":"{read_only:false; response_revision:1352; number_of_response:1; }","duration":"100.511487ms","start":"2026-05-08T05:54:48.873696Z","end":"2026-05-08T05:54:48.974208Z","steps":["trace[41482014] 'process raft request' (duration: 100.316ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T05:54:49.000241Z","caller":"traceutil/trace.go:171","msg":"trace[252260759] linearizableReadLoop","detail":"{readStateIndex:1405; appliedIndex:1403; }","duration":"107.175762ms","start":"2026-05-08T05:54:48.893049Z","end":"2026-05-08T05:54:49.000225Z","steps":["trace[252260759] 'read index received' (duration: 81.012971ms)","trace[252260759] 'applied index is now lower than readState.Index' (duration: 26.161891ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T05:54:49.000338Z","caller":"traceutil/trace.go:171","msg":"trace[1719758499] transaction","detail":"{read_only:false; response_revision:1353; number_of_response:1; }","duration":"125.204036ms","start":"2026-05-08T05:54:48.875102Z","end":"2026-05-08T05:54:49.000306Z","steps":["trace[1719758499] 'process raft request' (duration: 124.826873ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:54:49.00062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.563256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-08T05:54:49.000665Z","caller":"traceutil/trace.go:171","msg":"trace[1425495455] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1353; }","duration":"107.659889ms","start":"2026-05-08T05:54:48.892993Z","end":"2026-05-08T05:54:49.000653Z","steps":["trace[1425495455] 'agreement among raft nodes before linearized reading' (duration: 107.512393ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T05:54:49.111132Z","caller":"traceutil/trace.go:171","msg":"trace[1648522694] transaction","detail":"{read_only:false; response_revision:1355; number_of_response:1; }","duration":"104.387946ms","start":"2026-05-08T05:54:49.006718Z","end":"2026-05-08T05:54:49.111106Z","steps":["trace[1648522694] 'process raft request' (duration: 71.614068ms)","trace[1648522694] 'compare' (duration: 32.625773ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T05:54:49.11149Z","caller":"traceutil/trace.go:171","msg":"trace[1394257976] transaction","detail":"{read_only:false; response_revision:1356; number_of_response:1; }","duration":"104.51357ms","start":"2026-05-08T05:54:49.00696Z","end":"2026-05-08T05:54:49.111474Z","steps":["trace[1394257976] 'process raft request' (duration: 104.083915ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T05:54:49.64219Z","caller":"traceutil/trace.go:171","msg":"trace[892817449] transaction","detail":"{read_only:false; response_revision:1357; number_of_response:1; }","duration":"158.696798ms","start":"2026-05-08T05:54:49.483466Z","end":"2026-05-08T05:54:49.642163Z","steps":["trace[892817449] 'process raft request' (duration: 158.532982ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T05:54:50.142667Z","caller":"traceutil/trace.go:171","msg":"trace[99802396] transaction","detail":"{read_only:false; response_revision:1358; number_of_response:1; }","duration":"438.204185ms","start":"2026-05-08T05:54:49.70443Z","end":"2026-05-08T05:54:50.142635Z","steps":["trace[99802396] 'process raft request' (duration: 438.037119ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:54:50.142846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T05:54:49.704405Z","time spent":"438.35214ms","remote":"127.0.0.1:57324","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T05:55:06.366109Z","caller":"traceutil/trace.go:171","msg":"trace[252545621] transaction","detail":"{read_only:false; response_revision:1415; number_of_response:1; }","duration":"106.712706ms","start":"2026-05-08T05:55:06.259345Z","end":"2026-05-08T05:55:06.366057Z","steps":["trace[252545621] 'process raft request' (duration: 106.481939ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:55:07.370346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.392501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-08T05:55:07.370372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.540938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/envoy-gateway-system/kube-root-ca.crt\" ","response":"range_response_count:1 size:1764"} {"level":"info","ts":"2026-05-08T05:55:07.370437Z","caller":"traceutil/trace.go:171","msg":"trace[694333801] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1416; }","duration":"151.557167ms","start":"2026-05-08T05:55:07.218854Z","end":"2026-05-08T05:55:07.370411Z","steps":["trace[694333801] 'count revisions from in-memory index tree' (duration: 151.288758ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T05:55:07.370448Z","caller":"traceutil/trace.go:171","msg":"trace[1883776603] range","detail":"{range_begin:/registry/configmaps/envoy-gateway-system/kube-root-ca.crt; range_end:; response_count:1; response_revision:1416; }","duration":"143.649532ms","start":"2026-05-08T05:55:07.226781Z","end":"2026-05-08T05:55:07.37043Z","steps":["trace[1883776603] 'range keys from in-memory index tree' (duration: 143.392394ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:55:07.370457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.372372ms","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-05-08T05:55:07.370518Z","caller":"traceutil/trace.go:171","msg":"trace[1759556286] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1416; }","duration":"120.503846ms","start":"2026-05-08T05:55:07.249997Z","end":"2026-05-08T05:55:07.370501Z","steps":["trace[1759556286] 'range keys from in-memory index tree' (duration: 120.175095ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:55:07.370621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.81746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/envoy-gateway-system/envoy-gateway\" ","response":"range_response_count:1 size:5641"} {"level":"warn","ts":"2026-05-08T05:55:07.370657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.399632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-08T05:55:07.370717Z","caller":"traceutil/trace.go:171","msg":"trace[1796768621] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1416; }","duration":"151.501925ms","start":"2026-05-08T05:55:07.219199Z","end":"2026-05-08T05:55:07.370701Z","steps":["trace[1796768621] 'range keys from in-memory index tree' (duration: 151.218966ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T05:55:07.370728Z","caller":"traceutil/trace.go:171","msg":"trace[130976810] range","detail":"{range_begin:/registry/deployments/envoy-gateway-system/envoy-gateway; range_end:; response_count:1; response_revision:1416; }","duration":"140.959155ms","start":"2026-05-08T05:55:07.229728Z","end":"2026-05-08T05:55:07.370687Z","steps":["trace[130976810] 'range keys from in-memory index tree' (duration: 140.633035ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:55:07.370783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.765045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/\" range_end:\"/registry/services/endpoints/default0\" limit:500 ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-05-08T05:55:07.370826Z","caller":"traceutil/trace.go:171","msg":"trace[97223879] range","detail":"{range_begin:/registry/services/endpoints/default/; range_end:/registry/services/endpoints/default0; response_count:1; response_revision:1416; }","duration":"147.816826ms","start":"2026-05-08T05:55:07.222996Z","end":"2026-05-08T05:55:07.370813Z","steps":["trace[97223879] 'range keys from in-memory index tree' (duration: 147.711073ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:55:07.370783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.841931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-05-08T05:55:07.37092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.89471ms","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-05-08T05:55:07.370956Z","caller":"traceutil/trace.go:171","msg":"trace[1958952324] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1416; }","duration":"161.971563ms","start":"2026-05-08T05:55:07.208976Z","end":"2026-05-08T05:55:07.370948Z","steps":["trace[1958952324] 'range keys from in-memory index tree' (duration: 161.726895ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T05:55:07.370917Z","caller":"traceutil/trace.go:171","msg":"trace[1899637631] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1416; }","duration":"123.000676ms","start":"2026-05-08T05:55:07.247889Z","end":"2026-05-08T05:55:07.37089Z","steps":["trace[1899637631] 'range keys from in-memory index tree' (duration: 122.662215ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T05:55:07.371165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.533157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/envoy-gateway-system/\" range_end:\"/registry/services/endpoints/envoy-gateway-system0\" limit:500 ","response":"range_response_count:1 size:1119"} {"level":"info","ts":"2026-05-08T05:55:07.371268Z","caller":"traceutil/trace.go:171","msg":"trace[447183133] range","detail":"{range_begin:/registry/services/endpoints/envoy-gateway-system/; range_end:/registry/services/endpoints/envoy-gateway-system0; response_count:1; response_revision:1416; }","duration":"112.313852ms","start":"2026-05-08T05:55:07.258933Z","end":"2026-05-08T05:55:07.371247Z","steps":["trace[447183133] 'range keys from in-memory index tree' (duration: 111.364141ms)"],"step_count":1}