{"level":"warn","ts":"2026-05-05T12:50:15.188139Z","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-05T12:50:15.188236Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.222: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.222:2380","--initial-cluster=instance=https://199.204.45.222:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.222:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.222: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-05T12:50:15.188345Z","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-05T12:50:15.188361Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.222:2380"]} {"level":"info","ts":"2026-05-05T12:50:15.188379Z","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-05T12:50:15.188974Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.222:2379"]} {"level":"info","ts":"2026-05-05T12:50:15.189048Z","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.222:2380"],"listen-peer-urls":["https://199.204.45.222:2380"],"advertise-client-urls":["https://199.204.45.222:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.222:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.222: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-05T12:50:15.194566Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.30317ms"} {"level":"info","ts":"2026-05-05T12:50:15.200361Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"380f6760faea5f9e","cluster-id":"b87e8aca83a17eab"} {"level":"info","ts":"2026-05-05T12:50:15.200452Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e switched to configuration voters=()"} {"level":"info","ts":"2026-05-05T12:50:15.200487Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e became follower at term 0"} {"level":"info","ts":"2026-05-05T12:50:15.200504Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 380f6760faea5f9e [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-05T12:50:15.200528Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e became follower at term 1"} {"level":"info","ts":"2026-05-05T12:50:15.200585Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e switched to configuration voters=(4039561056998809502)"} {"level":"warn","ts":"2026-05-05T12:50:15.207084Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-05T12:50:15.208456Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-05T12:50:15.209594Z","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-05T12:50:15.212127Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"380f6760faea5f9e","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-05T12:50:15.212647Z","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-05T12:50:15.212736Z","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-05T12:50:15.212749Z","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-05T12:50:15.212748Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"380f6760faea5f9e","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-05T12:50:15.214342Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e switched to configuration voters=(4039561056998809502)"} {"level":"info","ts":"2026-05-05T12:50:15.214505Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"b87e8aca83a17eab","local-member-id":"380f6760faea5f9e","added-peer-id":"380f6760faea5f9e","added-peer-peer-urls":["https://199.204.45.222:2380"]} {"level":"info","ts":"2026-05-05T12:50:15.217934Z","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-05T12:50:15.217998Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.222:2380"} {"level":"info","ts":"2026-05-05T12:50:15.218048Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.222:2380"} {"level":"info","ts":"2026-05-05T12:50:15.218229Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"380f6760faea5f9e","initial-advertise-peer-urls":["https://199.204.45.222:2380"],"listen-peer-urls":["https://199.204.45.222:2380"],"advertise-client-urls":["https://199.204.45.222:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.222:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-05T12:50:15.218287Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-05T12:50:15.901585Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e is starting a new election at term 1"} {"level":"info","ts":"2026-05-05T12:50:15.901668Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e became pre-candidate at term 1"} {"level":"info","ts":"2026-05-05T12:50:15.901703Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e received MsgPreVoteResp from 380f6760faea5f9e at term 1"} {"level":"info","ts":"2026-05-05T12:50:15.901724Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e became candidate at term 2"} {"level":"info","ts":"2026-05-05T12:50:15.901735Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e received MsgVoteResp from 380f6760faea5f9e at term 2"} {"level":"info","ts":"2026-05-05T12:50:15.901751Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"380f6760faea5f9e became leader at term 2"} {"level":"info","ts":"2026-05-05T12:50:15.901764Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 380f6760faea5f9e elected leader 380f6760faea5f9e at term 2"} {"level":"info","ts":"2026-05-05T12:50:15.903107Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T12:50:15.904122Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"380f6760faea5f9e","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.222:2379]}","request-path":"/0/members/380f6760faea5f9e/attributes","cluster-id":"b87e8aca83a17eab","publish-timeout":"7s"} {"level":"info","ts":"2026-05-05T12:50:15.904141Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-05T12:50:15.904344Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-05T12:50:15.904618Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-05T12:50:15.904669Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-05T12:50:15.904772Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"b87e8aca83a17eab","local-member-id":"380f6760faea5f9e","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T12:50:15.904885Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T12:50:15.904912Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T12:50:15.907476Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.222:2379"} {"level":"info","ts":"2026-05-05T12:50:15.907804Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-05T12:50:31.687261Z","caller":"traceutil/trace.go:171","msg":"trace[1602925414] transaction","detail":"{read_only:false; response_revision:414; number_of_response:1; }","duration":"144.665763ms","start":"2026-05-05T12:50:31.54257Z","end":"2026-05-05T12:50:31.687236Z","steps":["trace[1602925414] 'process raft request' (duration: 144.533421ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:50:36.372044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.896817ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T12:50:36.372226Z","caller":"traceutil/trace.go:171","msg":"trace[621075135] linearizableReadLoop","detail":"{readStateIndex:464; appliedIndex:463; }","duration":"153.269044ms","start":"2026-05-05T12:50:36.218917Z","end":"2026-05-05T12:50:36.372186Z","steps":["trace[621075135] 'read index received' (duration: 34.971µs)","trace[621075135] 'applied index is now lower than readState.Index' (duration: 153.232423ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T12:50:36.372301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.379746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T12:50:36.37229Z","caller":"traceutil/trace.go:171","msg":"trace[1187512522] transaction","detail":"{read_only:false; response_revision:453; number_of_response:1; }","duration":"661.832578ms","start":"2026-05-05T12:50:35.710411Z","end":"2026-05-05T12:50:36.372244Z","steps":["trace[1187512522] 'process raft request' (duration: 413.899089ms)","trace[1187512522] 'compare' (duration: 246.693203ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T12:50:36.372351Z","caller":"traceutil/trace.go:171","msg":"trace[65981901] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:453; }","duration":"153.459707ms","start":"2026-05-05T12:50:36.21888Z","end":"2026-05-05T12:50:36.37234Z","steps":["trace[65981901] 'agreement among raft nodes before linearized reading' (duration: 153.381656ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:50:36.372489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:50:35.710376Z","time spent":"661.995632ms","remote":"127.0.0.1:38712","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T12:50:46.186567Z","caller":"traceutil/trace.go:171","msg":"trace[1891948171] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"364.524374ms","start":"2026-05-05T12:50:45.822023Z","end":"2026-05-05T12:50:46.186547Z","steps":["trace[1891948171] 'process raft request' (duration: 364.404741ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:50:46.186919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:50:45.822002Z","time spent":"364.822109ms","remote":"127.0.0.1:38500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-05T12:50:46.196686Z","caller":"traceutil/trace.go:171","msg":"trace[317719915] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"368.66004ms","start":"2026-05-05T12:50:45.827995Z","end":"2026-05-05T12:50:46.196655Z","steps":["trace[317719915] 'process raft request' (duration: 368.297633ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:50:46.19688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:50:45.82797Z","time spent":"368.793773ms","remote":"127.0.0.1:38500","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-05-05T12:50:46.197035Z","caller":"traceutil/trace.go:171","msg":"trace[1700095322] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"359.906277ms","start":"2026-05-05T12:50:45.837118Z","end":"2026-05-05T12:50:46.197024Z","steps":["trace[1700095322] 'process raft request' (duration: 359.556949ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:50:46.197079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:50:45.83711Z","time spent":"359.946067ms","remote":"127.0.0.1:38500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-05T12:50:46.197128Z","caller":"traceutil/trace.go:171","msg":"trace[1751671143] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"369.040219ms","start":"2026-05-05T12:50:45.828081Z","end":"2026-05-05T12:50:46.197122Z","steps":["trace[1751671143] 'process raft request' (duration: 368.406686ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:50:46.197147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:50:45.828046Z","time spent":"369.091549ms","remote":"127.0.0.1:38500","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-05-05T12:50:46.197191Z","caller":"traceutil/trace.go:171","msg":"trace[12914368] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"361.929169ms","start":"2026-05-05T12:50:45.835257Z","end":"2026-05-05T12:50:46.197186Z","steps":["trace[12914368] 'process raft request' (duration: 361.282686ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:50:46.197235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:50:45.835237Z","time spent":"361.98419ms","remote":"127.0.0.1:38500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-05T12:50:46.19728Z","caller":"traceutil/trace.go:171","msg":"trace[569798300] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"361.98742ms","start":"2026-05-05T12:50:45.835287Z","end":"2026-05-05T12:50:46.197275Z","steps":["trace[569798300] 'process raft request' (duration: 361.346197ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:50:46.1973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:50:45.835279Z","time spent":"362.0097ms","remote":"127.0.0.1:38500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-05T12:50:46.197337Z","caller":"traceutil/trace.go:171","msg":"trace[128418635] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"352.535922ms","start":"2026-05-05T12:50:45.844797Z","end":"2026-05-05T12:50:46.197333Z","steps":["trace[128418635] 'process raft request' (duration: 352.026952ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:50:46.197362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:50:45.84479Z","time spent":"352.561892ms","remote":"127.0.0.1:38500","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-05-05T12:50:46.197473Z","caller":"traceutil/trace.go:171","msg":"trace[2049285898] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"357.420145ms","start":"2026-05-05T12:50:45.840041Z","end":"2026-05-05T12:50:46.197461Z","steps":["trace[2049285898] 'process raft request' (duration: 356.70034ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:50:46.197465Z","caller":"traceutil/trace.go:171","msg":"trace[2059642177] linearizableReadLoop","detail":"{readStateIndex:529; appliedIndex:523; }","duration":"356.601616ms","start":"2026-05-05T12:50:45.840841Z","end":"2026-05-05T12:50:46.197443Z","steps":["trace[2059642177] 'read index received' (duration: 346.474704ms)","trace[2059642177] 'applied index is now lower than readState.Index' (duration: 10.125732ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T12:50:46.19752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:50:45.84002Z","time spent":"357.480006ms","remote":"127.0.0.1:38500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-05T12:50:46.197589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.72501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-05T12:50:46.197612Z","caller":"traceutil/trace.go:171","msg":"trace[1097437432] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:521; }","duration":"356.783721ms","start":"2026-05-05T12:50:45.840822Z","end":"2026-05-05T12:50:46.197605Z","steps":["trace[1097437432] 'agreement among raft nodes before linearized reading' (duration: 356.694509ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:50:46.197625Z","caller":"traceutil/trace.go:171","msg":"trace[705006578] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"338.62031ms","start":"2026-05-05T12:50:45.858997Z","end":"2026-05-05T12:50:46.197618Z","steps":["trace[705006578] 'process raft request' (duration: 337.954936ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:50:46.197636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:50:45.840815Z","time spent":"356.815831ms","remote":"127.0.0.1:38712","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-05T12:50:46.197655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:50:45.858967Z","time spent":"338.674841ms","remote":"127.0.0.1:38500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-05T12:50:46.197707Z","caller":"traceutil/trace.go:171","msg":"trace[924200806] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"345.232169ms","start":"2026-05-05T12:50:45.852469Z","end":"2026-05-05T12:50:46.197701Z","steps":["trace[924200806] 'process raft request' (duration: 344.420742ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:50:46.197725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:50:45.852454Z","time spent":"345.261589ms","remote":"127.0.0.1:38500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-05T12:50:46.197787Z","caller":"traceutil/trace.go:171","msg":"trace[1479556743] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"126.707096ms","start":"2026-05-05T12:50:46.071075Z","end":"2026-05-05T12:50:46.197782Z","steps":["trace[1479556743] 'process raft request' (duration: 126.302618ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:50:46.197827Z","caller":"traceutil/trace.go:171","msg":"trace[1677819980] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"300.506971ms","start":"2026-05-05T12:50:45.897315Z","end":"2026-05-05T12:50:46.197822Z","steps":["trace[1677819980] 'process raft request' (duration: 299.863038ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:50:46.197846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:50:45.897229Z","time spent":"300.605472ms","remote":"127.0.0.1:38500","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-05T12:50:47.715631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.770048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/httproutes.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:151236"} {"level":"info","ts":"2026-05-05T12:50:47.71574Z","caller":"traceutil/trace.go:171","msg":"trace[1778083860] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/httproutes.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:584; }","duration":"363.926131ms","start":"2026-05-05T12:50:47.351789Z","end":"2026-05-05T12:50:47.715715Z","steps":["trace[1778083860] 'range keys from in-memory index tree' (duration: 363.532043ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:50:47.715815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:50:47.351773Z","time spent":"364.031284ms","remote":"127.0.0.1:38500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":95,"response count":1,"response size":151259,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/httproutes.gateway.networking.k8s.io\" "} {"level":"warn","ts":"2026-05-05T12:50:47.715846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.085607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/envoyproxies.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:570096"} {"level":"info","ts":"2026-05-05T12:50:47.715904Z","caller":"traceutil/trace.go:171","msg":"trace[1982750560] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/envoyproxies.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:584; }","duration":"274.178519ms","start":"2026-05-05T12:50:47.441711Z","end":"2026-05-05T12:50:47.715889Z","steps":["trace[1982750560] 'range keys from in-memory index tree' (duration: 273.559566ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:50:47.716485Z","caller":"traceutil/trace.go:171","msg":"trace[16793931] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"219.338899ms","start":"2026-05-05T12:50:47.497116Z","end":"2026-05-05T12:50:47.716455Z","steps":["trace[16793931] 'process raft request' (duration: 218.174415ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:50:52.31286Z","caller":"traceutil/trace.go:171","msg":"trace[288072561] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"260.132144ms","start":"2026-05-05T12:50:52.052696Z","end":"2026-05-05T12:50:52.312828Z","steps":["trace[288072561] 'process raft request' (duration: 259.526992ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:50:52.651551Z","caller":"traceutil/trace.go:171","msg":"trace[84422321] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"332.85588ms","start":"2026-05-05T12:50:52.318664Z","end":"2026-05-05T12:50:52.651519Z","steps":["trace[84422321] 'process raft request' (duration: 332.597835ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:50:52.651794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:50:52.31864Z","time spent":"333.017193ms","remote":"127.0.0.1:38712","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T12:50:53.022765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.058832ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-05T12:50:53.022881Z","caller":"traceutil/trace.go:171","msg":"trace[1079551271] linearizableReadLoop","detail":"{readStateIndex:636; appliedIndex:634; }","duration":"566.248533ms","start":"2026-05-05T12:50:52.456613Z","end":"2026-05-05T12:50:53.022862Z","steps":["trace[1079551271] 'read index received' (duration: 194.707773ms)","trace[1079551271] 'applied index is now lower than readState.Index' (duration: 371.53951ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T12:50:53.023163Z","caller":"traceutil/trace.go:171","msg":"trace[1687177294] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"631.710816ms","start":"2026-05-05T12:50:52.391435Z","end":"2026-05-05T12:50:53.023146Z","steps":["trace[1687177294] 'process raft request' (duration: 516.193424ms)","trace[1687177294] 'compare' (duration: 114.874408ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T12:50:53.023239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:50:52.391403Z","time spent":"631.804538ms","remote":"127.0.0.1:38794","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-05T12:50:53.023562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"450.22269ms","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-05T12:50:53.023707Z","caller":"traceutil/trace.go:171","msg":"trace[949680001] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:618; }","duration":"450.444056ms","start":"2026-05-05T12:50:52.573226Z","end":"2026-05-05T12:50:53.02367Z","steps":["trace[949680001] 'agreement among raft nodes before linearized reading' (duration: 449.983926ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:50:53.023833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:50:52.573195Z","time spent":"450.618978ms","remote":"127.0.0.1:38712","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":"warn","ts":"2026-05-05T12:50:53.023606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"566.978779ms","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-05T12:50:53.023991Z","caller":"traceutil/trace.go:171","msg":"trace[2145524955] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:618; }","duration":"567.409178ms","start":"2026-05-05T12:50:52.456562Z","end":"2026-05-05T12:50:53.023971Z","steps":["trace[2145524955] 'agreement among raft nodes before linearized reading' (duration: 566.756324ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:50:53.024224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:50:52.45654Z","time spent":"567.667364ms","remote":"127.0.0.1:38712","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-05-05T12:50:53.024107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.469678ms","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-05T12:50:53.024481Z","caller":"traceutil/trace.go:171","msg":"trace[745953990] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:618; }","duration":"442.893936ms","start":"2026-05-05T12:50:52.581535Z","end":"2026-05-05T12:50:53.024429Z","steps":["trace[745953990] 'agreement among raft nodes before linearized reading' (duration: 441.795404ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:50:53.024774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:50:52.581513Z","time spent":"443.05308ms","remote":"127.0.0.1:38712","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-05T12:50:53.6725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.13986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T12:50:53.673034Z","caller":"traceutil/trace.go:171","msg":"trace[1987788259] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:621; }","duration":"452.282593ms","start":"2026-05-05T12:50:53.220307Z","end":"2026-05-05T12:50:53.67259Z","steps":["trace[1987788259] 'range keys from in-memory index tree' (duration: 452.039168ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:50:53.673118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:50:53.220214Z","time spent":"452.887546ms","remote":"127.0.0.1:38462","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-05-05T12:50:54.100921Z","caller":"traceutil/trace.go:171","msg":"trace[1973976440] linearizableReadLoop","detail":"{readStateIndex:640; appliedIndex:639; }","duration":"426.304869ms","start":"2026-05-05T12:50:53.674596Z","end":"2026-05-05T12:50:54.1009Z","steps":["trace[1973976440] 'read index received' (duration: 425.719006ms)","trace[1973976440] 'applied index is now lower than readState.Index' (duration: 585.283µs)"],"step_count":2} {"level":"warn","ts":"2026-05-05T12:50:54.101046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.432421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T12:50:54.101067Z","caller":"traceutil/trace.go:171","msg":"trace[1711585781] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:622; }","duration":"426.516863ms","start":"2026-05-05T12:50:53.674543Z","end":"2026-05-05T12:50:54.10106Z","steps":["trace[1711585781] 'agreement among raft nodes before linearized reading' (duration: 426.432581ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:50:54.101028Z","caller":"traceutil/trace.go:171","msg":"trace[1412834062] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"652.468721ms","start":"2026-05-05T12:50:53.448518Z","end":"2026-05-05T12:50:54.100987Z","steps":["trace[1412834062] 'process raft request' (duration: 651.9381ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:50:54.101105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:50:53.674518Z","time spent":"426.580245ms","remote":"127.0.0.1:38466","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-05T12:50:54.101266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:50:53.448486Z","time spent":"652.645664ms","remote":"127.0.0.1:38550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036289,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T12:50:55.387118Z","caller":"traceutil/trace.go:171","msg":"trace[341268632] linearizableReadLoop","detail":"{readStateIndex:657; appliedIndex:655; }","duration":"321.214424ms","start":"2026-05-05T12:50:55.065888Z","end":"2026-05-05T12:50:55.387102Z","steps":["trace[341268632] 'read index received' (duration: 308.170071ms)","trace[341268632] 'applied index is now lower than readState.Index' (duration: 13.043233ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T12:50:55.387477Z","caller":"traceutil/trace.go:171","msg":"trace[1534106969] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"322.710366ms","start":"2026-05-05T12:50:55.064758Z","end":"2026-05-05T12:50:55.387468Z","steps":["trace[1534106969] 'process raft request' (duration: 309.278525ms)","trace[1534106969] 'compare' (duration: 12.92747ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T12:50:55.387616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:50:55.064743Z","time spent":"322.81583ms","remote":"127.0.0.1:38712","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T12:50:55.387915Z","caller":"traceutil/trace.go:171","msg":"trace[712958064] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"322.216596ms","start":"2026-05-05T12:50:55.065654Z","end":"2026-05-05T12:50:55.387871Z","steps":["trace[712958064] 'process raft request' (duration: 321.417209ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:50:55.388799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:50:55.065646Z","time spent":"322.91709ms","remote":"127.0.0.1:38712","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T12:50:55.387943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.965461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T12:50:55.389116Z","caller":"traceutil/trace.go:171","msg":"trace[1651452825] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:638; }","duration":"323.160675ms","start":"2026-05-05T12:50:55.065927Z","end":"2026-05-05T12:50:55.389087Z","steps":["trace[1651452825] 'agreement among raft nodes before linearized reading' (duration: 321.92461ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:50:55.389198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:50:55.065922Z","time spent":"323.255437ms","remote":"127.0.0.1:36694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-05T12:50:55.388133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.378334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T12:50:55.389302Z","caller":"traceutil/trace.go:171","msg":"trace[354980608] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:638; }","duration":"256.556649ms","start":"2026-05-05T12:50:55.132727Z","end":"2026-05-05T12:50:55.389284Z","steps":["trace[354980608] 'agreement among raft nodes before linearized reading' (duration: 255.382735ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:50:55.388163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.794924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T12:50:55.389469Z","caller":"traceutil/trace.go:171","msg":"trace[1856352575] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:638; }","duration":"257.109661ms","start":"2026-05-05T12:50:55.132351Z","end":"2026-05-05T12:50:55.38946Z","steps":["trace[1856352575] 'agreement among raft nodes before linearized reading' (duration: 255.797244ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:50:55.388194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.172412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T12:50:55.389534Z","caller":"traceutil/trace.go:171","msg":"trace[434681359] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:638; }","duration":"257.55481ms","start":"2026-05-05T12:50:55.131972Z","end":"2026-05-05T12:50:55.389527Z","steps":["trace[434681359] 'agreement among raft nodes before linearized reading' (duration: 256.201542ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:50:55.388223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.661583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T12:50:55.389608Z","caller":"traceutil/trace.go:171","msg":"trace[225657482] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:638; }","duration":"301.049061ms","start":"2026-05-05T12:50:55.088538Z","end":"2026-05-05T12:50:55.389587Z","steps":["trace[225657482] 'agreement among raft nodes before linearized reading' (duration: 299.668433ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:50:55.389758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:50:55.088528Z","time spent":"301.155914ms","remote":"127.0.0.1:36710","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:500 "} {"level":"warn","ts":"2026-05-05T12:50:55.388258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.658095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T12:50:55.389962Z","caller":"traceutil/trace.go:171","msg":"trace[1043484144] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:638; }","duration":"302.419391ms","start":"2026-05-05T12:50:55.08753Z","end":"2026-05-05T12:50:55.389949Z","steps":["trace[1043484144] 'agreement among raft nodes before linearized reading' (duration: 300.706396ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:50:55.390002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:50:55.087491Z","time spent":"302.498113ms","remote":"127.0.0.1:36710","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true "} {"level":"warn","ts":"2026-05-05T12:50:55.38825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.351418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T12:50:55.388283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.828008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T12:50:55.390153Z","caller":"traceutil/trace.go:171","msg":"trace[1385473973] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:638; }","duration":"324.256208ms","start":"2026-05-05T12:50:55.065871Z","end":"2026-05-05T12:50:55.390127Z","steps":["trace[1385473973] 'agreement among raft nodes before linearized reading' (duration: 322.341238ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:50:55.390222Z","caller":"traceutil/trace.go:171","msg":"trace[297286845] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:638; }","duration":"302.811849ms","start":"2026-05-05T12:50:55.087396Z","end":"2026-05-05T12:50:55.390208Z","steps":["trace[297286845] 'agreement among raft nodes before linearized reading' (duration: 300.873329ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:50:55.390254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:50:55.065865Z","time spent":"324.35595ms","remote":"127.0.0.1:36694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":109,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" limit:500 "} {"level":"warn","ts":"2026-05-05T12:50:55.390276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:50:55.087381Z","time spent":"302.87778ms","remote":"127.0.0.1:36710","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:10000 "} {"level":"warn","ts":"2026-05-05T12:50:55.388308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.351009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T12:50:55.390399Z","caller":"traceutil/trace.go:171","msg":"trace[1167621235] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:638; }","duration":"324.428743ms","start":"2026-05-05T12:50:55.065945Z","end":"2026-05-05T12:50:55.390374Z","steps":["trace[1167621235] 'agreement among raft nodes before linearized reading' (duration: 322.349139ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:50:55.390551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:50:55.065941Z","time spent":"324.526695ms","remote":"127.0.0.1:36694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":109,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" limit:10000 "} {"level":"info","ts":"2026-05-05T12:51:09.851162Z","caller":"traceutil/trace.go:171","msg":"trace[1643641493] transaction","detail":"{read_only:false; response_revision:781; number_of_response:1; }","duration":"388.427555ms","start":"2026-05-05T12:51:09.462707Z","end":"2026-05-05T12:51:09.851134Z","steps":["trace[1643641493] 'process raft request' (duration: 388.254071ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:51:09.851357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:51:09.462681Z","time spent":"388.592909ms","remote":"127.0.0.1:38712","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T12:51:09.974865Z","caller":"traceutil/trace.go:171","msg":"trace[1207085967] linearizableReadLoop","detail":"{readStateIndex:805; appliedIndex:803; }","duration":"229.611605ms","start":"2026-05-05T12:51:09.74523Z","end":"2026-05-05T12:51:09.974841Z","steps":["trace[1207085967] 'read index received' (duration: 105.605724ms)","trace[1207085967] 'applied index is now lower than readState.Index' (duration: 124.004491ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T12:51:09.975067Z","caller":"traceutil/trace.go:171","msg":"trace[1145094527] transaction","detail":"{read_only:false; response_revision:782; number_of_response:1; }","duration":"509.79434ms","start":"2026-05-05T12:51:09.465248Z","end":"2026-05-05T12:51:09.975042Z","steps":["trace[1145094527] 'process raft request' (duration: 507.825048ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:51:09.975193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:51:09.46522Z","time spent":"509.906272ms","remote":"127.0.0.1:38712","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T12:51:09.97509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.85683ms","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-05-05T12:51:09.975269Z","caller":"traceutil/trace.go:171","msg":"trace[1565341717] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:782; }","duration":"230.103395ms","start":"2026-05-05T12:51:09.745153Z","end":"2026-05-05T12:51:09.975257Z","steps":["trace[1565341717] 'agreement among raft nodes before linearized reading' (duration: 229.768508ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:51:44.282966Z","caller":"traceutil/trace.go:171","msg":"trace[866660304] linearizableReadLoop","detail":"{readStateIndex:1031; appliedIndex:1030; }","duration":"124.330863ms","start":"2026-05-05T12:51:44.158613Z","end":"2026-05-05T12:51:44.282944Z","steps":["trace[866660304] 'read index received' (duration: 124.238951ms)","trace[866660304] 'applied index is now lower than readState.Index' (duration: 91.222µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T12:51:44.283098Z","caller":"traceutil/trace.go:171","msg":"trace[1506577862] transaction","detail":"{read_only:false; response_revision:1000; number_of_response:1; }","duration":"124.796283ms","start":"2026-05-05T12:51:44.158266Z","end":"2026-05-05T12:51:44.283062Z","steps":["trace[1506577862] 'process raft request' (duration: 124.536167ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:51:44.283193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.572059ms","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-05T12:51:44.283224Z","caller":"traceutil/trace.go:171","msg":"trace[936583103] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1000; }","duration":"124.669551ms","start":"2026-05-05T12:51:44.158544Z","end":"2026-05-05T12:51:44.283214Z","steps":["trace[936583103] 'agreement among raft nodes before linearized reading' (duration: 124.505728ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:51:45.622629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"996.514421ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-05T12:51:45.622794Z","caller":"traceutil/trace.go:171","msg":"trace[300725041] linearizableReadLoop","detail":"{readStateIndex:1034; appliedIndex:1033; }","duration":"705.079999ms","start":"2026-05-05T12:51:44.917686Z","end":"2026-05-05T12:51:45.622766Z","steps":["trace[300725041] 'read index received' (duration: 33.51µs)","trace[300725041] 'applied index is now lower than readState.Index' (duration: 705.044519ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T12:51:45.622995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"705.287364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-05T12:51:45.623065Z","caller":"traceutil/trace.go:171","msg":"trace[1945359716] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1002; }","duration":"705.366726ms","start":"2026-05-05T12:51:44.917648Z","end":"2026-05-05T12:51:45.623014Z","steps":["trace[1945359716] 'agreement among raft nodes before linearized reading' (duration: 705.267214ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:51:45.62314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:51:44.917628Z","time spent":"705.491738ms","remote":"127.0.0.1:38880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":30,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-05-05T12:51:45.623266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"693.508132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-05T12:51:45.623345Z","caller":"traceutil/trace.go:171","msg":"trace[445191781] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1002; }","duration":"693.606914ms","start":"2026-05-05T12:51:44.929715Z","end":"2026-05-05T12:51:45.623322Z","steps":["trace[445191781] 'agreement among raft nodes before linearized reading' (duration: 693.40383ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:51:45.623379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:51:44.92971Z","time spent":"693.662315ms","remote":"127.0.0.1:38712","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-05T12:51:45.623568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.007318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-05T12:51:45.623607Z","caller":"traceutil/trace.go:171","msg":"trace[1473249981] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1002; }","duration":"133.09728ms","start":"2026-05-05T12:51:45.490499Z","end":"2026-05-05T12:51:45.623596Z","steps":["trace[1473249981] 'agreement among raft nodes before linearized reading' (duration: 133.032378ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:51:45.623799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.113779ms","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-05T12:51:45.623821Z","caller":"traceutil/trace.go:171","msg":"trace[1169331104] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1002; }","duration":"330.17794ms","start":"2026-05-05T12:51:45.293637Z","end":"2026-05-05T12:51:45.623815Z","steps":["trace[1169331104] 'agreement among raft nodes before linearized reading' (duration: 330.079288ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:51:45.62384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:51:45.293613Z","time spent":"330.220642ms","remote":"127.0.0.1:38712","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-05T12:51:45.623915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.976618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T12:51:45.623976Z","caller":"traceutil/trace.go:171","msg":"trace[1019461264] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1002; }","duration":"402.069529ms","start":"2026-05-05T12:51:45.221883Z","end":"2026-05-05T12:51:45.623952Z","steps":["trace[1019461264] 'agreement among raft nodes before linearized reading' (duration: 401.984578ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:51:45.62401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:51:45.221857Z","time spent":"402.147001ms","remote":"127.0.0.1:38462","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-05-05T12:51:45.748918Z","caller":"traceutil/trace.go:171","msg":"trace[1739809304] transaction","detail":"{read_only:false; response_revision:1003; number_of_response:1; }","duration":"120.151003ms","start":"2026-05-05T12:51:45.628733Z","end":"2026-05-05T12:51:45.748884Z","steps":["trace[1739809304] 'process raft request' (duration: 119.922289ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:51:45.74944Z","caller":"traceutil/trace.go:171","msg":"trace[117577363] linearizableReadLoop","detail":"{readStateIndex:1035; appliedIndex:1035; }","duration":"117.492356ms","start":"2026-05-05T12:51:45.63191Z","end":"2026-05-05T12:51:45.749403Z","steps":["trace[117577363] 'read index received' (duration: 117.475536ms)","trace[117577363] 'applied index is now lower than readState.Index' (duration: 13.45µs)"],"step_count":2} {"level":"warn","ts":"2026-05-05T12:51:45.749695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.754722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T12:51:45.749767Z","caller":"traceutil/trace.go:171","msg":"trace[1566317605] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1003; }","duration":"117.888235ms","start":"2026-05-05T12:51:45.631865Z","end":"2026-05-05T12:51:45.749753Z","steps":["trace[1566317605] 'agreement among raft nodes before linearized reading' (duration: 117.66978ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:51:45.752932Z","caller":"traceutil/trace.go:171","msg":"trace[1844570141] transaction","detail":"{read_only:false; response_revision:1004; number_of_response:1; }","duration":"120.752056ms","start":"2026-05-05T12:51:45.632157Z","end":"2026-05-05T12:51:45.752909Z","steps":["trace[1844570141] 'process raft request' (duration: 120.594713ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:52:08.557053Z","caller":"traceutil/trace.go:171","msg":"trace[1041811803] transaction","detail":"{read_only:false; response_revision:1075; number_of_response:1; }","duration":"531.119317ms","start":"2026-05-05T12:52:08.0259Z","end":"2026-05-05T12:52:08.557019Z","steps":["trace[1041811803] 'process raft request' (duration: 530.915613ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:52:08.557304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:52:08.025865Z","time spent":"531.266979ms","remote":"127.0.0.1:38712","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T12:52:08.573247Z","caller":"traceutil/trace.go:171","msg":"trace[1932905657] linearizableReadLoop","detail":"{readStateIndex:1112; appliedIndex:1110; }","duration":"385.760746ms","start":"2026-05-05T12:52:08.187457Z","end":"2026-05-05T12:52:08.573218Z","steps":["trace[1932905657] 'read index received' (duration: 369.361396ms)","trace[1932905657] 'applied index is now lower than readState.Index' (duration: 16.39796ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T12:52:08.573374Z","caller":"traceutil/trace.go:171","msg":"trace[571899980] transaction","detail":"{read_only:false; response_revision:1077; number_of_response:1; }","duration":"188.388935ms","start":"2026-05-05T12:52:08.384972Z","end":"2026-05-05T12:52:08.573361Z","steps":["trace[571899980] 'process raft request' (duration: 188.19978ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:52:08.573295Z","caller":"traceutil/trace.go:171","msg":"trace[673308186] transaction","detail":"{read_only:false; response_revision:1076; number_of_response:1; }","duration":"546.537586ms","start":"2026-05-05T12:52:08.026706Z","end":"2026-05-05T12:52:08.573244Z","steps":["trace[673308186] 'process raft request' (duration: 546.312901ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:52:08.573712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.363149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T12:52:08.573824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.514665ms","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-05T12:52:08.573859Z","caller":"traceutil/trace.go:171","msg":"trace[1268823252] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1077; }","duration":"351.518623ms","start":"2026-05-05T12:52:08.222286Z","end":"2026-05-05T12:52:08.573804Z","steps":["trace[1268823252] 'agreement among raft nodes before linearized reading' (duration: 351.282558ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:52:08.573853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.803855ms","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-05-05T12:52:08.573889Z","caller":"traceutil/trace.go:171","msg":"trace[1303572123] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1077; }","duration":"151.642327ms","start":"2026-05-05T12:52:08.422231Z","end":"2026-05-05T12:52:08.573873Z","steps":["trace[1303572123] 'agreement among raft nodes before linearized reading' (duration: 151.448053ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:52:08.573895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.421311ms","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-05T12:52:08.573937Z","caller":"traceutil/trace.go:171","msg":"trace[1194888347] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1077; }","duration":"386.519153ms","start":"2026-05-05T12:52:08.187406Z","end":"2026-05-05T12:52:08.573925Z","steps":["trace[1194888347] 'agreement among raft nodes before linearized reading' (duration: 386.36428ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:52:08.573931Z","caller":"traceutil/trace.go:171","msg":"trace[1795769898] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1077; }","duration":"147.956309ms","start":"2026-05-05T12:52:08.425956Z","end":"2026-05-05T12:52:08.573912Z","steps":["trace[1795769898] 'agreement among raft nodes before linearized reading' (duration: 147.719184ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:52:08.573963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:52:08.222263Z","time spent":"351.682896ms","remote":"127.0.0.1:38462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-05T12:52:08.573979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:52:08.187377Z","time spent":"386.593904ms","remote":"127.0.0.1:38712","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-05T12:52:08.574412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:52:08.026683Z","time spent":"547.377285ms","remote":"127.0.0.1:38712","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T12:52:10.244088Z","caller":"traceutil/trace.go:171","msg":"trace[1099915023] transaction","detail":"{read_only:false; response_revision:1082; number_of_response:1; }","duration":"576.974376ms","start":"2026-05-05T12:52:09.667072Z","end":"2026-05-05T12:52:10.244046Z","steps":["trace[1099915023] 'process raft request' (duration: 576.387413ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:52:10.244289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:52:09.66704Z","time spent":"577.144819ms","remote":"127.0.0.1:38712","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T12:52:11.027357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.357441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T12:52:11.027412Z","caller":"traceutil/trace.go:171","msg":"trace[1898465312] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:1088; }","duration":"207.456744ms","start":"2026-05-05T12:52:10.819941Z","end":"2026-05-05T12:52:11.027398Z","steps":["trace[1898465312] 'count revisions from in-memory index tree' (duration: 207.295229ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:52:24.502771Z","caller":"traceutil/trace.go:171","msg":"trace[1182094007] linearizableReadLoop","detail":"{readStateIndex:1222; appliedIndex:1221; }","duration":"281.623772ms","start":"2026-05-05T12:52:24.221119Z","end":"2026-05-05T12:52:24.502743Z","steps":["trace[1182094007] 'read index received' (duration: 187.061946ms)","trace[1182094007] 'applied index is now lower than readState.Index' (duration: 94.560216ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T12:52:24.502905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.760914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T12:52:24.502938Z","caller":"traceutil/trace.go:171","msg":"trace[1313207806] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1180; }","duration":"281.847816ms","start":"2026-05-05T12:52:24.221079Z","end":"2026-05-05T12:52:24.502927Z","steps":["trace[1313207806] 'agreement among raft nodes before linearized reading' (duration: 281.767925ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:52:36.862208Z","caller":"traceutil/trace.go:171","msg":"trace[626363402] transaction","detail":"{read_only:false; response_revision:1220; number_of_response:1; }","duration":"143.246936ms","start":"2026-05-05T12:52:36.718926Z","end":"2026-05-05T12:52:36.862173Z","steps":["trace[626363402] 'process raft request' (duration: 143.039321ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:52:41.110487Z","caller":"traceutil/trace.go:171","msg":"trace[681936360] linearizableReadLoop","detail":"{readStateIndex:1299; appliedIndex:1298; }","duration":"225.557408ms","start":"2026-05-05T12:52:40.884898Z","end":"2026-05-05T12:52:41.110456Z","steps":["trace[681936360] 'read index received' (duration: 225.330333ms)","trace[681936360] 'applied index is now lower than readState.Index' (duration: 225.855µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T12:52:41.110557Z","caller":"traceutil/trace.go:171","msg":"trace[738421829] transaction","detail":"{read_only:false; response_revision:1254; number_of_response:1; }","duration":"236.127587ms","start":"2026-05-05T12:52:40.87439Z","end":"2026-05-05T12:52:41.110517Z","steps":["trace[738421829] 'process raft request' (duration: 235.905562ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:52:41.110736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.827905ms","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-05T12:52:41.110778Z","caller":"traceutil/trace.go:171","msg":"trace[495774903] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1254; }","duration":"225.912936ms","start":"2026-05-05T12:52:40.884852Z","end":"2026-05-05T12:52:41.110765Z","steps":["trace[495774903] 'agreement among raft nodes before linearized reading' (duration: 225.731612ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:52:41.110874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.900146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-05T12:52:41.110915Z","caller":"traceutil/trace.go:171","msg":"trace[1220614103] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1254; }","duration":"225.966197ms","start":"2026-05-05T12:52:40.884936Z","end":"2026-05-05T12:52:41.110902Z","steps":["trace[1220614103] 'agreement among raft nodes before linearized reading' (duration: 225.806523ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:52:41.111051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.715169ms","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-05T12:52:41.111077Z","caller":"traceutil/trace.go:171","msg":"trace[478760575] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1254; }","duration":"210.75916ms","start":"2026-05-05T12:52:40.900309Z","end":"2026-05-05T12:52:41.111068Z","steps":["trace[478760575] 'agreement among raft nodes before linearized reading' (duration: 210.684439ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:52:41.111209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.821924ms","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-05T12:52:41.111234Z","caller":"traceutil/trace.go:171","msg":"trace[728818776] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1254; }","duration":"192.879665ms","start":"2026-05-05T12:52:40.918346Z","end":"2026-05-05T12:52:41.111226Z","steps":["trace[728818776] 'agreement among raft nodes before linearized reading' (duration: 192.806112ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:52:41.11169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.016544ms","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-05-05T12:52:41.111802Z","caller":"traceutil/trace.go:171","msg":"trace[654303439] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1254; }","duration":"210.157217ms","start":"2026-05-05T12:52:40.901626Z","end":"2026-05-05T12:52:41.111783Z","steps":["trace[654303439] 'agreement among raft nodes before linearized reading' (duration: 209.341309ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:52:41.517705Z","caller":"traceutil/trace.go:171","msg":"trace[1356770228] transaction","detail":"{read_only:false; response_revision:1255; number_of_response:1; }","duration":"401.432908ms","start":"2026-05-05T12:52:41.116234Z","end":"2026-05-05T12:52:41.517667Z","steps":["trace[1356770228] 'process raft request' (duration: 401.213114ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:52:41.517919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:52:41.11619Z","time spent":"401.622502ms","remote":"127.0.0.1:38712","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T12:52:41.546734Z","caller":"traceutil/trace.go:171","msg":"trace[349834344] transaction","detail":"{read_only:false; response_revision:1256; number_of_response:1; }","duration":"430.141947ms","start":"2026-05-05T12:52:41.116548Z","end":"2026-05-05T12:52:41.54669Z","steps":["trace[349834344] 'process raft request' (duration: 429.551784ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:52:41.546918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:52:41.116523Z","time spent":"430.29301ms","remote":"127.0.0.1:38712","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T12:52:41.547058Z","caller":"traceutil/trace.go:171","msg":"trace[1713834381] transaction","detail":"{read_only:false; response_revision:1257; number_of_response:1; }","duration":"430.426722ms","start":"2026-05-05T12:52:41.11662Z","end":"2026-05-05T12:52:41.547046Z","steps":["trace[1713834381] 'process raft request' (duration: 429.666136ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:52:41.547128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:52:41.116614Z","time spent":"430.467303ms","remote":"127.0.0.1:38712","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T12:52:41.547507Z","caller":"traceutil/trace.go:171","msg":"trace[1160616512] linearizableReadLoop","detail":"{readStateIndex:1304; appliedIndex:1299; }","duration":"346.241348ms","start":"2026-05-05T12:52:41.201222Z","end":"2026-05-05T12:52:41.547463Z","steps":["trace[1160616512] 'read index received' (duration: 316.397996ms)","trace[1160616512] 'applied index is now lower than readState.Index' (duration: 29.842192ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T12:52:41.547725Z","caller":"traceutil/trace.go:171","msg":"trace[625320844] transaction","detail":"{read_only:false; response_revision:1258; number_of_response:1; }","duration":"430.717058ms","start":"2026-05-05T12:52:41.116979Z","end":"2026-05-05T12:52:41.547696Z","steps":["trace[625320844] 'process raft request' (duration: 429.36469ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:52:41.547828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.479492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T12:52:41.547877Z","caller":"traceutil/trace.go:171","msg":"trace[1066912530] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1259; }","duration":"325.551463ms","start":"2026-05-05T12:52:41.22231Z","end":"2026-05-05T12:52:41.547861Z","steps":["trace[1066912530] 'agreement among raft nodes before linearized reading' (duration: 325.474561ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:52:41.547921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:52:41.222292Z","time spent":"325.622685ms","remote":"127.0.0.1:38462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-05T12:52:41.547912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:52:41.116972Z","time spent":"430.860161ms","remote":"127.0.0.1:38712","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T12:52:41.548077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.81439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T12:52:41.548286Z","caller":"traceutil/trace.go:171","msg":"trace[77726554] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:1259; }","duration":"347.098797ms","start":"2026-05-05T12:52:41.201171Z","end":"2026-05-05T12:52:41.54827Z","steps":["trace[77726554] 'agreement among raft nodes before linearized reading' (duration: 346.773949ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:52:41.548551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:52:41.201152Z","time spent":"347.373523ms","remote":"127.0.0.1:36808","response type":"/etcdserverpb.KV/Range","request count":0,"request size":114,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true "} {"level":"info","ts":"2026-05-05T12:52:41.547784Z","caller":"traceutil/trace.go:171","msg":"trace[959007196] transaction","detail":"{read_only:false; response_revision:1259; number_of_response:1; }","duration":"429.778328ms","start":"2026-05-05T12:52:41.117952Z","end":"2026-05-05T12:52:41.54773Z","steps":["trace[959007196] 'process raft request' (duration: 428.46433ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:52:41.549247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:52:41.117927Z","time spent":"431.23639ms","remote":"127.0.0.1:38712","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T12:52:42.783469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"560.841491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T12:52:42.783561Z","caller":"traceutil/trace.go:171","msg":"trace[2085998899] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1261; }","duration":"560.997375ms","start":"2026-05-05T12:52:42.222542Z","end":"2026-05-05T12:52:42.78354Z","steps":["trace[2085998899] 'range keys from in-memory index tree' (duration: 560.741199ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:52:42.783608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:52:42.222519Z","time spent":"561.078606ms","remote":"127.0.0.1:38462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-05T12:52:42.783912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"610.790518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T12:52:42.784015Z","caller":"traceutil/trace.go:171","msg":"trace[172645034] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1261; }","duration":"610.93088ms","start":"2026-05-05T12:52:42.173058Z","end":"2026-05-05T12:52:42.783988Z","steps":["trace[172645034] 'range keys from in-memory index tree' (duration: 610.686765ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:52:42.784062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:52:42.173043Z","time spent":"611.008252ms","remote":"127.0.0.1:38712","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-05-05T12:52:42.808047Z","caller":"traceutil/trace.go:171","msg":"trace[1868492472] transaction","detail":"{read_only:false; response_revision:1262; number_of_response:1; }","duration":"170.900281ms","start":"2026-05-05T12:52:42.637107Z","end":"2026-05-05T12:52:42.808007Z","steps":["trace[1868492472] 'process raft request' (duration: 170.744928ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:52:43.163577Z","caller":"traceutil/trace.go:171","msg":"trace[882618050] transaction","detail":"{read_only:false; response_revision:1263; number_of_response:1; }","duration":"369.379187ms","start":"2026-05-05T12:52:42.793963Z","end":"2026-05-05T12:52:43.163342Z","steps":["trace[882618050] 'process raft request' (duration: 323.405437ms)","trace[882618050] 'compare' (duration: 45.819866ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T12:52:43.1639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:52:42.793923Z","time spent":"369.806746ms","remote":"127.0.0.1:38712","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":457,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-05T12:52:43.228705Z","caller":"traceutil/trace.go:171","msg":"trace[961514466] linearizableReadLoop","detail":"{readStateIndex:1309; appliedIndex:1307; }","duration":"141.000847ms","start":"2026-05-05T12:52:43.087685Z","end":"2026-05-05T12:52:43.228686Z","steps":["trace[961514466] 'read index received' (duration: 29.655039ms)","trace[961514466] 'applied index is now lower than readState.Index' (duration: 111.344318ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T12:52:43.228955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.257192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-05T12:52:43.229027Z","caller":"traceutil/trace.go:171","msg":"trace[1618672896] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1265; }","duration":"141.380605ms","start":"2026-05-05T12:52:43.087631Z","end":"2026-05-05T12:52:43.229012Z","steps":["trace[1618672896] 'agreement among raft nodes before linearized reading' (duration: 141.14811ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:52:43.228957Z","caller":"traceutil/trace.go:171","msg":"trace[59516396] transaction","detail":"{read_only:false; response_revision:1265; number_of_response:1; }","duration":"112.81493ms","start":"2026-05-05T12:52:43.116077Z","end":"2026-05-05T12:52:43.228891Z","steps":["trace[59516396] 'process raft request' (duration: 112.552655ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:52:43.229536Z","caller":"traceutil/trace.go:171","msg":"trace[4440127] transaction","detail":"{read_only:false; response_revision:1264; number_of_response:1; }","duration":"418.047947ms","start":"2026-05-05T12:52:42.811471Z","end":"2026-05-05T12:52:43.229519Z","steps":["trace[4440127] 'process raft request' (duration: 417.056535ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:52:43.229679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:52:42.811446Z","time spent":"418.144538ms","remote":"127.0.0.1:38538","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":836,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-05T12:52:43.338362Z","caller":"traceutil/trace.go:171","msg":"trace[1025261907] transaction","detail":"{read_only:false; response_revision:1266; number_of_response:1; }","duration":"169.707847ms","start":"2026-05-05T12:52:43.168615Z","end":"2026-05-05T12:52:43.338323Z","steps":["trace[1025261907] 'process raft request' (duration: 132.379402ms)","trace[1025261907] 'compare' (duration: 36.908595ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T12:52:43.338542Z","caller":"traceutil/trace.go:171","msg":"trace[495943970] linearizableReadLoop","detail":"{readStateIndex:1311; appliedIndex:1310; }","duration":"109.662004ms","start":"2026-05-05T12:52:43.228758Z","end":"2026-05-05T12:52:43.33842Z","steps":["trace[495943970] 'read index received' (duration: 72.200196ms)","trace[495943970] 'applied index is now lower than readState.Index' (duration: 37.456118ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T12:52:43.338953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.263459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:472"} {"level":"info","ts":"2026-05-05T12:52:43.339027Z","caller":"traceutil/trace.go:171","msg":"trace[808542996] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1267; }","duration":"171.411132ms","start":"2026-05-05T12:52:43.167602Z","end":"2026-05-05T12:52:43.339013Z","steps":["trace[808542996] 'agreement among raft nodes before linearized reading' (duration: 171.052945ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:52:43.780399Z","caller":"traceutil/trace.go:171","msg":"trace[248574742] transaction","detail":"{read_only:false; response_revision:1270; number_of_response:1; }","duration":"152.967685ms","start":"2026-05-05T12:52:43.627406Z","end":"2026-05-05T12:52:43.780374Z","steps":["trace[248574742] 'process raft request' (duration: 152.826242ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:52:44.122755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.180937ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T12:52:44.123025Z","caller":"traceutil/trace.go:171","msg":"trace[2009206166] transaction","detail":"{read_only:false; response_revision:1271; number_of_response:1; }","duration":"494.860781ms","start":"2026-05-05T12:52:43.628121Z","end":"2026-05-05T12:52:44.122982Z","steps":["trace[2009206166] 'process raft request' (duration: 200.187652ms)","trace[2009206166] 'compare' (duration: 294.020394ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T12:52:44.123164Z","caller":"traceutil/trace.go:171","msg":"trace[512787885] transaction","detail":"{read_only:false; response_revision:1272; number_of_response:1; }","duration":"494.898311ms","start":"2026-05-05T12:52:43.628229Z","end":"2026-05-05T12:52:44.123127Z","steps":["trace[512787885] 'process raft request' (duration: 494.669076ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:52:44.123203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:52:43.628092Z","time spent":"495.010334ms","remote":"127.0.0.1:38712","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T12:52:44.12321Z","caller":"traceutil/trace.go:171","msg":"trace[331008082] transaction","detail":"{read_only:false; response_revision:1273; number_of_response:1; }","duration":"494.025923ms","start":"2026-05-05T12:52:43.629155Z","end":"2026-05-05T12:52:44.123181Z","steps":["trace[331008082] 'process raft request' (duration: 493.822039ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:52:44.123306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:52:43.629144Z","time spent":"494.130185ms","remote":"127.0.0.1:38712","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T12:52:44.123339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:52:43.628221Z","time spent":"495.029535ms","remote":"127.0.0.1:38712","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-05-05T12:52:44.678325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.981658ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-05T12:52:44.678448Z","caller":"traceutil/trace.go:171","msg":"trace[1816494366] linearizableReadLoop","detail":"{readStateIndex:1319; appliedIndex:1318; }","duration":"456.496554ms","start":"2026-05-05T12:52:44.221925Z","end":"2026-05-05T12:52:44.678422Z","steps":["trace[1816494366] 'read index received' (duration: 327.467864ms)","trace[1816494366] 'applied index is now lower than readState.Index' (duration: 129.02373ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T12:52:44.679243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.313752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T12:52:44.679336Z","caller":"traceutil/trace.go:171","msg":"trace[1980511271] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1273; }","duration":"457.475415ms","start":"2026-05-05T12:52:44.221846Z","end":"2026-05-05T12:52:44.679321Z","steps":["trace[1980511271] 'agreement among raft nodes before linearized reading' (duration: 456.641117ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:52:44.679421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:52:44.221814Z","time spent":"457.594468ms","remote":"127.0.0.1:38462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-05T12:52:55.034557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"527.026783ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-05T12:52:55.03474Z","caller":"traceutil/trace.go:171","msg":"trace[1084525361] linearizableReadLoop","detail":"{readStateIndex:1395; appliedIndex:1394; }","duration":"812.223237ms","start":"2026-05-05T12:52:54.222484Z","end":"2026-05-05T12:52:55.034707Z","steps":["trace[1084525361] 'read index received' (duration: 285.02057ms)","trace[1084525361] 'applied index is now lower than readState.Index' (duration: 527.199637ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T12:52:55.034898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"812.39402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T12:52:55.034975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"719.658153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T12:52:55.035024Z","caller":"traceutil/trace.go:171","msg":"trace[882286458] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:1347; }","duration":"719.754556ms","start":"2026-05-05T12:52:54.31525Z","end":"2026-05-05T12:52:55.035005Z","steps":["trace[882286458] 'agreement among raft nodes before linearized reading' (duration: 719.628463ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:52:55.035015Z","caller":"traceutil/trace.go:171","msg":"trace[1070627776] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1347; }","duration":"812.551074ms","start":"2026-05-05T12:52:54.222432Z","end":"2026-05-05T12:52:55.034983Z","steps":["trace[1070627776] 'agreement among raft nodes before linearized reading' (duration: 812.35198ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:52:55.03507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:52:54.222375Z","time spent":"812.687347ms","remote":"127.0.0.1:38462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-05T12:52:55.035073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:52:54.315238Z","time spent":"719.822847ms","remote":"127.0.0.1:36694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-05T12:52:55.035337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.820933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-05-05T12:52:55.035415Z","caller":"traceutil/trace.go:171","msg":"trace[1494617752] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1347; }","duration":"172.909195ms","start":"2026-05-05T12:52:54.862484Z","end":"2026-05-05T12:52:55.035393Z","steps":["trace[1494617752] 'agreement among raft nodes before linearized reading' (duration: 172.631419ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:52:55.035454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"676.283448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T12:52:55.035505Z","caller":"traceutil/trace.go:171","msg":"trace[563275808] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:1347; }","duration":"676.389071ms","start":"2026-05-05T12:52:54.359102Z","end":"2026-05-05T12:52:55.035491Z","steps":["trace[563275808] 'agreement among raft nodes before linearized reading' (duration: 676.290779ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:52:55.03555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T12:52:54.359089Z","time spent":"676.451442ms","remote":"127.0.0.1:38904","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":28,"request content":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true "} {"level":"info","ts":"2026-05-05T12:56:16.895368Z","caller":"traceutil/trace.go:171","msg":"trace[1025453960] linearizableReadLoop","detail":"{readStateIndex:2391; appliedIndex:2390; }","duration":"111.109321ms","start":"2026-05-05T12:56:16.784214Z","end":"2026-05-05T12:56:16.895324Z","steps":["trace[1025453960] 'read index received' (duration: 77.768778ms)","trace[1025453960] 'applied index is now lower than readState.Index' (duration: 33.339663ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T12:56:16.895724Z","caller":"traceutil/trace.go:171","msg":"trace[179025604] transaction","detail":"{read_only:false; response_revision:2302; number_of_response:1; }","duration":"165.177627ms","start":"2026-05-05T12:56:16.730483Z","end":"2026-05-05T12:56:16.89566Z","steps":["trace[179025604] 'process raft request' (duration: 131.531428ms)","trace[179025604] 'compare' (duration: 33.131427ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T12:56:16.896115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.507189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-05-05T12:56:16.896217Z","caller":"traceutil/trace.go:171","msg":"trace[350103755] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2302; }","duration":"112.041921ms","start":"2026-05-05T12:56:16.784156Z","end":"2026-05-05T12:56:16.896198Z","steps":["trace[350103755] 'agreement among raft nodes before linearized reading' (duration: 111.415897ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:56:17.245412Z","caller":"traceutil/trace.go:171","msg":"trace[1545565543] transaction","detail":"{read_only:false; response_revision:2304; number_of_response:1; }","duration":"124.918263ms","start":"2026-05-05T12:56:17.120434Z","end":"2026-05-05T12:56:17.245352Z","steps":["trace[1545565543] 'process raft request' (duration: 124.401081ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:56:17.420779Z","caller":"traceutil/trace.go:171","msg":"trace[1245040781] transaction","detail":"{read_only:false; response_revision:2306; number_of_response:1; }","duration":"105.597118ms","start":"2026-05-05T12:56:17.315149Z","end":"2026-05-05T12:56:17.420746Z","steps":["trace[1245040781] 'process raft request' (duration: 105.517087ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T12:56:17.421045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.945966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-05-05T12:56:17.421113Z","caller":"traceutil/trace.go:171","msg":"trace[2123135386] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2306; }","duration":"106.085749ms","start":"2026-05-05T12:56:17.315015Z","end":"2026-05-05T12:56:17.4211Z","steps":["trace[2123135386] 'agreement among raft nodes before linearized reading' (duration: 105.825703ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T12:56:17.420779Z","caller":"traceutil/trace.go:171","msg":"trace[1179178628] linearizableReadLoop","detail":"{readStateIndex:2394; appliedIndex:2393; }","duration":"105.687941ms","start":"2026-05-05T12:56:17.31507Z","end":"2026-05-05T12:56:17.420757Z","steps":["trace[1179178628] 'read index received' (duration: 17.651228ms)","trace[1179178628] 'applied index is now lower than readState.Index' (duration: 88.035483ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T12:56:17.421337Z","caller":"traceutil/trace.go:171","msg":"trace[726733009] transaction","detail":"{read_only:false; response_revision:2305; number_of_response:1; }","duration":"106.745773ms","start":"2026-05-05T12:56:17.314544Z","end":"2026-05-05T12:56:17.42129Z","steps":["trace[726733009] 'process raft request' (duration: 18.20195ms)","trace[726733009] 'compare' (duration: 87.674124ms)"],"step_count":2}