{"level":"warn","ts":"2026-07-02T00:16:19.451525Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-02T00:16:19.451624Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.57:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.57:2380","--initial-cluster=instance=https://199.204.45.57:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.57:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.57:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-07-02T00:16:19.451695Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-02T00:16:19.451708Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.57:2380"]} {"level":"info","ts":"2026-07-02T00:16:19.451732Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-02T00:16:19.452426Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.57:2379"]} {"level":"info","ts":"2026-07-02T00:16:19.452509Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.57:2380"],"listen-peer-urls":["https://199.204.45.57:2380"],"advertise-client-urls":["https://199.204.45.57:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.57:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.57:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-07-02T00:16:19.457707Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.946381ms"} {"level":"info","ts":"2026-07-02T00:16:19.466163Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b6478f0ac5aeb8e1","cluster-id":"6c58cff80d4a5b62"} {"level":"info","ts":"2026-07-02T00:16:19.466267Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 switched to configuration voters=()"} {"level":"info","ts":"2026-07-02T00:16:19.466301Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 became follower at term 0"} {"level":"info","ts":"2026-07-02T00:16:19.466315Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b6478f0ac5aeb8e1 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-02T00:16:19.46633Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 became follower at term 1"} {"level":"info","ts":"2026-07-02T00:16:19.466382Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 switched to configuration voters=(13134624114678348001)"} {"level":"warn","ts":"2026-07-02T00:16:19.474183Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-02T00:16:19.475457Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-02T00:16:19.476353Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-07-02T00:16:19.478328Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b6478f0ac5aeb8e1","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-02T00:16:19.478459Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b6478f0ac5aeb8e1","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-02T00:16:19.478574Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-02T00:16:19.478715Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-02T00:16:19.478725Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-02T00:16:19.480678Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 switched to configuration voters=(13134624114678348001)"} {"level":"info","ts":"2026-07-02T00:16:19.480859Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"6c58cff80d4a5b62","local-member-id":"b6478f0ac5aeb8e1","added-peer-id":"b6478f0ac5aeb8e1","added-peer-peer-urls":["https://199.204.45.57:2380"]} {"level":"info","ts":"2026-07-02T00:16:19.482924Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-02T00:16:19.483132Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.57:2380"} {"level":"info","ts":"2026-07-02T00:16:19.483206Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.57:2380"} {"level":"info","ts":"2026-07-02T00:16:19.483201Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b6478f0ac5aeb8e1","initial-advertise-peer-urls":["https://199.204.45.57:2380"],"listen-peer-urls":["https://199.204.45.57:2380"],"advertise-client-urls":["https://199.204.45.57:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.57:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-02T00:16:19.483257Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-02T00:16:19.967531Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 is starting a new election at term 1"} {"level":"info","ts":"2026-07-02T00:16:19.967616Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-02T00:16:19.967644Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 received MsgPreVoteResp from b6478f0ac5aeb8e1 at term 1"} {"level":"info","ts":"2026-07-02T00:16:19.967666Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 became candidate at term 2"} {"level":"info","ts":"2026-07-02T00:16:19.967684Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 received MsgVoteResp from b6478f0ac5aeb8e1 at term 2"} {"level":"info","ts":"2026-07-02T00:16:19.9677Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 became leader at term 2"} {"level":"info","ts":"2026-07-02T00:16:19.967713Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b6478f0ac5aeb8e1 elected leader b6478f0ac5aeb8e1 at term 2"} {"level":"info","ts":"2026-07-02T00:16:19.968604Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-02T00:16:19.969144Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b6478f0ac5aeb8e1","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.57:2379]}","request-path":"/0/members/b6478f0ac5aeb8e1/attributes","cluster-id":"6c58cff80d4a5b62","publish-timeout":"7s"} {"level":"info","ts":"2026-07-02T00:16:19.969344Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-02T00:16:19.969557Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"6c58cff80d4a5b62","local-member-id":"b6478f0ac5aeb8e1","cluster-version":"3.5"} {"level":"info","ts":"2026-07-02T00:16:19.969647Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-02T00:16:19.969676Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-02T00:16:19.969503Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-02T00:16:19.96978Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-02T00:16:19.97002Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-02T00:16:19.973448Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.57:2379"} {"level":"info","ts":"2026-07-02T00:16:19.974114Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-02T00:16:29.484205Z","caller":"traceutil/trace.go:171","msg":"trace[308039931] linearizableReadLoop","detail":"{readStateIndex:310; appliedIndex:309; }","duration":"105.892727ms","start":"2026-07-02T00:16:29.378282Z","end":"2026-07-02T00:16:29.484175Z","steps":["trace[308039931] 'read index received' (duration: 26.892371ms)","trace[308039931] 'applied index is now lower than readState.Index' (duration: 78.998766ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T00:16:29.484305Z","caller":"traceutil/trace.go:171","msg":"trace[1642204159] transaction","detail":"{read_only:false; response_revision:300; number_of_response:1; }","duration":"143.404378ms","start":"2026-07-02T00:16:29.340834Z","end":"2026-07-02T00:16:29.484238Z","steps":["trace[1642204159] 'process raft request' (duration: 64.471945ms)","trace[1642204159] 'compare' (duration: 78.674423ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T00:16:29.484553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.189728ms","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-07-02T00:16:29.484985Z","caller":"traceutil/trace.go:171","msg":"trace[869359728] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:300; }","duration":"106.444767ms","start":"2026-07-02T00:16:29.378233Z","end":"2026-07-02T00:16:29.484678Z","steps":["trace[869359728] 'agreement among raft nodes before linearized reading' (duration: 106.064122ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:16:39.437728Z","caller":"traceutil/trace.go:171","msg":"trace[353664229] transaction","detail":"{read_only:false; response_revision:379; number_of_response:1; }","duration":"236.142568ms","start":"2026-07-02T00:16:39.201553Z","end":"2026-07-02T00:16:39.437696Z","steps":["trace[353664229] 'process raft request' (duration: 235.748175ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:16:40.038554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.789844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-02T00:16:40.038656Z","caller":"traceutil/trace.go:171","msg":"trace[1179270344] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:379; }","duration":"298.007292ms","start":"2026-07-02T00:16:39.740624Z","end":"2026-07-02T00:16:40.038632Z","steps":["trace[1179270344] 'range keys from in-memory index tree' (duration: 297.629749ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:16:40.457563Z","caller":"traceutil/trace.go:171","msg":"trace[1319018024] linearizableReadLoop","detail":"{readStateIndex:393; appliedIndex:392; }","duration":"196.329217ms","start":"2026-07-02T00:16:40.261207Z","end":"2026-07-02T00:16:40.457536Z","steps":["trace[1319018024] 'read index received' (duration: 196.128091ms)","trace[1319018024] 'applied index is now lower than readState.Index' (duration: 200.236µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T00:16:40.457612Z","caller":"traceutil/trace.go:171","msg":"trace[1086859243] transaction","detail":"{read_only:false; response_revision:380; number_of_response:1; }","duration":"414.202626ms","start":"2026-07-02T00:16:40.043395Z","end":"2026-07-02T00:16:40.457598Z","steps":["trace[1086859243] 'process raft request' (duration: 414.00726ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:16:40.457715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.498754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-07-02T00:16:40.457748Z","caller":"traceutil/trace.go:171","msg":"trace[378424131] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:380; }","duration":"196.594916ms","start":"2026-07-02T00:16:40.261143Z","end":"2026-07-02T00:16:40.457738Z","steps":["trace[378424131] 'agreement among raft nodes before linearized reading' (duration: 196.511633ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:16:40.458035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:16:40.043371Z","time spent":"414.268599ms","remote":"127.0.0.1:36808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T00:16:40.640134Z","caller":"traceutil/trace.go:171","msg":"trace[366261351] transaction","detail":"{read_only:false; response_revision:381; number_of_response:1; }","duration":"174.873918ms","start":"2026-07-02T00:16:40.465176Z","end":"2026-07-02T00:16:40.64005Z","steps":["trace[366261351] 'process raft request' (duration: 101.50751ms)","trace[366261351] 'compare' (duration: 73.070538ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T00:17:00.238577Z","caller":"traceutil/trace.go:171","msg":"trace[1249512184] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"310.350248ms","start":"2026-07-02T00:16:59.9282Z","end":"2026-07-02T00:17:00.23855Z","steps":["trace[1249512184] 'process raft request' (duration: 310.205494ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:17:00.238731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:16:59.928182Z","time spent":"310.473531ms","remote":"127.0.0.1:36574","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-02T00:17:00.248521Z","caller":"traceutil/trace.go:171","msg":"trace[1380663888] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"314.678115ms","start":"2026-07-02T00:16:59.933819Z","end":"2026-07-02T00:17:00.248497Z","steps":["trace[1380663888] 'process raft request' (duration: 314.554371ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:17:00.248623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:16:59.9338Z","time spent":"314.770457ms","remote":"127.0.0.1:36574","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-02T00:17:00.248621Z","caller":"traceutil/trace.go:171","msg":"trace[1485533094] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"301.557234ms","start":"2026-07-02T00:16:59.947048Z","end":"2026-07-02T00:17:00.248606Z","steps":["trace[1485533094] 'process raft request' (duration: 301.42489ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:17:00.248685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:16:59.947016Z","time spent":"301.637186ms","remote":"127.0.0.1:36574","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-02T00:17:00.248709Z","caller":"traceutil/trace.go:171","msg":"trace[2072971096] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"292.28957ms","start":"2026-07-02T00:16:59.956411Z","end":"2026-07-02T00:17:00.2487Z","steps":["trace[2072971096] 'process raft request' (duration: 292.105725ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:17:00.248798Z","caller":"traceutil/trace.go:171","msg":"trace[2003357407] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"302.283604ms","start":"2026-07-02T00:16:59.946507Z","end":"2026-07-02T00:17:00.24879Z","steps":["trace[2003357407] 'process raft request' (duration: 301.930784ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:17:00.248829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:16:59.946488Z","time spent":"302.327294ms","remote":"127.0.0.1:36574","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-02T00:17:00.248908Z","caller":"traceutil/trace.go:171","msg":"trace[2145103633] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"292.440093ms","start":"2026-07-02T00:16:59.956455Z","end":"2026-07-02T00:17:00.248896Z","steps":["trace[2145103633] 'process raft request' (duration: 292.090964ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:17:00.249229Z","caller":"traceutil/trace.go:171","msg":"trace[1322213022] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"289.224714ms","start":"2026-07-02T00:16:59.959997Z","end":"2026-07-02T00:17:00.249222Z","steps":["trace[1322213022] 'process raft request' (duration: 288.858764ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:17:00.249341Z","caller":"traceutil/trace.go:171","msg":"trace[1897446462] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"275.99147ms","start":"2026-07-02T00:16:59.973344Z","end":"2026-07-02T00:17:00.249336Z","steps":["trace[1897446462] 'process raft request' (duration: 275.770314ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:17:00.249402Z","caller":"traceutil/trace.go:171","msg":"trace[1706555867] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"273.629706ms","start":"2026-07-02T00:16:59.975752Z","end":"2026-07-02T00:17:00.249381Z","steps":["trace[1706555867] 'process raft request' (duration: 273.420371ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:17:00.249952Z","caller":"traceutil/trace.go:171","msg":"trace[121062659] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"137.984361ms","start":"2026-07-02T00:17:00.111952Z","end":"2026-07-02T00:17:00.249937Z","steps":["trace[121062659] 'process raft request' (duration: 137.92268ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:17:00.250107Z","caller":"traceutil/trace.go:171","msg":"trace[1106044879] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"260.113646ms","start":"2026-07-02T00:16:59.989981Z","end":"2026-07-02T00:17:00.250095Z","steps":["trace[1106044879] 'process raft request' (duration: 259.238182ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:17:00.250212Z","caller":"traceutil/trace.go:171","msg":"trace[1339891659] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"240.375024ms","start":"2026-07-02T00:17:00.009829Z","end":"2026-07-02T00:17:00.250204Z","steps":["trace[1339891659] 'process raft request' (duration: 239.469689ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:17:03.382119Z","caller":"traceutil/trace.go:171","msg":"trace[1493696242] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"163.715691ms","start":"2026-07-02T00:17:03.218338Z","end":"2026-07-02T00:17:03.382053Z","steps":["trace[1493696242] 'process raft request' (duration: 163.230149ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:17:03.658526Z","caller":"traceutil/trace.go:171","msg":"trace[1553160676] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"258.085943ms","start":"2026-07-02T00:17:03.40042Z","end":"2026-07-02T00:17:03.658506Z","steps":["trace[1553160676] 'process raft request' (duration: 250.620846ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:17:06.50455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.348049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/cilium-config\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-07-02T00:17:06.504662Z","caller":"traceutil/trace.go:171","msg":"trace[631144037] range","detail":"{range_begin:/registry/configmaps/kube-system/cilium-config; range_end:; response_count:1; response_revision:596; }","duration":"108.473202ms","start":"2026-07-02T00:17:06.39617Z","end":"2026-07-02T00:17:06.504643Z","steps":["trace[631144037] 'range keys from in-memory index tree' (duration: 108.125453ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:17:06.784847Z","caller":"traceutil/trace.go:171","msg":"trace[1513901514] linearizableReadLoop","detail":"{readStateIndex:619; appliedIndex:618; }","duration":"137.152737ms","start":"2026-07-02T00:17:06.647673Z","end":"2026-07-02T00:17:06.784826Z","steps":["trace[1513901514] 'read index received' (duration: 136.491601ms)","trace[1513901514] 'applied index is now lower than readState.Index' (duration: 660.426µs)"],"step_count":2} {"level":"warn","ts":"2026-07-02T00:17:06.785006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.314331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-02T00:17:06.78504Z","caller":"traceutil/trace.go:171","msg":"trace[471656803] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:597; }","duration":"137.384863ms","start":"2026-07-02T00:17:06.647643Z","end":"2026-07-02T00:17:06.785028Z","steps":["trace[471656803] 'agreement among raft nodes before linearized reading' (duration: 137.302891ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:17:06.785044Z","caller":"traceutil/trace.go:171","msg":"trace[968095262] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"139.727704ms","start":"2026-07-02T00:17:06.645241Z","end":"2026-07-02T00:17:06.784968Z","steps":["trace[968095262] 'process raft request' (duration: 139.065197ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:17:06.785171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.013801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-mr786\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-07-02T00:17:06.785479Z","caller":"traceutil/trace.go:171","msg":"trace[25302872] range","detail":"{range_begin:/registry/pods/kube-system/cilium-mr786; range_end:; response_count:1; response_revision:597; }","duration":"119.37043ms","start":"2026-07-02T00:17:06.666091Z","end":"2026-07-02T00:17:06.785462Z","steps":["trace[25302872] 'agreement among raft nodes before linearized reading' (duration: 118.905649ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:17:06.957717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.501469ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-02T00:17:06.957833Z","caller":"traceutil/trace.go:171","msg":"trace[256281380] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"153.504297ms","start":"2026-07-02T00:17:06.80431Z","end":"2026-07-02T00:17:06.957814Z","steps":["trace[256281380] 'process raft request' (duration: 18.68397ms)","trace[256281380] 'compare' (duration: 134.393566ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T00:17:07.749901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"587.195246ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-02T00:17:07.750096Z","caller":"traceutil/trace.go:171","msg":"trace[810898911] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"899.110727ms","start":"2026-07-02T00:17:06.850966Z","end":"2026-07-02T00:17:07.750077Z","steps":["trace[810898911] 'process raft request' (duration: 899.040955ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:17:07.7501Z","caller":"traceutil/trace.go:171","msg":"trace[1321018119] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"938.638222ms","start":"2026-07-02T00:17:06.811435Z","end":"2026-07-02T00:17:07.750073Z","steps":["trace[1321018119] 'process raft request' (duration: 350.750168ms)","trace[1321018119] 'compare' (duration: 586.383425ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T00:17:07.750185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:17:06.850944Z","time spent":"899.20084ms","remote":"127.0.0.1:37018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-02T00:17:07.750227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:17:06.811376Z","time spent":"938.778235ms","remote":"127.0.0.1:36708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T00:17:07.750468Z","caller":"traceutil/trace.go:171","msg":"trace[1013667705] linearizableReadLoop","detail":"{readStateIndex:622; appliedIndex:620; }","duration":"937.262096ms","start":"2026-07-02T00:17:06.81318Z","end":"2026-07-02T00:17:07.750442Z","steps":["trace[1013667705] 'read index received' (duration: 9.753121ms)","trace[1013667705] 'applied index is now lower than readState.Index' (duration: 927.505475ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T00:17:07.750663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.385337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-02T00:17:07.750698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"937.509042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-02T00:17:07.750749Z","caller":"traceutil/trace.go:171","msg":"trace[313265352] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:601; }","duration":"937.596295ms","start":"2026-07-02T00:17:06.813133Z","end":"2026-07-02T00:17:07.75073Z","steps":["trace[313265352] 'agreement among raft nodes before linearized reading' (duration: 937.43333ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:17:07.75076Z","caller":"traceutil/trace.go:171","msg":"trace[841009031] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:601; }","duration":"329.49408ms","start":"2026-07-02T00:17:07.421226Z","end":"2026-07-02T00:17:07.75072Z","steps":["trace[841009031] 'agreement among raft nodes before linearized reading' (duration: 329.374767ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:17:07.750798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:17:06.813126Z","time spent":"937.660516ms","remote":"127.0.0.1:36808","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-02T00:17:07.750824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:17:07.421204Z","time spent":"329.607134ms","remote":"127.0.0.1:36518","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-02T00:17:08.853423Z","caller":"traceutil/trace.go:171","msg":"trace[2032691727] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"676.464432ms","start":"2026-07-02T00:17:08.176931Z","end":"2026-07-02T00:17:08.853396Z","steps":["trace[2032691727] 'process raft request' (duration: 675.905548ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:17:08.853597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:17:08.176905Z","time spent":"676.602406ms","remote":"127.0.0.1:36604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036313,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T00:17:12.460948Z","caller":"traceutil/trace.go:171","msg":"trace[2109019177] transaction","detail":"{read_only:false; response_revision:663; number_of_response:1; }","duration":"229.500225ms","start":"2026-07-02T00:17:12.231406Z","end":"2026-07-02T00:17:12.460907Z","steps":["trace[2109019177] 'process raft request' (duration: 229.331911ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:17:15.931756Z","caller":"traceutil/trace.go:171","msg":"trace[1525930327] linearizableReadLoop","detail":"{readStateIndex:726; appliedIndex:725; }","duration":"127.051827ms","start":"2026-07-02T00:17:15.804664Z","end":"2026-07-02T00:17:15.931716Z","steps":["trace[1525930327] 'read index received' (duration: 124.694851ms)","trace[1525930327] 'applied index is now lower than readState.Index' (duration: 2.355946ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T00:17:15.931936Z","caller":"traceutil/trace.go:171","msg":"trace[1869343566] transaction","detail":"{read_only:false; response_revision:702; number_of_response:1; }","duration":"186.10093ms","start":"2026-07-02T00:17:15.745806Z","end":"2026-07-02T00:17:15.931907Z","steps":["trace[1869343566] 'process raft request' (duration: 183.582521ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:17:15.932297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.621101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-02T00:17:15.932337Z","caller":"traceutil/trace.go:171","msg":"trace[640113214] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:702; }","duration":"127.708182ms","start":"2026-07-02T00:17:15.804615Z","end":"2026-07-02T00:17:15.932323Z","steps":["trace[640113214] 'agreement among raft nodes before linearized reading' (duration: 127.59358ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:17:15.932596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.858453ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-02T00:17:15.932652Z","caller":"traceutil/trace.go:171","msg":"trace[1730554836] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:702; }","duration":"126.965426ms","start":"2026-07-02T00:17:15.805673Z","end":"2026-07-02T00:17:15.932639Z","steps":["trace[1730554836] 'agreement among raft nodes before linearized reading' (duration: 126.765691ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:17:19.046403Z","caller":"traceutil/trace.go:171","msg":"trace[807004528] transaction","detail":"{read_only:false; response_revision:717; number_of_response:1; }","duration":"113.257803ms","start":"2026-07-02T00:17:18.933119Z","end":"2026-07-02T00:17:19.046376Z","steps":["trace[807004528] 'process raft request' (duration: 113.078779ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:17:19.156586Z","caller":"traceutil/trace.go:171","msg":"trace[638083712] transaction","detail":"{read_only:false; response_revision:718; number_of_response:1; }","duration":"100.052188ms","start":"2026-07-02T00:17:19.056502Z","end":"2026-07-02T00:17:19.156554Z","steps":["trace[638083712] 'process raft request' (duration: 86.202613ms)","trace[638083712] 'compare' (duration: 13.64565ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T00:17:19.212412Z","caller":"traceutil/trace.go:171","msg":"trace[2077592118] linearizableReadLoop","detail":"{readStateIndex:745; appliedIndex:743; }","duration":"103.232961ms","start":"2026-07-02T00:17:19.10915Z","end":"2026-07-02T00:17:19.212383Z","steps":["trace[2077592118] 'read index received' (duration: 33.610956ms)","trace[2077592118] 'applied index is now lower than readState.Index' (duration: 69.620085ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T00:17:19.212521Z","caller":"traceutil/trace.go:171","msg":"trace[281509387] transaction","detail":"{read_only:false; response_revision:719; number_of_response:1; }","duration":"149.968024ms","start":"2026-07-02T00:17:19.062508Z","end":"2026-07-02T00:17:19.212476Z","steps":["trace[281509387] 'process raft request' (duration: 149.671688ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:17:19.212822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.6481ms","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-07-02T00:17:19.212891Z","caller":"traceutil/trace.go:171","msg":"trace[1278564304] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:719; }","duration":"103.808144ms","start":"2026-07-02T00:17:19.109067Z","end":"2026-07-02T00:17:19.212876Z","steps":["trace[1278564304] 'agreement among raft nodes before linearized reading' (duration: 103.465946ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:17:19.298681Z","caller":"traceutil/trace.go:171","msg":"trace[203169028] transaction","detail":"{read_only:false; response_revision:720; number_of_response:1; }","duration":"137.183184ms","start":"2026-07-02T00:17:19.161477Z","end":"2026-07-02T00:17:19.29866Z","steps":["trace[203169028] 'process raft request' (duration: 133.219024ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:17:19.299189Z","caller":"traceutil/trace.go:171","msg":"trace[337994909] transaction","detail":"{read_only:false; response_revision:722; number_of_response:1; }","duration":"134.947742ms","start":"2026-07-02T00:17:19.164216Z","end":"2026-07-02T00:17:19.299163Z","steps":["trace[337994909] 'process raft request' (duration: 134.813659ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:17:19.299314Z","caller":"traceutil/trace.go:171","msg":"trace[437958571] transaction","detail":"{read_only:false; response_revision:721; number_of_response:1; }","duration":"135.531596ms","start":"2026-07-02T00:17:19.163741Z","end":"2026-07-02T00:17:19.299273Z","steps":["trace[437958571] 'process raft request' (duration: 134.876331ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:17:19.299975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.864627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-07-02T00:17:19.300847Z","caller":"traceutil/trace.go:171","msg":"trace[922372841] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:722; }","duration":"141.838311ms","start":"2026-07-02T00:17:19.158981Z","end":"2026-07-02T00:17:19.300819Z","steps":["trace[922372841] 'agreement among raft nodes before linearized reading' (duration: 140.147682ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:17:19.319192Z","caller":"traceutil/trace.go:171","msg":"trace[1888992515] transaction","detail":"{read_only:false; response_revision:723; number_of_response:1; }","duration":"101.176784ms","start":"2026-07-02T00:17:19.217987Z","end":"2026-07-02T00:17:19.319164Z","steps":["trace[1888992515] 'process raft request' (duration: 101.02597ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:17:19.429668Z","caller":"traceutil/trace.go:171","msg":"trace[1975595182] linearizableReadLoop","detail":"{readStateIndex:750; appliedIndex:748; }","duration":"123.494812ms","start":"2026-07-02T00:17:19.306149Z","end":"2026-07-02T00:17:19.429644Z","steps":["trace[1975595182] 'read index received' (duration: 12.720689ms)","trace[1975595182] 'applied index is now lower than readState.Index' (duration: 110.772813ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T00:17:19.429977Z","caller":"traceutil/trace.go:171","msg":"trace[1377473324] transaction","detail":"{read_only:false; response_revision:724; number_of_response:1; }","duration":"124.608407ms","start":"2026-07-02T00:17:19.305333Z","end":"2026-07-02T00:17:19.429942Z","steps":["trace[1377473324] 'process raft request' (duration: 95.860793ms)","trace[1377473324] 'compare' (duration: 28.163831ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T00:17:19.430035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.85554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-7hbz7\" ","response":"range_response_count:1 size:3043"} {"level":"info","ts":"2026-07-02T00:17:19.430202Z","caller":"traceutil/trace.go:171","msg":"trace[1877213845] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-7hbz7; range_end:; response_count:1; response_revision:725; }","duration":"123.969962ms","start":"2026-07-02T00:17:19.306097Z","end":"2026-07-02T00:17:19.430067Z","steps":["trace[1877213845] 'agreement among raft nodes before linearized reading' (duration: 123.599244ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:17:19.430393Z","caller":"traceutil/trace.go:171","msg":"trace[1189058964] transaction","detail":"{read_only:false; response_revision:725; number_of_response:1; }","duration":"123.650265ms","start":"2026-07-02T00:17:19.30669Z","end":"2026-07-02T00:17:19.43034Z","steps":["trace[1189058964] 'process raft request' (duration: 122.859358ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:17:19.579728Z","caller":"traceutil/trace.go:171","msg":"trace[1475671950] transaction","detail":"{read_only:false; response_revision:727; number_of_response:1; }","duration":"141.637504ms","start":"2026-07-02T00:17:19.438064Z","end":"2026-07-02T00:17:19.579702Z","steps":["trace[1475671950] 'process raft request' (duration: 108.334366ms)","trace[1475671950] 'compare' (duration: 33.155095ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T00:17:20.081874Z","caller":"traceutil/trace.go:171","msg":"trace[852976699] transaction","detail":"{read_only:false; response_revision:731; number_of_response:1; }","duration":"126.424294ms","start":"2026-07-02T00:17:19.95541Z","end":"2026-07-02T00:17:20.081834Z","steps":["trace[852976699] 'process raft request' (duration: 104.870138ms)","trace[852976699] 'compare' (duration: 21.278389ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T00:17:20.148384Z","caller":"traceutil/trace.go:171","msg":"trace[1544328402] linearizableReadLoop","detail":"{readStateIndex:758; appliedIndex:756; }","duration":"151.320277ms","start":"2026-07-02T00:17:19.997035Z","end":"2026-07-02T00:17:20.148355Z","steps":["trace[1544328402] 'read index received' (duration: 63.24522ms)","trace[1544328402] 'applied index is now lower than readState.Index' (duration: 88.073777ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T00:17:20.148502Z","caller":"traceutil/trace.go:171","msg":"trace[774296730] transaction","detail":"{read_only:false; response_revision:732; number_of_response:1; }","duration":"181.37524ms","start":"2026-07-02T00:17:19.967074Z","end":"2026-07-02T00:17:20.14845Z","steps":["trace[774296730] 'process raft request' (duration: 181.114445ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:17:20.148672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.608313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-02T00:17:20.148765Z","caller":"traceutil/trace.go:171","msg":"trace[1578502421] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:732; }","duration":"151.714886ms","start":"2026-07-02T00:17:19.996979Z","end":"2026-07-02T00:17:20.148694Z","steps":["trace[1578502421] 'agreement among raft nodes before linearized reading' (duration: 151.523082ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:17:22.317593Z","caller":"traceutil/trace.go:171","msg":"trace[820923131] transaction","detail":"{read_only:false; response_revision:751; number_of_response:1; }","duration":"111.521245ms","start":"2026-07-02T00:17:22.206045Z","end":"2026-07-02T00:17:22.317567Z","steps":["trace[820923131] 'process raft request' (duration: 111.368561ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:17:23.471109Z","caller":"traceutil/trace.go:171","msg":"trace[1515919195] linearizableReadLoop","detail":"{readStateIndex:780; appliedIndex:779; }","duration":"131.966173ms","start":"2026-07-02T00:17:23.339124Z","end":"2026-07-02T00:17:23.47109Z","steps":["trace[1515919195] 'read index received' (duration: 131.861721ms)","trace[1515919195] 'applied index is now lower than readState.Index' (duration: 103.012µs)"],"step_count":2} {"level":"warn","ts":"2026-07-02T00:17:23.471705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.557957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T00:17:23.471765Z","caller":"traceutil/trace.go:171","msg":"trace[1855430999] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:753; }","duration":"132.648558ms","start":"2026-07-02T00:17:23.339094Z","end":"2026-07-02T00:17:23.471742Z","steps":["trace[1855430999] 'agreement among raft nodes before linearized reading' (duration: 132.557856ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:17:23.471725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.393857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-02T00:17:23.47213Z","caller":"traceutil/trace.go:171","msg":"trace[236371100] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:753; }","duration":"124.783205ms","start":"2026-07-02T00:17:23.347269Z","end":"2026-07-02T00:17:23.472052Z","steps":["trace[236371100] 'agreement among raft nodes before linearized reading' (duration: 124.228413ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:17:27.268707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"614.486234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-02T00:17:27.269251Z","caller":"traceutil/trace.go:171","msg":"trace[2029962334] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:773; }","duration":"615.086867ms","start":"2026-07-02T00:17:26.654144Z","end":"2026-07-02T00:17:27.269231Z","steps":["trace[2029962334] 'range keys from in-memory index tree' (duration: 614.31195ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:17:27.268862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"488.294928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-7hbz7\" ","response":"range_response_count:1 size:3964"} {"level":"warn","ts":"2026-07-02T00:17:27.2695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:17:26.654115Z","time spent":"615.367852ms","remote":"127.0.0.1:36808","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-07-02T00:17:27.269569Z","caller":"traceutil/trace.go:171","msg":"trace[787936803] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-7hbz7; range_end:; response_count:1; response_revision:773; }","duration":"489.021393ms","start":"2026-07-02T00:17:26.780521Z","end":"2026-07-02T00:17:27.269543Z","steps":["trace[787936803] 'range keys from in-memory index tree' (duration: 488.106004ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:17:27.269642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:17:26.780503Z","time spent":"489.121924ms","remote":"127.0.0.1:36708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3987,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-7hbz7\" "} {"level":"warn","ts":"2026-07-02T00:17:27.268879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.74324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-02T00:17:27.269889Z","caller":"traceutil/trace.go:171","msg":"trace[1136398277] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:773; }","duration":"312.68494ms","start":"2026-07-02T00:17:26.957055Z","end":"2026-07-02T00:17:27.26974Z","steps":["trace[1136398277] 'count revisions from in-memory index tree' (duration: 311.620597ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:17:27.269978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:17:26.957035Z","time spent":"312.922616ms","remote":"127.0.0.1:36574","response type":"/etcdserverpb.KV/Range","request count":0,"request size":120,"response count":31,"response size":30,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true "} {"level":"info","ts":"2026-07-02T00:17:34.353169Z","caller":"traceutil/trace.go:171","msg":"trace[2125380398] transaction","detail":"{read_only:false; response_revision:844; number_of_response:1; }","duration":"172.463789ms","start":"2026-07-02T00:17:34.180675Z","end":"2026-07-02T00:17:34.353139Z","steps":["trace[2125380398] 'process raft request' (duration: 172.263375ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:17:34.353268Z","caller":"traceutil/trace.go:171","msg":"trace[80033521] transaction","detail":"{read_only:false; response_revision:845; number_of_response:1; }","duration":"169.234423ms","start":"2026-07-02T00:17:34.184005Z","end":"2026-07-02T00:17:34.353239Z","steps":["trace[80033521] 'process raft request' (duration: 169.054279ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:17:34.367837Z","caller":"traceutil/trace.go:171","msg":"trace[1433587009] transaction","detail":"{read_only:false; response_revision:846; number_of_response:1; }","duration":"107.90429ms","start":"2026-07-02T00:17:34.259915Z","end":"2026-07-02T00:17:34.367819Z","steps":["trace[1433587009] 'process raft request' (duration: 107.754897ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:17:35.932887Z","caller":"traceutil/trace.go:171","msg":"trace[2050714743] transaction","detail":"{read_only:false; response_revision:863; number_of_response:1; }","duration":"106.791351ms","start":"2026-07-02T00:17:35.826072Z","end":"2026-07-02T00:17:35.932863Z","steps":["trace[2050714743] 'process raft request' (duration: 106.624267ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:17:36.098633Z","caller":"traceutil/trace.go:171","msg":"trace[1839953989] transaction","detail":"{read_only:false; response_revision:867; number_of_response:1; }","duration":"129.790525ms","start":"2026-07-02T00:17:35.968823Z","end":"2026-07-02T00:17:36.098613Z","steps":["trace[1839953989] 'process raft request' (duration: 129.680352ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:17:36.231506Z","caller":"traceutil/trace.go:171","msg":"trace[2017704662] transaction","detail":"{read_only:false; response_revision:869; number_of_response:1; }","duration":"125.836531ms","start":"2026-07-02T00:17:36.105646Z","end":"2026-07-02T00:17:36.231483Z","steps":["trace[2017704662] 'process raft request' (duration: 125.599296ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:17:36.231846Z","caller":"traceutil/trace.go:171","msg":"trace[1612793162] transaction","detail":"{read_only:false; response_revision:870; number_of_response:1; }","duration":"124.068608ms","start":"2026-07-02T00:17:36.10776Z","end":"2026-07-02T00:17:36.231829Z","steps":["trace[1612793162] 'process raft request' (duration: 123.615938ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:17:36.232463Z","caller":"traceutil/trace.go:171","msg":"trace[1525942381] transaction","detail":"{read_only:false; response_revision:871; number_of_response:1; }","duration":"121.696081ms","start":"2026-07-02T00:17:36.110752Z","end":"2026-07-02T00:17:36.232448Z","steps":["trace[1525942381] 'process raft request' (duration: 120.961866ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:17:36.442772Z","caller":"traceutil/trace.go:171","msg":"trace[696370489] transaction","detail":"{read_only:false; response_revision:879; number_of_response:1; }","duration":"132.206567ms","start":"2026-07-02T00:17:36.310542Z","end":"2026-07-02T00:17:36.442749Z","steps":["trace[696370489] 'process raft request' (duration: 132.058084ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:17:36.506736Z","caller":"traceutil/trace.go:171","msg":"trace[1405577046] transaction","detail":"{read_only:false; response_revision:880; number_of_response:1; }","duration":"146.727446ms","start":"2026-07-02T00:17:36.359984Z","end":"2026-07-02T00:17:36.506712Z","steps":["trace[1405577046] 'process raft request' (duration: 146.516181ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:17:36.655653Z","caller":"traceutil/trace.go:171","msg":"trace[1532332241] transaction","detail":"{read_only:false; response_revision:881; number_of_response:1; }","duration":"207.06749ms","start":"2026-07-02T00:17:36.448559Z","end":"2026-07-02T00:17:36.655626Z","steps":["trace[1532332241] 'process raft request' (duration: 174.290901ms)","trace[1532332241] 'compare' (duration: 32.607806ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T00:17:36.655836Z","caller":"traceutil/trace.go:171","msg":"trace[1624362440] transaction","detail":"{read_only:false; response_revision:882; number_of_response:1; }","duration":"207.115121ms","start":"2026-07-02T00:17:36.448694Z","end":"2026-07-02T00:17:36.655809Z","steps":["trace[1624362440] 'process raft request' (duration: 206.880326ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:17:36.656006Z","caller":"traceutil/trace.go:171","msg":"trace[648888236] transaction","detail":"{read_only:false; response_revision:883; number_of_response:1; }","duration":"206.803175ms","start":"2026-07-02T00:17:36.449176Z","end":"2026-07-02T00:17:36.655979Z","steps":["trace[648888236] 'process raft request' (duration: 206.581031ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:17:36.656148Z","caller":"traceutil/trace.go:171","msg":"trace[553352807] transaction","detail":"{read_only:false; response_revision:884; number_of_response:1; }","duration":"206.450657ms","start":"2026-07-02T00:17:36.449663Z","end":"2026-07-02T00:17:36.656114Z","steps":["trace[553352807] 'process raft request' (duration: 206.210353ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:17:36.656173Z","caller":"traceutil/trace.go:171","msg":"trace[87522740] transaction","detail":"{read_only:false; response_revision:885; number_of_response:1; }","duration":"206.342766ms","start":"2026-07-02T00:17:36.449823Z","end":"2026-07-02T00:17:36.656166Z","steps":["trace[87522740] 'process raft request' (duration: 206.117581ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:17:36.656369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.905977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-7c96b6546b-xb52x\" ","response":"range_response_count:1 size:4883"} {"level":"warn","ts":"2026-07-02T00:17:36.65642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.836839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T00:17:36.65643Z","caller":"traceutil/trace.go:171","msg":"trace[820875108] range","detail":"{range_begin:/registry/pods/kube-system/coredns-7c96b6546b-xb52x; range_end:; response_count:1; response_revision:885; }","duration":"208.039048ms","start":"2026-07-02T00:17:36.448375Z","end":"2026-07-02T00:17:36.656414Z","steps":["trace[820875108] 'agreement among raft nodes before linearized reading' (duration: 207.793594ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:17:36.656443Z","caller":"traceutil/trace.go:171","msg":"trace[448153498] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:885; }","duration":"236.88665ms","start":"2026-07-02T00:17:36.41955Z","end":"2026-07-02T00:17:36.656436Z","steps":["trace[448153498] 'agreement among raft nodes before linearized reading' (duration: 236.829299ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:17:36.657206Z","caller":"traceutil/trace.go:171","msg":"trace[1425001628] transaction","detail":"{read_only:false; response_revision:886; number_of_response:1; }","duration":"145.430219ms","start":"2026-07-02T00:17:36.511768Z","end":"2026-07-02T00:17:36.657199Z","steps":["trace[1425001628] 'process raft request' (duration: 145.362397ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:17:36.656014Z","caller":"traceutil/trace.go:171","msg":"trace[1202175003] linearizableReadLoop","detail":"{readStateIndex:915; appliedIndex:910; }","duration":"149.318186ms","start":"2026-07-02T00:17:36.50667Z","end":"2026-07-02T00:17:36.655988Z","steps":["trace[1202175003] 'read index received' (duration: 116.1937ms)","trace[1202175003] 'applied index is now lower than readState.Index' (duration: 33.123056ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T00:17:36.657653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.697323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-02T00:17:36.657703Z","caller":"traceutil/trace.go:171","msg":"trace[1412892241] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:886; }","duration":"103.789825ms","start":"2026-07-02T00:17:36.553898Z","end":"2026-07-02T00:17:36.657687Z","steps":["trace[1412892241] 'agreement among raft nodes before linearized reading' (duration: 103.617962ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:17:36.937359Z","caller":"traceutil/trace.go:171","msg":"trace[1403857596] transaction","detail":"{read_only:false; response_revision:888; number_of_response:1; }","duration":"273.067656ms","start":"2026-07-02T00:17:36.664268Z","end":"2026-07-02T00:17:36.937336Z","steps":["trace[1403857596] 'process raft request' (duration: 272.694839ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:18:28.169377Z","caller":"traceutil/trace.go:171","msg":"trace[1453037707] transaction","detail":"{read_only:false; response_revision:1105; number_of_response:1; }","duration":"188.31273ms","start":"2026-07-02T00:18:27.981015Z","end":"2026-07-02T00:18:28.169327Z","steps":["trace[1453037707] 'process raft request' (duration: 186.815819ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:18:29.156334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"501.336552ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"warn","ts":"2026-07-02T00:18:29.706706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.308622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T00:18:29.707011Z","caller":"traceutil/trace.go:171","msg":"trace[2002514532] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1108; }","duration":"284.676986ms","start":"2026-07-02T00:18:29.422314Z","end":"2026-07-02T00:18:29.706991Z","steps":["trace[2002514532] 'range keys from in-memory index tree' (duration: 284.193351ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:19:00.695875Z","caller":"traceutil/trace.go:171","msg":"trace[1767574341] linearizableReadLoop","detail":"{readStateIndex:1259; appliedIndex:1258; }","duration":"275.824833ms","start":"2026-07-02T00:19:00.420017Z","end":"2026-07-02T00:19:00.695842Z","steps":["trace[1767574341] 'read index received' (duration: 275.013362ms)","trace[1767574341] 'applied index is now lower than readState.Index' (duration: 810.161µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T00:19:00.696016Z","caller":"traceutil/trace.go:171","msg":"trace[556297055] transaction","detail":"{read_only:false; response_revision:1211; number_of_response:1; }","duration":"289.078261ms","start":"2026-07-02T00:19:00.406926Z","end":"2026-07-02T00:19:00.696004Z","steps":["trace[556297055] 'process raft request' (duration: 288.156679ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:19:00.696341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.313339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T00:19:00.696396Z","caller":"traceutil/trace.go:171","msg":"trace[1230855058] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1211; }","duration":"276.421131ms","start":"2026-07-02T00:19:00.419961Z","end":"2026-07-02T00:19:00.696382Z","steps":["trace[1230855058] 'agreement among raft nodes before linearized reading' (duration: 276.322849ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:19:00.696364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.232393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-02T00:19:00.696439Z","caller":"traceutil/trace.go:171","msg":"trace[647940043] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1211; }","duration":"248.616707ms","start":"2026-07-02T00:19:00.447809Z","end":"2026-07-02T00:19:00.696426Z","steps":["trace[647940043] 'agreement among raft nodes before linearized reading' (duration: 248.204572ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:19:54.9293Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13322104133315074726,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-02T00:19:55.028567Z","caller":"traceutil/trace.go:171","msg":"trace[1049580715] linearizableReadLoop","detail":"{readStateIndex:1490; appliedIndex:1489; }","duration":"599.543896ms","start":"2026-07-02T00:19:54.428989Z","end":"2026-07-02T00:19:55.028533Z","steps":["trace[1049580715] 'read index received' (duration: 599.495746ms)","trace[1049580715] 'applied index is now lower than readState.Index' (duration: 46.62µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T00:19:55.028745Z","caller":"traceutil/trace.go:171","msg":"trace[1419378499] transaction","detail":"{read_only:false; response_revision:1428; number_of_response:1; }","duration":"606.665325ms","start":"2026-07-02T00:19:54.422066Z","end":"2026-07-02T00:19:55.028731Z","steps":["trace[1419378499] 'process raft request' (duration: 606.275291ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:19:55.029007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:19:54.422051Z","time spent":"606.807367ms","remote":"127.0.0.1:36808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T00:19:55.029236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"600.223163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-02T00:19:55.029411Z","caller":"traceutil/trace.go:171","msg":"trace[1945126730] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1428; }","duration":"600.445735ms","start":"2026-07-02T00:19:54.428938Z","end":"2026-07-02T00:19:55.029384Z","steps":["trace[1945126730] 'agreement among raft nodes before linearized reading' (duration: 600.109452ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:19:55.029466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:19:54.428921Z","time spent":"600.533587ms","remote":"127.0.0.1:36808","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-07-02T00:19:55.031252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.03676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/pv-protection-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-07-02T00:19:55.031354Z","caller":"traceutil/trace.go:171","msg":"trace[312572941] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/pv-protection-controller; range_end:; response_count:1; response_revision:1429; }","duration":"337.180362ms","start":"2026-07-02T00:19:54.69413Z","end":"2026-07-02T00:19:55.03131Z","steps":["trace[312572941] 'agreement among raft nodes before linearized reading' (duration: 337.025831ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:19:55.031391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:19:54.694107Z","time spent":"337.276703ms","remote":"127.0.0.1:36740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":237,"request content":"key:\"/registry/serviceaccounts/kube-system/pv-protection-controller\" "} {"level":"warn","ts":"2026-07-02T00:19:55.031573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.215704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/pvc-protection-controller\" ","response":"range_response_count:1 size:216"} {"level":"info","ts":"2026-07-02T00:19:55.031653Z","caller":"traceutil/trace.go:171","msg":"trace[163211981] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/pvc-protection-controller; range_end:; response_count:1; response_revision:1429; }","duration":"305.356546ms","start":"2026-07-02T00:19:54.726276Z","end":"2026-07-02T00:19:55.031633Z","steps":["trace[163211981] 'agreement among raft nodes before linearized reading' (duration: 305.204484ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:19:55.031688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:19:54.726244Z","time spent":"305.439066ms","remote":"127.0.0.1:36740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":1,"response size":239,"request content":"key:\"/registry/serviceaccounts/kube-system/pvc-protection-controller\" "} {"level":"warn","ts":"2026-07-02T00:19:55.032343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.686473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-02T00:19:55.032405Z","caller":"traceutil/trace.go:171","msg":"trace[1589263655] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1429; }","duration":"227.823695ms","start":"2026-07-02T00:19:54.804568Z","end":"2026-07-02T00:19:55.032392Z","steps":["trace[1589263655] 'agreement among raft nodes before linearized reading' (duration: 226.897585ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:19:55.858826Z","caller":"traceutil/trace.go:171","msg":"trace[2013379329] transaction","detail":"{read_only:false; response_revision:1433; number_of_response:1; }","duration":"127.111984ms","start":"2026-07-02T00:19:55.731679Z","end":"2026-07-02T00:19:55.858791Z","steps":["trace[2013379329] 'process raft request' (duration: 126.68019ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:19:58.62204Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13322104133315074827,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-02T00:19:58.660262Z","caller":"traceutil/trace.go:171","msg":"trace[871070584] transaction","detail":"{read_only:false; response_revision:1439; number_of_response:1; }","duration":"790.182823ms","start":"2026-07-02T00:19:57.870051Z","end":"2026-07-02T00:19:58.660233Z","steps":["trace[871070584] 'process raft request' (duration: 790.063382ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:19:58.660393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:19:57.870036Z","time spent":"790.284554ms","remote":"127.0.0.1:36808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T00:19:58.660631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"790.484378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-02T00:19:58.660753Z","caller":"traceutil/trace.go:171","msg":"trace[434344857] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1439; }","duration":"790.6751ms","start":"2026-07-02T00:19:57.870056Z","end":"2026-07-02T00:19:58.660731Z","steps":["trace[434344857] 'agreement among raft nodes before linearized reading' (duration: 790.297305ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:19:58.660816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:19:57.870034Z","time spent":"790.76773ms","remote":"127.0.0.1:36808","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-02T00:19:58.661198Z","caller":"traceutil/trace.go:171","msg":"trace[98260331] linearizableReadLoop","detail":"{readStateIndex:1501; appliedIndex:1500; }","duration":"790.176744ms","start":"2026-07-02T00:19:57.870106Z","end":"2026-07-02T00:19:58.660283Z","steps":["trace[98260331] 'read index received' (duration: 790.101293ms)","trace[98260331] 'applied index is now lower than readState.Index' (duration: 34.51µs)"],"step_count":2} {"level":"warn","ts":"2026-07-02T00:19:59.54535Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13322104133315074833,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-02T00:20:00.335857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.34116064s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-02T00:20:00.336169Z","caller":"traceutil/trace.go:171","msg":"trace[334274366] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:1440; }","duration":"1.341425653s","start":"2026-07-02T00:19:58.994612Z","end":"2026-07-02T00:20:00.336038Z","steps":["trace[334274366] 'count revisions from in-memory index tree' (duration: 1.341064689s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:20:00.336328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:19:58.99459Z","time spent":"1.341697316s","remote":"127.0.0.1:36692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":30,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true "} {"level":"warn","ts":"2026-07-02T00:20:00.33602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"552.66679ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-02T00:20:00.336859Z","caller":"traceutil/trace.go:171","msg":"trace[2021338943] linearizableReadLoop","detail":"{readStateIndex:1505; appliedIndex:1503; }","duration":"1.291845239s","start":"2026-07-02T00:19:59.044997Z","end":"2026-07-02T00:20:00.336842Z","steps":["trace[2021338943] 'read index received' (duration: 738.398921ms)","trace[2021338943] 'applied index is now lower than readState.Index' (duration: 553.445068ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T00:20:00.337117Z","caller":"traceutil/trace.go:171","msg":"trace[408725538] transaction","detail":"{read_only:false; response_revision:1441; number_of_response:1; }","duration":"1.293773811s","start":"2026-07-02T00:19:59.043323Z","end":"2026-07-02T00:20:00.337097Z","steps":["trace[408725538] 'process raft request' (duration: 739.928239ms)","trace[408725538] 'compare' (duration: 552.265454ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T00:20:00.33728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:19:59.043302Z","time spent":"1.293918522s","remote":"127.0.0.1:36808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T00:20:00.337655Z","caller":"traceutil/trace.go:171","msg":"trace[150328716] transaction","detail":"{read_only:false; response_revision:1442; number_of_response:1; }","duration":"839.599356ms","start":"2026-07-02T00:19:59.498038Z","end":"2026-07-02T00:20:00.337637Z","steps":["trace[150328716] 'process raft request' (duration: 838.712316ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:20:00.337843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:19:59.498008Z","time spent":"839.783788ms","remote":"127.0.0.1:36808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T00:20:00.337961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.290051239s","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-07-02T00:20:00.33798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.0296434s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-02T00:20:00.337981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.292942561s","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-07-02T00:20:00.338053Z","caller":"traceutil/trace.go:171","msg":"trace[540405838] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1442; }","duration":"1.29016403s","start":"2026-07-02T00:19:59.04787Z","end":"2026-07-02T00:20:00.338034Z","steps":["trace[540405838] 'agreement among raft nodes before linearized reading' (duration: 1.289938898s)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:20:00.338071Z","caller":"traceutil/trace.go:171","msg":"trace[1000862433] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:1442; }","duration":"1.029803881s","start":"2026-07-02T00:19:59.308242Z","end":"2026-07-02T00:20:00.338046Z","steps":["trace[1000862433] 'agreement among raft nodes before linearized reading' (duration: 1.029570599s)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:20:00.338082Z","caller":"traceutil/trace.go:171","msg":"trace[1034046340] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1442; }","duration":"1.293091632s","start":"2026-07-02T00:19:59.044971Z","end":"2026-07-02T00:20:00.338062Z","steps":["trace[1034046340] 'agreement among raft nodes before linearized reading' (duration: 1.292787419s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:20:00.338106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:19:59.047862Z","time spent":"1.290230781s","remote":"127.0.0.1:36808","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-02T00:20:00.338133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:19:59.04496Z","time spent":"1.293162003s","remote":"127.0.0.1:36808","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-07-02T00:20:00.338187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"916.772415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-02T00:20:00.338127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:19:59.308217Z","time spent":"1.029895093s","remote":"127.0.0.1:36864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":69,"response size":30,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true "} {"level":"info","ts":"2026-07-02T00:20:00.338236Z","caller":"traceutil/trace.go:171","msg":"trace[1506294190] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1442; }","duration":"916.867745ms","start":"2026-07-02T00:19:59.421358Z","end":"2026-07-02T00:20:00.338226Z","steps":["trace[1506294190] 'agreement among raft nodes before linearized reading' (duration: 916.795224ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:20:00.338486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:19:59.421335Z","time spent":"917.133259ms","remote":"127.0.0.1:36518","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-02T00:20:00.338488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.287671342s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-07-02T00:20:00.338455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.234111836s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-02T00:20:00.338564Z","caller":"traceutil/trace.go:171","msg":"trace[822946254] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1442; }","duration":"1.287824245s","start":"2026-07-02T00:19:59.050721Z","end":"2026-07-02T00:20:00.338545Z","steps":["trace[822946254] 'agreement among raft nodes before linearized reading' (duration: 1.287569821s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:20:00.338608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:19:59.0507Z","time spent":"1.287898896s","remote":"127.0.0.1:36808","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-02T00:20:00.338603Z","caller":"traceutil/trace.go:171","msg":"trace[642296119] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:1442; }","duration":"1.234326389s","start":"2026-07-02T00:19:59.10425Z","end":"2026-07-02T00:20:00.338577Z","steps":["trace[642296119] 'agreement among raft nodes before linearized reading' (duration: 1.234063225s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:20:00.338683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:19:59.104228Z","time spent":"1.23443623s","remote":"127.0.0.1:37044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":29,"response size":30,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true "}