{"level":"warn","ts":"2026-03-25T21:06:53.834706Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-25T21:06:53.834818Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.3: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.3:2380","--initial-cluster=instance=https://199.204.45.3:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.3:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.3: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-03-25T21:06:53.835075Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-25T21:06:53.835095Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.3:2380"]} {"level":"info","ts":"2026-03-25T21:06:53.835117Z","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-03-25T21:06:53.83598Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.3:2379"]} {"level":"info","ts":"2026-03-25T21:06:53.836184Z","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.3:2380"],"listen-peer-urls":["https://199.204.45.3:2380"],"advertise-client-urls":["https://199.204.45.3:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.3:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.3: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-03-25T21:06:53.841421Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.870616ms"} {"level":"info","ts":"2026-03-25T21:06:53.853072Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"597401b06ce1cf3d","cluster-id":"dfc54657ad8a2432"} {"level":"info","ts":"2026-03-25T21:06:53.853327Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d switched to configuration voters=()"} {"level":"info","ts":"2026-03-25T21:06:53.85355Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d became follower at term 0"} {"level":"info","ts":"2026-03-25T21:06:53.853574Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 597401b06ce1cf3d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-25T21:06:53.853586Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d became follower at term 1"} {"level":"info","ts":"2026-03-25T21:06:53.853665Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d switched to configuration voters=(6445778823926632253)"} {"level":"warn","ts":"2026-03-25T21:06:53.860483Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-25T21:06:53.861998Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-25T21:06:53.863209Z","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-03-25T21:06:53.865919Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"597401b06ce1cf3d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-25T21:06:53.866214Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"597401b06ce1cf3d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-25T21:06:53.866585Z","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-03-25T21:06:53.866731Z","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-03-25T21:06:53.866762Z","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-03-25T21:06:53.867469Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d switched to configuration voters=(6445778823926632253)"} {"level":"info","ts":"2026-03-25T21:06:53.870362Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"dfc54657ad8a2432","local-member-id":"597401b06ce1cf3d","added-peer-id":"597401b06ce1cf3d","added-peer-peer-urls":["https://199.204.45.3:2380"]} {"level":"info","ts":"2026-03-25T21:06:53.872451Z","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-03-25T21:06:53.87253Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.3:2380"} {"level":"info","ts":"2026-03-25T21:06:53.872572Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.3:2380"} {"level":"info","ts":"2026-03-25T21:06:53.873019Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"597401b06ce1cf3d","initial-advertise-peer-urls":["https://199.204.45.3:2380"],"listen-peer-urls":["https://199.204.45.3:2380"],"advertise-client-urls":["https://199.204.45.3:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.3:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-25T21:06:53.873098Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-25T21:06:54.754517Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d is starting a new election at term 1"} {"level":"info","ts":"2026-03-25T21:06:54.754579Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d became pre-candidate at term 1"} {"level":"info","ts":"2026-03-25T21:06:54.754602Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d received MsgPreVoteResp from 597401b06ce1cf3d at term 1"} {"level":"info","ts":"2026-03-25T21:06:54.754621Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d became candidate at term 2"} {"level":"info","ts":"2026-03-25T21:06:54.754632Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d received MsgVoteResp from 597401b06ce1cf3d at term 2"} {"level":"info","ts":"2026-03-25T21:06:54.754648Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d became leader at term 2"} {"level":"info","ts":"2026-03-25T21:06:54.754661Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 597401b06ce1cf3d elected leader 597401b06ce1cf3d at term 2"} {"level":"info","ts":"2026-03-25T21:06:54.755785Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"597401b06ce1cf3d","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.3:2379]}","request-path":"/0/members/597401b06ce1cf3d/attributes","cluster-id":"dfc54657ad8a2432","publish-timeout":"7s"} {"level":"info","ts":"2026-03-25T21:06:54.755878Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-25T21:06:54.755986Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-25T21:06:54.755956Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-25T21:06:54.756571Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-25T21:06:54.756657Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-25T21:06:54.756738Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"dfc54657ad8a2432","local-member-id":"597401b06ce1cf3d","cluster-version":"3.5"} {"level":"info","ts":"2026-03-25T21:06:54.75685Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-25T21:06:54.756882Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-25T21:06:54.759253Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.3:2379"} {"level":"info","ts":"2026-03-25T21:06:54.75954Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-25T21:07:18.459771Z","caller":"traceutil/trace.go:171","msg":"trace[2050787093] transaction","detail":"{read_only:false; response_revision:418; number_of_response:1; }","duration":"152.556418ms","start":"2026-03-25T21:07:18.307186Z","end":"2026-03-25T21:07:18.459743Z","steps":["trace[2050787093] 'process raft request' (duration: 152.309812ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:07:25.822721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.697714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T21:07:25.822839Z","caller":"traceutil/trace.go:171","msg":"trace[62033645] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:469; }","duration":"414.898458ms","start":"2026-03-25T21:07:25.407921Z","end":"2026-03-25T21:07:25.82282Z","steps":["trace[62033645] 'range keys from in-memory index tree' (duration: 414.608911ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:07:25.822889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:07:25.407902Z","time spent":"414.97895ms","remote":"127.0.0.1:54946","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-03-25T21:07:25.822727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.003311839s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T21:07:25.823182Z","caller":"traceutil/trace.go:171","msg":"trace[1531011813] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:469; }","duration":"1.003790239s","start":"2026-03-25T21:07:24.819367Z","end":"2026-03-25T21:07:25.823158Z","steps":["trace[1531011813] 'range keys from in-memory index tree' (duration: 1.003262677s)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:07:25.823231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:07:24.819357Z","time spent":"1.003858721s","remote":"127.0.0.1:55058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" "} {"level":"warn","ts":"2026-03-25T21:07:27.025321Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14933264729494069530,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-25T21:07:27.526291Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14933264729494069530,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-25T21:07:28.027122Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14933264729494069530,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-25T21:07:28.115094Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.123023069s","expected-duration":"1s"} {"level":"info","ts":"2026-03-25T21:07:28.116229Z","caller":"traceutil/trace.go:171","msg":"trace[1234653021] transaction","detail":"{read_only:false; response_revision:470; number_of_response:1; }","duration":"2.124215877s","start":"2026-03-25T21:07:25.991969Z","end":"2026-03-25T21:07:28.116184Z","steps":["trace[1234653021] 'process raft request' (duration: 2.123966411s)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:07:28.117175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:07:25.991948Z","time spent":"2.124609467s","remote":"127.0.0.1:54996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-25T21:07:29.408646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999806815s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-03-25T21:07:29.408788Z","caller":"traceutil/trace.go:171","msg":"trace[1535268440] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.00000064s","start":"2026-03-25T21:07:27.408751Z","end":"2026-03-25T21:07:29.408752Z","steps":["trace[1535268440] 'agreement among raft nodes before linearized reading' (duration: 1.999845566s)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:07:29.408895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:07:27.408716Z","time spent":"2.000161694s","remote":"127.0.0.1:54946","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-25T21:07:29.912777Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.15984778s","expected-duration":"1s"} {"level":"info","ts":"2026-03-25T21:07:29.913457Z","caller":"traceutil/trace.go:171","msg":"trace[1093544649] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"3.919074594s","start":"2026-03-25T21:07:25.994319Z","end":"2026-03-25T21:07:29.913394Z","steps":["trace[1093544649] 'process raft request' (duration: 3.918662844s)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:07:29.913527Z","caller":"traceutil/trace.go:171","msg":"trace[351221565] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"3.918051019s","start":"2026-03-25T21:07:25.995406Z","end":"2026-03-25T21:07:29.913457Z","steps":["trace[351221565] 'process raft request' (duration: 3.917813304s)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:07:29.913684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:07:25.994299Z","time spent":"3.919288268s","remote":"127.0.0.1:54996","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-03-25T21:07:29.913769Z","caller":"traceutil/trace.go:171","msg":"trace[1791525998] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"3.904976989s","start":"2026-03-25T21:07:26.008771Z","end":"2026-03-25T21:07:29.913748Z","steps":["trace[1791525998] 'process raft request' (duration: 3.904825376s)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:07:29.913755Z","caller":"traceutil/trace.go:171","msg":"trace[1862795782] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"3.907424188s","start":"2026-03-25T21:07:26.006264Z","end":"2026-03-25T21:07:29.913689Z","steps":["trace[1862795782] 'process raft request' (duration: 3.90707821s)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:07:29.913852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:07:26.008758Z","time spent":"3.905047581s","remote":"127.0.0.1:54996","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-03-25T21:07:29.913873Z","caller":"traceutil/trace.go:171","msg":"trace[1184844069] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"3.907008647s","start":"2026-03-25T21:07:26.006753Z","end":"2026-03-25T21:07:29.913762Z","steps":["trace[1184844069] 'process raft request' (duration: 3.906659469s)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:07:29.913949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:07:26.006244Z","time spent":"3.907607042s","remote":"127.0.0.1:54996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-25T21:07:29.913987Z","caller":"traceutil/trace.go:171","msg":"trace[1515400624] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"3.900056022s","start":"2026-03-25T21:07:26.013912Z","end":"2026-03-25T21:07:29.913968Z","steps":["trace[1515400624] 'process raft request' (duration: 3.899809736s)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:07:29.913918Z","caller":"traceutil/trace.go:171","msg":"trace[468275264] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"3.904318183s","start":"2026-03-25T21:07:26.009562Z","end":"2026-03-25T21:07:29.91388Z","steps":["trace[468275264] 'process raft request' (duration: 3.904096918s)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:07:29.914051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:07:26.013895Z","time spent":"3.900132555s","remote":"127.0.0.1:54996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-25T21:07:29.914134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:07:26.009555Z","time spent":"3.904501738s","remote":"127.0.0.1:54996","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-03-25T21:07:29.914157Z","caller":"traceutil/trace.go:171","msg":"trace[1392277880] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"3.890601808s","start":"2026-03-25T21:07:26.023539Z","end":"2026-03-25T21:07:29.914141Z","steps":["trace[1392277880] 'process raft request' (duration: 3.89025894s)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:07:29.914171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:07:26.00673Z","time spent":"3.907296595s","remote":"127.0.0.1:54996","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-03-25T21:07:29.914188Z","caller":"traceutil/trace.go:171","msg":"trace[1255044684] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"3.886177963s","start":"2026-03-25T21:07:26.027999Z","end":"2026-03-25T21:07:29.914177Z","steps":["trace[1255044684] 'process raft request' (duration: 3.885904347s)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:07:29.914208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:07:26.023519Z","time spent":"3.890660619s","remote":"127.0.0.1:54996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-25T21:07:29.914244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:07:26.027981Z","time spent":"3.886235974s","remote":"127.0.0.1:54996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-25T21:07:29.91369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:07:25.995398Z","time spent":"3.918189602s","remote":"127.0.0.1:54996","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-03-25T21:07:29.91444Z","caller":"traceutil/trace.go:171","msg":"trace[553788853] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"3.866034105s","start":"2026-03-25T21:07:26.048387Z","end":"2026-03-25T21:07:29.914422Z","steps":["trace[553788853] 'process raft request' (duration: 3.865594015s)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:07:29.914514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:07:26.048355Z","time spent":"3.866125168s","remote":"127.0.0.1:54996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-25T21:07:29.914571Z","caller":"traceutil/trace.go:171","msg":"trace[478328971] linearizableReadLoop","detail":"{readStateIndex:499; appliedIndex:486; }","duration":"3.389719993s","start":"2026-03-25T21:07:26.524818Z","end":"2026-03-25T21:07:29.914538Z","steps":["trace[478328971] 'read index received' (duration: 1.590749679s)","trace[478328971] 'applied index is now lower than readState.Index' (duration: 1.798969034s)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:07:29.914639Z","caller":"traceutil/trace.go:171","msg":"trace[2108730962] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"2.749026831s","start":"2026-03-25T21:07:27.165596Z","end":"2026-03-25T21:07:29.914623Z","steps":["trace[2108730962] 'process raft request' (duration: 2.748869567s)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:07:29.914734Z","caller":"traceutil/trace.go:171","msg":"trace[1582545738] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"3.758827994s","start":"2026-03-25T21:07:26.155894Z","end":"2026-03-25T21:07:29.914722Z","steps":["trace[1582545738] 'process raft request' (duration: 3.758347553s)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:07:29.914797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:07:26.155864Z","time spent":"3.758906055s","remote":"127.0.0.1:54996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-25T21:07:29.914877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.3900296s","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-03-25T21:07:29.914916Z","caller":"traceutil/trace.go:171","msg":"trace[1301249953] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:482; }","duration":"3.390148503s","start":"2026-03-25T21:07:26.524756Z","end":"2026-03-25T21:07:29.914905Z","steps":["trace[1301249953] 'agreement among raft nodes before linearized reading' (duration: 3.389932457s)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:07:29.914958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:07:26.524731Z","time spent":"3.390217615s","remote":"127.0.0.1:44388","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-03-25T21:07:29.914989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:07:27.165554Z","time spent":"2.749125463s","remote":"127.0.0.1:44388","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-25T21:07:29.992136Z","caller":"traceutil/trace.go:171","msg":"trace[1780009269] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"1.867065979s","start":"2026-03-25T21:07:28.125046Z","end":"2026-03-25T21:07:29.992112Z","steps":["trace[1780009269] 'process raft request' (duration: 1.863884653s)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:07:29.9922Z","caller":"traceutil/trace.go:171","msg":"trace[869947167] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"1.865987013s","start":"2026-03-25T21:07:28.126174Z","end":"2026-03-25T21:07:29.992161Z","steps":["trace[869947167] 'process raft request' (duration: 1.86585583s)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:07:29.992264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:07:28.125005Z","time spent":"1.867193472s","remote":"127.0.0.1:44634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":995,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-25T21:07:29.992296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.424998259s","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-03-25T21:07:29.992378Z","caller":"traceutil/trace.go:171","msg":"trace[384148875] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:484; }","duration":"3.425149012s","start":"2026-03-25T21:07:26.567209Z","end":"2026-03-25T21:07:29.992358Z","steps":["trace[384148875] 'agreement among raft nodes before linearized reading' (duration: 3.424929847s)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:07:29.992423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:07:26.56718Z","time spent":"3.425234545s","remote":"127.0.0.1:44388","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-03-25T21:07:29.99245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:07:28.126163Z","time spent":"1.866130336s","remote":"127.0.0.1:54996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6888,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-25T21:07:29.99254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"583.081916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T21:07:29.992577Z","caller":"traceutil/trace.go:171","msg":"trace[24031062] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:484; }","duration":"583.151077ms","start":"2026-03-25T21:07:29.409415Z","end":"2026-03-25T21:07:29.992566Z","steps":["trace[24031062] 'agreement among raft nodes before linearized reading' (duration: 583.086506ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:07:29.992543Z","caller":"traceutil/trace.go:171","msg":"trace[1761431553] transaction","detail":"{read_only:false; number_of_response:1; response_revision:484; }","duration":"1.866118016s","start":"2026-03-25T21:07:28.126365Z","end":"2026-03-25T21:07:29.992483Z","steps":["trace[1761431553] 'process raft request' (duration: 1.865727527s)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:07:29.992589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.25165887s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-03-25T21:07:29.99253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.186049732s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.3\" ","response":"range_response_count:1 size:131"} {"level":"info","ts":"2026-03-25T21:07:29.992959Z","caller":"traceutil/trace.go:171","msg":"trace[1939571071] range","detail":"{range_begin:/registry/masterleases/199.204.45.3; range_end:; response_count:1; response_revision:484; }","duration":"2.186485913s","start":"2026-03-25T21:07:27.806394Z","end":"2026-03-25T21:07:29.99288Z","steps":["trace[1939571071] 'agreement among raft nodes before linearized reading' (duration: 2.18594142s)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:07:29.99306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:07:27.806366Z","time spent":"2.186677047s","remote":"127.0.0.1:54986","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":1,"response size":154,"request content":"key:\"/registry/masterleases/199.204.45.3\" "} {"level":"info","ts":"2026-03-25T21:07:29.992639Z","caller":"traceutil/trace.go:171","msg":"trace[1149001467] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:484; }","duration":"3.251750622s","start":"2026-03-25T21:07:26.740876Z","end":"2026-03-25T21:07:29.992627Z","steps":["trace[1149001467] 'agreement among raft nodes before linearized reading' (duration: 3.251649039s)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:07:29.993227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:07:26.740851Z","time spent":"3.252364056s","remote":"127.0.0.1:44388","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-03-25T21:07:29.99264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.39383027s","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-03-25T21:07:29.993317Z","caller":"traceutil/trace.go:171","msg":"trace[100861849] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:484; }","duration":"3.394545427s","start":"2026-03-25T21:07:26.59876Z","end":"2026-03-25T21:07:29.993306Z","steps":["trace[100861849] 'agreement among raft nodes before linearized reading' (duration: 3.39385613s)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:07:29.993343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:07:26.598737Z","time spent":"3.394600428s","remote":"127.0.0.1:44388","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-03-25T21:07:29.992598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:07:29.409393Z","time spent":"583.201599ms","remote":"127.0.0.1:54956","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-03-25T21:07:29.992708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:07:28.12634Z","time spent":"1.86629141s","remote":"127.0.0.1:54996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":102,"response count":0,"response size":6937,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-25T21:07:36.193068Z","caller":"traceutil/trace.go:171","msg":"trace[497679038] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"154.063141ms","start":"2026-03-25T21:07:36.038984Z","end":"2026-03-25T21:07:36.193047Z","steps":["trace[497679038] 'process raft request' (duration: 148.854949ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:07:36.193465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.457596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T21:07:36.193556Z","caller":"traceutil/trace.go:171","msg":"trace[2103410395] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:580; }","duration":"147.58084ms","start":"2026-03-25T21:07:36.045953Z","end":"2026-03-25T21:07:36.193533Z","steps":["trace[2103410395] 'agreement among raft nodes before linearized reading' (duration: 147.435566ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:07:36.193633Z","caller":"traceutil/trace.go:171","msg":"trace[2099266969] linearizableReadLoop","detail":"{readStateIndex:600; appliedIndex:599; }","duration":"147.007446ms","start":"2026-03-25T21:07:36.045977Z","end":"2026-03-25T21:07:36.192985Z","steps":["trace[2099266969] 'read index received' (duration: 141.774863ms)","trace[2099266969] 'applied index is now lower than readState.Index' (duration: 5.231123ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T21:07:36.1939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.811544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-03-25T21:07:36.194051Z","caller":"traceutil/trace.go:171","msg":"trace[1462855713] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:580; }","duration":"138.974847ms","start":"2026-03-25T21:07:36.055063Z","end":"2026-03-25T21:07:36.194038Z","steps":["trace[1462855713] 'agreement among raft nodes before linearized reading' (duration: 138.684691ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:07:36.194063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.204853ms","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-03-25T21:07:36.194115Z","caller":"traceutil/trace.go:171","msg":"trace[1813194895] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:580; }","duration":"136.265724ms","start":"2026-03-25T21:07:36.057834Z","end":"2026-03-25T21:07:36.194099Z","steps":["trace[1813194895] 'agreement among raft nodes before linearized reading' (duration: 136.11682ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:07:36.193936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.580443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-03-25T21:07:36.194225Z","caller":"traceutil/trace.go:171","msg":"trace[2075121692] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:580; }","duration":"122.912501ms","start":"2026-03-25T21:07:36.071289Z","end":"2026-03-25T21:07:36.194202Z","steps":["trace[2075121692] 'agreement among raft nodes before linearized reading' (duration: 122.481471ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:07:36.399555Z","caller":"traceutil/trace.go:171","msg":"trace[279056456] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"201.806211ms","start":"2026-03-25T21:07:36.197687Z","end":"2026-03-25T21:07:36.399493Z","steps":["trace[279056456] 'process raft request' (duration: 138.983958ms)","trace[279056456] 'compare' (duration: 62.588877ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:07:36.399714Z","caller":"traceutil/trace.go:171","msg":"trace[2061836899] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"199.037135ms","start":"2026-03-25T21:07:36.200652Z","end":"2026-03-25T21:07:36.399689Z","steps":["trace[2061836899] 'process raft request' (duration: 198.933143ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:07:36.399811Z","caller":"traceutil/trace.go:171","msg":"trace[553269780] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"200.691055ms","start":"2026-03-25T21:07:36.1991Z","end":"2026-03-25T21:07:36.399791Z","steps":["trace[553269780] 'process raft request' (duration: 200.332126ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:07:37.161666Z","caller":"traceutil/trace.go:171","msg":"trace[34643423] linearizableReadLoop","detail":"{readStateIndex:604; appliedIndex:603; }","duration":"487.854767ms","start":"2026-03-25T21:07:36.673789Z","end":"2026-03-25T21:07:37.161643Z","steps":["trace[34643423] 'read index received' (duration: 487.168191ms)","trace[34643423] 'applied index is now lower than readState.Index' (duration: 685.526µs)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:07:37.161773Z","caller":"traceutil/trace.go:171","msg":"trace[1020673585] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"553.492114ms","start":"2026-03-25T21:07:36.608217Z","end":"2026-03-25T21:07:37.161709Z","steps":["trace[1020673585] 'process raft request' (duration: 552.90251ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:07:37.1619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"488.093982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-cjp7g\" ","response":"range_response_count:1 size:16957"} {"level":"info","ts":"2026-03-25T21:07:37.161975Z","caller":"traceutil/trace.go:171","msg":"trace[1317719302] range","detail":"{range_begin:/registry/pods/kube-system/cilium-cjp7g; range_end:; response_count:1; response_revision:584; }","duration":"488.214415ms","start":"2026-03-25T21:07:36.673748Z","end":"2026-03-25T21:07:37.161963Z","steps":["trace[1317719302] 'agreement among raft nodes before linearized reading' (duration: 487.98098ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:07:37.162048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:07:36.673711Z","time spent":"488.326148ms","remote":"127.0.0.1:44318","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16980,"request content":"key:\"/registry/pods/kube-system/cilium-cjp7g\" "} {"level":"warn","ts":"2026-03-25T21:07:37.1643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:07:36.608181Z","time spent":"553.696929ms","remote":"127.0.0.1:55058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036313,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-25T21:07:37.908882Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14933264729494069775,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-25T21:07:38.189726Z","caller":"traceutil/trace.go:171","msg":"trace[1677606687] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"1.008042671s","start":"2026-03-25T21:07:37.181639Z","end":"2026-03-25T21:07:38.189682Z","steps":["trace[1677606687] 'process raft request' (duration: 911.483768ms)","trace[1677606687] 'compare' (duration: 96.305537ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T21:07:38.190022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:07:37.1816Z","time spent":"1.008215935s","remote":"127.0.0.1:44318","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17126,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-25T21:07:38.955177Z","caller":"traceutil/trace.go:171","msg":"trace[315170152] linearizableReadLoop","detail":"{readStateIndex:606; appliedIndex:604; }","duration":"1.547230357s","start":"2026-03-25T21:07:37.407911Z","end":"2026-03-25T21:07:38.955142Z","steps":["trace[315170152] 'read index received' (duration: 685.316845ms)","trace[315170152] 'applied index is now lower than readState.Index' (duration: 861.911842ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:07:38.955299Z","caller":"traceutil/trace.go:171","msg":"trace[778031658] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"1.724055781s","start":"2026-03-25T21:07:37.231207Z","end":"2026-03-25T21:07:38.955263Z","steps":["trace[778031658] 'process raft request' (duration: 1.723723734s)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:07:38.955488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:07:37.231178Z","time spent":"1.724228096s","remote":"127.0.0.1:44370","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-25T21:07:38.955578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.547583155s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-25T21:07:38.955625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.148435898s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.3\" ","response":"range_response_count:1 size:131"} {"level":"info","ts":"2026-03-25T21:07:38.9557Z","caller":"traceutil/trace.go:171","msg":"trace[1860303137] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:586; }","duration":"1.547821311s","start":"2026-03-25T21:07:37.407854Z","end":"2026-03-25T21:07:38.955675Z","steps":["trace[1860303137] 'agreement among raft nodes before linearized reading' (duration: 1.547458983s)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:07:38.955708Z","caller":"traceutil/trace.go:171","msg":"trace[453678289] range","detail":"{range_begin:/registry/masterleases/199.204.45.3; range_end:; response_count:1; response_revision:586; }","duration":"1.148575251s","start":"2026-03-25T21:07:37.807111Z","end":"2026-03-25T21:07:38.955686Z","steps":["trace[453678289] 'agreement among raft nodes before linearized reading' (duration: 1.148226273s)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:07:38.955712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"550.812749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-03-25T21:07:38.955763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:07:37.407815Z","time spent":"1.547938484s","remote":"127.0.0.1:54946","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-03-25T21:07:38.955772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:07:37.807093Z","time spent":"1.148663914s","remote":"127.0.0.1:54986","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":1,"response size":154,"request content":"key:\"/registry/masterleases/199.204.45.3\" "} {"level":"info","ts":"2026-03-25T21:07:38.955792Z","caller":"traceutil/trace.go:171","msg":"trace[2057155652] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:586; }","duration":"550.956473ms","start":"2026-03-25T21:07:38.404805Z","end":"2026-03-25T21:07:38.955761Z","steps":["trace[2057155652] 'agreement among raft nodes before linearized reading' (duration: 550.674056ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:07:38.955786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"546.339175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-03-25T21:07:38.955815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"758.275253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-03-25T21:07:38.95585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:07:38.404792Z","time spent":"551.047875ms","remote":"127.0.0.1:44388","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-25T21:07:38.955854Z","caller":"traceutil/trace.go:171","msg":"trace[971404766] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:586; }","duration":"546.459518ms","start":"2026-03-25T21:07:38.409373Z","end":"2026-03-25T21:07:38.955833Z","steps":["trace[971404766] 'agreement among raft nodes before linearized reading' (duration: 546.269513ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:07:38.955894Z","caller":"traceutil/trace.go:171","msg":"trace[1542633980] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:586; }","duration":"758.411326ms","start":"2026-03-25T21:07:38.197462Z","end":"2026-03-25T21:07:38.955873Z","steps":["trace[1542633980] 'agreement among raft nodes before linearized reading' (duration: 758.219901ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:07:38.9559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:07:38.40936Z","time spent":"546.532189ms","remote":"127.0.0.1:44388","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-25T21:07:38.955944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:07:38.197429Z","time spent":"758.502308ms","remote":"127.0.0.1:44388","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-25T21:07:38.956058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"552.000887ms","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-03-25T21:07:38.956495Z","caller":"traceutil/trace.go:171","msg":"trace[1250964722] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:586; }","duration":"552.51086ms","start":"2026-03-25T21:07:38.403963Z","end":"2026-03-25T21:07:38.956474Z","steps":["trace[1250964722] 'agreement among raft nodes before linearized reading' (duration: 551.6873ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:07:38.956607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:07:38.403901Z","time spent":"552.691794ms","remote":"127.0.0.1:44388","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-25T21:07:41.102635Z","caller":"traceutil/trace.go:171","msg":"trace[757414437] linearizableReadLoop","detail":"{readStateIndex:644; appliedIndex:643; }","duration":"106.269601ms","start":"2026-03-25T21:07:40.996338Z","end":"2026-03-25T21:07:41.102607Z","steps":["trace[757414437] 'read index received' (duration: 106.032436ms)","trace[757414437] 'applied index is now lower than readState.Index' (duration: 236.135µs)"],"step_count":2} {"level":"warn","ts":"2026-03-25T21:07:41.103166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.967774ms","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-03-25T21:07:41.103252Z","caller":"traceutil/trace.go:171","msg":"trace[1391196767] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:623; }","duration":"106.088817ms","start":"2026-03-25T21:07:40.997138Z","end":"2026-03-25T21:07:41.103227Z","steps":["trace[1391196767] 'agreement among raft nodes before linearized reading' (duration: 105.7751ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:07:41.10325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.874565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-03-25T21:07:41.103306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.65764ms","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-03-25T21:07:41.103347Z","caller":"traceutil/trace.go:171","msg":"trace[471070503] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:623; }","duration":"107.029849ms","start":"2026-03-25T21:07:40.996292Z","end":"2026-03-25T21:07:41.103322Z","steps":["trace[471070503] 'agreement among raft nodes before linearized reading' (duration: 106.666141ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:07:41.10336Z","caller":"traceutil/trace.go:171","msg":"trace[1679024450] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:623; }","duration":"106.731982ms","start":"2026-03-25T21:07:40.996613Z","end":"2026-03-25T21:07:41.103345Z","steps":["trace[1679024450] 'agreement among raft nodes before linearized reading' (duration: 106.413754ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:07:41.10325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.806034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-03-25T21:07:41.103541Z","caller":"traceutil/trace.go:171","msg":"trace[833470086] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:623; }","duration":"107.10344ms","start":"2026-03-25T21:07:40.996391Z","end":"2026-03-25T21:07:41.103495Z","steps":["trace[833470086] 'agreement among raft nodes before linearized reading' (duration: 106.509406ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:07:41.10411Z","caller":"traceutil/trace.go:171","msg":"trace[1347115644] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"169.722359ms","start":"2026-03-25T21:07:40.933111Z","end":"2026-03-25T21:07:41.102833Z","steps":["trace[1347115644] 'process raft request' (duration: 169.333679ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:07:52.826233Z","caller":"traceutil/trace.go:171","msg":"trace[1045045220] transaction","detail":"{read_only:false; response_revision:737; number_of_response:1; }","duration":"180.921821ms","start":"2026-03-25T21:07:52.645287Z","end":"2026-03-25T21:07:52.826209Z","steps":["trace[1045045220] 'process raft request' (duration: 180.709706ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:07:56.945476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.61381282s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-03-25T21:07:56.94556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.536654081s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-25T21:07:56.945637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.614155078s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-03-25T21:07:56.945661Z","caller":"traceutil/trace.go:171","msg":"trace[948493144] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:751; }","duration":"1.536809545s","start":"2026-03-25T21:07:55.408826Z","end":"2026-03-25T21:07:56.945636Z","steps":["trace[948493144] 'range keys from in-memory index tree' (duration: 1.536505757s)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:07:56.945712Z","caller":"traceutil/trace.go:171","msg":"trace[732299524] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:751; }","duration":"1.614266041s","start":"2026-03-25T21:07:55.331429Z","end":"2026-03-25T21:07:56.945695Z","steps":["trace[732299524] 'range keys from in-memory index tree' (duration: 1.613924443s)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:07:56.945718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:07:55.408802Z","time spent":"1.536903177s","remote":"127.0.0.1:54946","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-03-25T21:07:56.945753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:07:55.331407Z","time spent":"1.614335603s","remote":"127.0.0.1:44388","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-25T21:07:56.945771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.614272091s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-03-25T21:07:56.945838Z","caller":"traceutil/trace.go:171","msg":"trace[213364261] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:751; }","duration":"1.614373054s","start":"2026-03-25T21:07:55.331448Z","end":"2026-03-25T21:07:56.945821Z","steps":["trace[213364261] 'range keys from in-memory index tree' (duration: 1.614060066s)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:07:56.945879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:07:55.331436Z","time spent":"1.614432765s","remote":"127.0.0.1:44388","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-25T21:07:56.945527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.718379111s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-03-25T21:07:56.945976Z","caller":"traceutil/trace.go:171","msg":"trace[998875356] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:751; }","duration":"1.718867812s","start":"2026-03-25T21:07:55.227097Z","end":"2026-03-25T21:07:56.945965Z","steps":["trace[998875356] 'range keys from in-memory index tree' (duration: 1.718232387s)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:07:56.946112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"466.771701ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-25T21:07:56.946197Z","caller":"traceutil/trace.go:171","msg":"trace[171690018] linearizableReadLoop","detail":"{readStateIndex:777; appliedIndex:776; }","duration":"107.273505ms","start":"2026-03-25T21:07:56.838911Z","end":"2026-03-25T21:07:56.946184Z","steps":["trace[171690018] 'read index received' (duration: 60.552µs)","trace[171690018] 'applied index is now lower than readState.Index' (duration: 107.211933ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:07:56.945653Z","caller":"traceutil/trace.go:171","msg":"trace[2082349864] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:751; }","duration":"1.613999025s","start":"2026-03-25T21:07:55.331616Z","end":"2026-03-25T21:07:56.945615Z","steps":["trace[2082349864] 'range keys from in-memory index tree' (duration: 1.613687197s)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:07:56.946133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:07:55.227073Z","time spent":"1.719045947s","remote":"127.0.0.1:44388","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-25T21:07:56.947032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:07:55.33149Z","time spent":"1.61549793s","remote":"127.0.0.1:44388","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-25T21:07:56.947337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.402668ms","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-03-25T21:07:56.947076Z","caller":"traceutil/trace.go:171","msg":"trace[15373345] transaction","detail":"{read_only:false; response_revision:752; number_of_response:1; }","duration":"1.147645622s","start":"2026-03-25T21:07:55.799402Z","end":"2026-03-25T21:07:56.947048Z","steps":["trace[15373345] 'process raft request' (duration: 679.687003ms)","trace[15373345] 'compare' (duration: 466.089936ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T21:07:56.947473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:07:55.799376Z","time spent":"1.148065353s","remote":"127.0.0.1:44370","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-25T21:07:56.947404Z","caller":"traceutil/trace.go:171","msg":"trace[1037070680] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:752; }","duration":"108.524324ms","start":"2026-03-25T21:07:56.838863Z","end":"2026-03-25T21:07:56.947387Z","steps":["trace[1037070680] 'agreement among raft nodes before linearized reading' (duration: 107.365787ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:08:19.611589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.568129ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-25T21:08:19.611765Z","caller":"traceutil/trace.go:171","msg":"trace[1395440919] linearizableReadLoop","detail":"{readStateIndex:962; appliedIndex:960; }","duration":"202.343003ms","start":"2026-03-25T21:08:19.409405Z","end":"2026-03-25T21:08:19.611748Z","steps":["trace[1395440919] 'read index received' (duration: 95.505469ms)","trace[1395440919] 'applied index is now lower than readState.Index' (duration: 106.836034ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T21:08:19.611832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.423935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T21:08:19.611864Z","caller":"traceutil/trace.go:171","msg":"trace[1350379058] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:930; }","duration":"202.475997ms","start":"2026-03-25T21:08:19.409378Z","end":"2026-03-25T21:08:19.611854Z","steps":["trace[1350379058] 'agreement among raft nodes before linearized reading' (duration: 202.425046ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:08:19.611834Z","caller":"traceutil/trace.go:171","msg":"trace[1817730493] transaction","detail":"{read_only:false; response_revision:929; number_of_response:1; }","duration":"206.209754ms","start":"2026-03-25T21:08:19.405578Z","end":"2026-03-25T21:08:19.611788Z","steps":["trace[1817730493] 'process raft request' (duration: 99.319268ms)","trace[1817730493] 'compare' (duration: 106.452226ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:08:19.611833Z","caller":"traceutil/trace.go:171","msg":"trace[1956506208] transaction","detail":"{read_only:false; response_revision:930; number_of_response:1; }","duration":"206.106981ms","start":"2026-03-25T21:08:19.405681Z","end":"2026-03-25T21:08:19.611788Z","steps":["trace[1956506208] 'process raft request' (duration: 206.012079ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:08:25.418803Z","caller":"traceutil/trace.go:171","msg":"trace[320908052] linearizableReadLoop","detail":"{readStateIndex:979; appliedIndex:978; }","duration":"145.399038ms","start":"2026-03-25T21:08:25.27338Z","end":"2026-03-25T21:08:25.418779Z","steps":["trace[320908052] 'read index received' (duration: 145.258895ms)","trace[320908052] 'applied index is now lower than readState.Index' (duration: 139.523µs)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:08:25.418886Z","caller":"traceutil/trace.go:171","msg":"trace[1052324050] transaction","detail":"{read_only:false; response_revision:946; number_of_response:1; }","duration":"317.411191ms","start":"2026-03-25T21:08:25.101469Z","end":"2026-03-25T21:08:25.41888Z","steps":["trace[1052324050] 'process raft request' (duration: 317.212866ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:08:25.418971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:08:25.101433Z","time spent":"317.471772ms","remote":"127.0.0.1:44388","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-25T21:08:25.419259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.866739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-03-25T21:08:25.419327Z","caller":"traceutil/trace.go:171","msg":"trace[1294210901] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:946; }","duration":"145.964392ms","start":"2026-03-25T21:08:25.273343Z","end":"2026-03-25T21:08:25.419307Z","steps":["trace[1294210901] 'agreement among raft nodes before linearized reading' (duration: 145.813208ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:08:27.644822Z","caller":"traceutil/trace.go:171","msg":"trace[1619143578] transaction","detail":"{read_only:false; response_revision:952; number_of_response:1; }","duration":"219.720301ms","start":"2026-03-25T21:08:27.425073Z","end":"2026-03-25T21:08:27.644794Z","steps":["trace[1619143578] 'process raft request' (duration: 219.559607ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:08:27.645012Z","caller":"traceutil/trace.go:171","msg":"trace[1364881829] linearizableReadLoop","detail":"{readStateIndex:985; appliedIndex:984; }","duration":"176.274412ms","start":"2026-03-25T21:08:27.468726Z","end":"2026-03-25T21:08:27.645Z","steps":["trace[1364881829] 'read index received' (duration: 175.838902ms)","trace[1364881829] 'applied index is now lower than readState.Index' (duration: 434.34µs)"],"step_count":2} {"level":"warn","ts":"2026-03-25T21:08:27.645232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.482967ms","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-03-25T21:08:27.64531Z","caller":"traceutil/trace.go:171","msg":"trace[1300515114] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:952; }","duration":"176.592379ms","start":"2026-03-25T21:08:27.468699Z","end":"2026-03-25T21:08:27.645291Z","steps":["trace[1300515114] 'agreement among raft nodes before linearized reading' (duration: 176.392715ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:08:27.646348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.168857ms","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-03-25T21:08:27.646426Z","caller":"traceutil/trace.go:171","msg":"trace[130093214] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:952; }","duration":"175.262279ms","start":"2026-03-25T21:08:27.471152Z","end":"2026-03-25T21:08:27.646414Z","steps":["trace[130093214] 'agreement among raft nodes before linearized reading' (duration: 175.106995ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:08:28.162152Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14933264729494070515,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-25T21:08:28.663629Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14933264729494070515,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-25T21:08:29.164563Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14933264729494070515,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-25T21:08:29.184274Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.535011233s","expected-duration":"1s"} {"level":"warn","ts":"2026-03-25T21:08:29.319387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.832301ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-25T21:08:29.319861Z","caller":"traceutil/trace.go:171","msg":"trace[1754463968] linearizableReadLoop","detail":"{readStateIndex:988; appliedIndex:985; }","duration":"1.658672802s","start":"2026-03-25T21:08:27.66117Z","end":"2026-03-25T21:08:29.319843Z","steps":["trace[1754463968] 'read index received' (duration: 1.523407751s)","trace[1754463968] 'applied index is now lower than readState.Index' (duration: 135.264171ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:08:29.319974Z","caller":"traceutil/trace.go:171","msg":"trace[1808531675] transaction","detail":"{read_only:false; response_revision:954; number_of_response:1; }","duration":"1.670145651s","start":"2026-03-25T21:08:27.649818Z","end":"2026-03-25T21:08:29.319964Z","steps":["trace[1808531675] 'process raft request' (duration: 1.669772492s)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:08:29.320079Z","caller":"traceutil/trace.go:171","msg":"trace[942397138] transaction","detail":"{read_only:false; response_revision:953; number_of_response:1; }","duration":"1.670868928s","start":"2026-03-25T21:08:27.649183Z","end":"2026-03-25T21:08:29.320052Z","steps":["trace[942397138] 'process raft request' (duration: 1.535275379s)","trace[942397138] 'compare' (duration: 134.669697ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T21:08:29.320124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:08:27.649793Z","time spent":"1.670283423s","remote":"127.0.0.1:44388","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-03-25T21:08:29.320207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:08:27.649163Z","time spent":"1.67097197s","remote":"127.0.0.1:44388","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-03-25T21:08:29.320389Z","caller":"traceutil/trace.go:171","msg":"trace[304842261] transaction","detail":"{read_only:false; response_revision:955; number_of_response:1; }","duration":"1.457501215s","start":"2026-03-25T21:08:27.862877Z","end":"2026-03-25T21:08:29.320378Z","steps":["trace[304842261] 'process raft request' (duration: 1.456921602s)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:08:29.320478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:08:27.862857Z","time spent":"1.457576238s","remote":"127.0.0.1:44388","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-25T21:08:29.320725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.659559332s","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-03-25T21:08:29.320774Z","caller":"traceutil/trace.go:171","msg":"trace[145802764] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:955; }","duration":"1.659635074s","start":"2026-03-25T21:08:27.661125Z","end":"2026-03-25T21:08:29.32076Z","steps":["trace[145802764] 'agreement among raft nodes before linearized reading' (duration: 1.659497141s)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:08:29.320817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:08:27.661112Z","time spent":"1.659690715s","remote":"127.0.0.1:44388","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-25T21:08:29.320835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.65306378s","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-03-25T21:08:29.320908Z","caller":"traceutil/trace.go:171","msg":"trace[1445334793] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:955; }","duration":"1.653170932s","start":"2026-03-25T21:08:27.667717Z","end":"2026-03-25T21:08:29.320888Z","steps":["trace[1445334793] 'agreement among raft nodes before linearized reading' (duration: 1.652976818s)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:08:29.320905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"529.300017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-03-25T21:08:29.320948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:08:27.667702Z","time spent":"1.653237704s","remote":"127.0.0.1:44388","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-25T21:08:29.320968Z","caller":"traceutil/trace.go:171","msg":"trace[1935859455] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:955; }","duration":"529.39581ms","start":"2026-03-25T21:08:28.791556Z","end":"2026-03-25T21:08:29.320952Z","steps":["trace[1935859455] 'agreement among raft nodes before linearized reading' (duration: 529.281867ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:08:29.321009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:08:28.791541Z","time spent":"529.457341ms","remote":"127.0.0.1:44370","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":1,"response size":30,"request content":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true "} {"level":"warn","ts":"2026-03-25T21:08:29.321034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.511310847s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.3\" ","response":"range_response_count:1 size:131"} {"level":"info","ts":"2026-03-25T21:08:29.321072Z","caller":"traceutil/trace.go:171","msg":"trace[1848466143] range","detail":"{range_begin:/registry/masterleases/199.204.45.3; range_end:; response_count:1; response_revision:955; }","duration":"1.511388238s","start":"2026-03-25T21:08:27.809673Z","end":"2026-03-25T21:08:29.321061Z","steps":["trace[1848466143] 'agreement among raft nodes before linearized reading' (duration: 1.511221104s)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:08:29.321119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:08:27.809647Z","time spent":"1.511464531s","remote":"127.0.0.1:54986","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":1,"response size":154,"request content":"key:\"/registry/masterleases/199.204.45.3\" "} {"level":"warn","ts":"2026-03-25T21:08:35.852605Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14933264729494070588,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-25T21:08:36.353089Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14933264729494070588,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-25T21:08:36.853781Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14933264729494070588,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-25T21:08:37.039001Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.689265978s","expected-duration":"1s"} {"level":"info","ts":"2026-03-25T21:08:37.039359Z","caller":"traceutil/trace.go:171","msg":"trace[1285884064] linearizableReadLoop","detail":"{readStateIndex:1013; appliedIndex:1012; }","duration":"1.686821601s","start":"2026-03-25T21:08:35.352513Z","end":"2026-03-25T21:08:37.039335Z","steps":["trace[1285884064] 'read index received' (duration: 1.686667978s)","trace[1285884064] 'applied index is now lower than readState.Index' (duration: 152.273µs)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:08:37.039442Z","caller":"traceutil/trace.go:171","msg":"trace[32273367] transaction","detail":"{read_only:false; response_revision:978; number_of_response:1; }","duration":"1.68978091s","start":"2026-03-25T21:08:35.349618Z","end":"2026-03-25T21:08:37.039399Z","steps":["trace[32273367] 'process raft request' (duration: 1.689574435s)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:08:37.039611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.958272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-03-25T21:08:37.039637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.686574265s","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-03-25T21:08:37.039668Z","caller":"traceutil/trace.go:171","msg":"trace[608756449] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:978; }","duration":"213.073415ms","start":"2026-03-25T21:08:36.82658Z","end":"2026-03-25T21:08:37.039653Z","steps":["trace[608756449] 'agreement among raft nodes before linearized reading' (duration: 212.951842ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:08:37.03966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"624.073299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-25T21:08:37.039666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.616555914s","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-03-25T21:08:37.039716Z","caller":"traceutil/trace.go:171","msg":"trace[825988758] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:978; }","duration":"1.616607435s","start":"2026-03-25T21:08:35.423099Z","end":"2026-03-25T21:08:37.039707Z","steps":["trace[825988758] 'agreement among raft nodes before linearized reading' (duration: 1.616480052s)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:08:37.039716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.61681643s","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":"warn","ts":"2026-03-25T21:08:37.039714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:08:35.349596Z","time spent":"1.689985865s","remote":"127.0.0.1:44388","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-25T21:08:37.039723Z","caller":"traceutil/trace.go:171","msg":"trace[705744686] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:978; }","duration":"624.223762ms","start":"2026-03-25T21:08:36.415481Z","end":"2026-03-25T21:08:37.039705Z","steps":["trace[705744686] 'agreement among raft nodes before linearized reading' (duration: 624.089749ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:08:37.039739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:08:35.423056Z","time spent":"1.616676997s","remote":"127.0.0.1:44388","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-25T21:08:37.039759Z","caller":"traceutil/trace.go:171","msg":"trace[1114272176] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:978; }","duration":"1.616875141s","start":"2026-03-25T21:08:35.42287Z","end":"2026-03-25T21:08:37.039745Z","steps":["trace[1114272176] 'agreement among raft nodes before linearized reading' (duration: 1.616686306s)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:08:37.039787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:08:35.422861Z","time spent":"1.616922122s","remote":"127.0.0.1:44388","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-25T21:08:37.039788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.62153664s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-25T21:08:37.039796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:08:36.415461Z","time spent":"624.323534ms","remote":"127.0.0.1:38908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"warn","ts":"2026-03-25T21:08:37.039835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.069867369s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T21:08:37.039823Z","caller":"traceutil/trace.go:171","msg":"trace[1362407714] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:978; }","duration":"1.621649543s","start":"2026-03-25T21:08:35.418164Z","end":"2026-03-25T21:08:37.039814Z","steps":["trace[1362407714] 'agreement among raft nodes before linearized reading' (duration: 1.621533851s)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:08:37.039899Z","caller":"traceutil/trace.go:171","msg":"trace[1487218281] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:978; }","duration":"1.06995896s","start":"2026-03-25T21:08:35.969933Z","end":"2026-03-25T21:08:37.039892Z","steps":["trace[1487218281] 'agreement among raft nodes before linearized reading' (duration: 1.069883569s)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:08:37.039919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:08:35.969898Z","time spent":"1.070015182s","remote":"127.0.0.1:38870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"warn","ts":"2026-03-25T21:08:37.039926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:08:35.418155Z","time spent":"1.621757146s","remote":"127.0.0.1:39038","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true "} {"level":"info","ts":"2026-03-25T21:08:37.039677Z","caller":"traceutil/trace.go:171","msg":"trace[544871928] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:978; }","duration":"1.686647107s","start":"2026-03-25T21:08:35.353021Z","end":"2026-03-25T21:08:37.039668Z","steps":["trace[544871928] 'agreement among raft nodes before linearized reading' (duration: 1.686469273s)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:08:37.039969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:08:35.353016Z","time spent":"1.686946054s","remote":"127.0.0.1:44388","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-03-25T21:08:37.039962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.631449893s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T21:08:37.040016Z","caller":"traceutil/trace.go:171","msg":"trace[1631589722] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:978; }","duration":"1.631533135s","start":"2026-03-25T21:08:35.408467Z","end":"2026-03-25T21:08:37.04Z","steps":["trace[1631589722] 'agreement among raft nodes before linearized reading' (duration: 1.631438153s)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:08:37.040049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:08:35.408454Z","time spent":"1.631588236s","remote":"127.0.0.1:54946","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-03-25T21:08:37.040419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.275671792s","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-03-25T21:08:37.040451Z","caller":"traceutil/trace.go:171","msg":"trace[679124017] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:978; }","duration":"1.275731974s","start":"2026-03-25T21:08:35.76471Z","end":"2026-03-25T21:08:37.040442Z","steps":["trace[679124017] 'agreement among raft nodes before linearized reading' (duration: 1.274789842s)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:08:37.040471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:08:35.764689Z","time spent":"1.275777576s","remote":"127.0.0.1:44388","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-03-25T21:08:37.04088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.688367407s","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-03-25T21:08:37.04092Z","caller":"traceutil/trace.go:171","msg":"trace[1251035139] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:978; }","duration":"1.688437999s","start":"2026-03-25T21:08:35.352474Z","end":"2026-03-25T21:08:37.040912Z","steps":["trace[1251035139] 'agreement among raft nodes before linearized reading' (duration: 1.686989305s)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:08:37.040959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:08:35.352459Z","time spent":"1.68849128s","remote":"127.0.0.1:44388","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-25T21:08:37.359061Z","caller":"traceutil/trace.go:171","msg":"trace[1833706918] transaction","detail":"{read_only:false; response_revision:979; number_of_response:1; }","duration":"315.598839ms","start":"2026-03-25T21:08:37.043445Z","end":"2026-03-25T21:08:37.359044Z","steps":["trace[1833706918] 'process raft request' (duration: 304.441287ms)","trace[1833706918] 'compare' (duration: 10.817904ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T21:08:37.359168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:08:37.043431Z","time spent":"315.6773ms","remote":"127.0.0.1:44388","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-25T21:08:37.359303Z","caller":"traceutil/trace.go:171","msg":"trace[1049019206] transaction","detail":"{read_only:false; response_revision:980; number_of_response:1; }","duration":"315.712831ms","start":"2026-03-25T21:08:37.043561Z","end":"2026-03-25T21:08:37.359273Z","steps":["trace[1049019206] 'process raft request' (duration: 315.433095ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:08:37.359451Z","caller":"traceutil/trace.go:171","msg":"trace[666604617] transaction","detail":"{read_only:false; response_revision:981; number_of_response:1; }","duration":"315.165648ms","start":"2026-03-25T21:08:37.044279Z","end":"2026-03-25T21:08:37.359444Z","steps":["trace[666604617] 'process raft request' (duration: 314.936513ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:08:37.359471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:08:37.043539Z","time spent":"315.834595ms","remote":"127.0.0.1:44388","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-03-25T21:08:37.359487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:08:37.04427Z","time spent":"315.196919ms","remote":"127.0.0.1:44388","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-03-25T21:08:37.35953Z","caller":"traceutil/trace.go:171","msg":"trace[815218201] linearizableReadLoop","detail":"{readStateIndex:1018; appliedIndex:1013; }","duration":"313.068119ms","start":"2026-03-25T21:08:37.04641Z","end":"2026-03-25T21:08:37.359479Z","steps":["trace[815218201] 'read index received' (duration: 301.493068ms)","trace[815218201] 'applied index is now lower than readState.Index' (duration: 11.574101ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:08:37.359622Z","caller":"traceutil/trace.go:171","msg":"trace[863976532] transaction","detail":"{read_only:false; response_revision:982; number_of_response:1; }","duration":"315.152578ms","start":"2026-03-25T21:08:37.044464Z","end":"2026-03-25T21:08:37.359617Z","steps":["trace[863976532] 'process raft request' (duration: 314.923773ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:08:37.359651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:08:37.044456Z","time spent":"315.178639ms","remote":"127.0.0.1:44388","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-03-25T21:08:37.359689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.275954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T21:08:37.359722Z","caller":"traceutil/trace.go:171","msg":"trace[1476819673] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:983; }","duration":"313.364586ms","start":"2026-03-25T21:08:37.046347Z","end":"2026-03-25T21:08:37.359711Z","steps":["trace[1476819673] 'agreement among raft nodes before linearized reading' (duration: 313.248224ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:08:37.359767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:08:37.04632Z","time spent":"313.439718ms","remote":"127.0.0.1:54946","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-03-25T21:08:37.359846Z","caller":"traceutil/trace.go:171","msg":"trace[1340883584] transaction","detail":"{read_only:false; response_revision:983; number_of_response:1; }","duration":"315.331872ms","start":"2026-03-25T21:08:37.044507Z","end":"2026-03-25T21:08:37.359839Z","steps":["trace[1340883584] 'process raft request' (duration: 314.932073ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:08:37.359878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:08:37.044503Z","time spent":"315.357513ms","remote":"127.0.0.1:44388","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-03-25T21:08:39.230407Z","caller":"traceutil/trace.go:171","msg":"trace[1110011110] transaction","detail":"{read_only:false; response_revision:985; number_of_response:1; }","duration":"185.483928ms","start":"2026-03-25T21:08:39.044899Z","end":"2026-03-25T21:08:39.230383Z","steps":["trace[1110011110] 'process raft request' (duration: 185.270793ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:08:39.538589Z","caller":"traceutil/trace.go:171","msg":"trace[2027071508] linearizableReadLoop","detail":"{readStateIndex:1026; appliedIndex:1021; }","duration":"130.09644ms","start":"2026-03-25T21:08:39.40847Z","end":"2026-03-25T21:08:39.538566Z","steps":["trace[2027071508] 'read index received' (duration: 28.740724ms)","trace[2027071508] 'applied index is now lower than readState.Index' (duration: 101.354706ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:08:39.538651Z","caller":"traceutil/trace.go:171","msg":"trace[1221273583] transaction","detail":"{read_only:false; response_revision:989; number_of_response:1; }","duration":"162.171702ms","start":"2026-03-25T21:08:39.376434Z","end":"2026-03-25T21:08:39.538605Z","steps":["trace[1221273583] 'process raft request' (duration: 94.200929ms)","trace[1221273583] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/leases/kube-system/cilium-operator-resource-lock; req_size:474; } (duration: 67.743328ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:08:39.538666Z","caller":"traceutil/trace.go:171","msg":"trace[1439462191] transaction","detail":"{read_only:false; response_revision:990; number_of_response:1; }","duration":"160.595995ms","start":"2026-03-25T21:08:39.378038Z","end":"2026-03-25T21:08:39.538634Z","steps":["trace[1439462191] 'process raft request' (duration: 160.472842ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:08:39.538761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.307765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T21:08:39.538849Z","caller":"traceutil/trace.go:171","msg":"trace[772037178] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:990; }","duration":"130.434108ms","start":"2026-03-25T21:08:39.408393Z","end":"2026-03-25T21:08:39.538827Z","steps":["trace[772037178] 'agreement among raft nodes before linearized reading' (duration: 130.269754ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:08:59.908892Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14933264729494070783,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-25T21:08:59.948658Z","caller":"traceutil/trace.go:171","msg":"trace[955958357] linearizableReadLoop","detail":"{readStateIndex:1091; appliedIndex:1090; }","duration":"539.855206ms","start":"2026-03-25T21:08:59.408778Z","end":"2026-03-25T21:08:59.948633Z","steps":["trace[955958357] 'read index received' (duration: 539.59152ms)","trace[955958357] 'applied index is now lower than readState.Index' (duration: 262.406µs)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:08:59.948787Z","caller":"traceutil/trace.go:171","msg":"trace[1428096265] transaction","detail":"{read_only:false; response_revision:1051; number_of_response:1; }","duration":"615.267963ms","start":"2026-03-25T21:08:59.333483Z","end":"2026-03-25T21:08:59.948751Z","steps":["trace[1428096265] 'process raft request' (duration: 614.962325ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:08:59.948826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"540.01643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T21:08:59.948869Z","caller":"traceutil/trace.go:171","msg":"trace[1790159242] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1051; }","duration":"540.133533ms","start":"2026-03-25T21:08:59.408723Z","end":"2026-03-25T21:08:59.948856Z","steps":["trace[1790159242] 'agreement among raft nodes before linearized reading' (duration: 540.032591ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:08:59.948904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:08:59.408702Z","time spent":"540.194434ms","remote":"127.0.0.1:54946","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-03-25T21:08:59.948935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.784293ms","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-03-25T21:08:59.948993Z","caller":"traceutil/trace.go:171","msg":"trace[774521009] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1051; }","duration":"419.885406ms","start":"2026-03-25T21:08:59.529088Z","end":"2026-03-25T21:08:59.948973Z","steps":["trace[774521009] 'agreement among raft nodes before linearized reading' (duration: 419.717792ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:08:59.949026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:08:59.529066Z","time spent":"419.953318ms","remote":"127.0.0.1:44388","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-03-25T21:08:59.94906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.548331ms","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-03-25T21:08:59.94912Z","caller":"traceutil/trace.go:171","msg":"trace[1523222296] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:1051; }","duration":"312.653974ms","start":"2026-03-25T21:08:59.636449Z","end":"2026-03-25T21:08:59.949103Z","steps":["trace[1523222296] 'agreement among raft nodes before linearized reading' (duration: 312.518241ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:08:59.949165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:08:59.636397Z","time spent":"312.753536ms","remote":"127.0.0.1:38978","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true "} {"level":"warn","ts":"2026-03-25T21:08:59.949149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.644882ms","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":"warn","ts":"2026-03-25T21:08:59.949226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.32944ms","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-03-25T21:08:59.949294Z","caller":"traceutil/trace.go:171","msg":"trace[1414129003] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1051; }","duration":"250.810095ms","start":"2026-03-25T21:08:59.698467Z","end":"2026-03-25T21:08:59.949277Z","steps":["trace[1414129003] 'agreement among raft nodes before linearized reading' (duration: 250.548049ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:08:59.949315Z","caller":"traceutil/trace.go:171","msg":"trace[1504608008] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1051; }","duration":"386.518454ms","start":"2026-03-25T21:08:59.562773Z","end":"2026-03-25T21:08:59.949291Z","steps":["trace[1504608008] 'agreement among raft nodes before linearized reading' (duration: 386.213167ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:08:59.949321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.308062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T21:08:59.949357Z","caller":"traceutil/trace.go:171","msg":"trace[1532469177] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:1051; }","duration":"413.381643ms","start":"2026-03-25T21:08:59.535965Z","end":"2026-03-25T21:08:59.949347Z","steps":["trace[1532469177] 'agreement among raft nodes before linearized reading' (duration: 413.303452ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:08:59.949363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:08:59.562747Z","time spent":"386.606097ms","remote":"127.0.0.1:44388","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-03-25T21:08:59.949386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:08:59.535954Z","time spent":"413.424204ms","remote":"127.0.0.1:44374","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":28,"request content":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true "} {"level":"warn","ts":"2026-03-25T21:08:59.949469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.847218ms","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-03-25T21:08:59.949505Z","caller":"traceutil/trace.go:171","msg":"trace[1048790538] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1051; }","duration":"385.944131ms","start":"2026-03-25T21:08:59.563551Z","end":"2026-03-25T21:08:59.949495Z","steps":["trace[1048790538] 'agreement among raft nodes before linearized reading' (duration: 385.800618ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:08:59.949533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:08:59.563537Z","time spent":"385.989662ms","remote":"127.0.0.1:44388","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-03-25T21:08:59.949558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.092379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-25T21:08:59.949595Z","caller":"traceutil/trace.go:171","msg":"trace[1033297958] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1051; }","duration":"148.170131ms","start":"2026-03-25T21:08:59.801413Z","end":"2026-03-25T21:08:59.949583Z","steps":["trace[1033297958] 'agreement among raft nodes before linearized reading' (duration: 148.10799ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:08:59.948963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:08:59.333456Z","time spent":"615.398066ms","remote":"127.0.0.1:44388","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-25T21:08:59.949739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.088632ms","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-03-25T21:08:59.949774Z","caller":"traceutil/trace.go:171","msg":"trace[1189151943] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1051; }","duration":"263.154504ms","start":"2026-03-25T21:08:59.68661Z","end":"2026-03-25T21:08:59.949765Z","steps":["trace[1189151943] 'agreement among raft nodes before linearized reading' (duration: 263.061032ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:09:00.091401Z","caller":"traceutil/trace.go:171","msg":"trace[1912399277] transaction","detail":"{read_only:false; response_revision:1052; number_of_response:1; }","duration":"136.796795ms","start":"2026-03-25T21:08:59.954581Z","end":"2026-03-25T21:09:00.091378Z","steps":["trace[1912399277] 'process raft request' (duration: 109.103616ms)","trace[1912399277] 'compare' (duration: 27.599586ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:09:00.137015Z","caller":"traceutil/trace.go:171","msg":"trace[345215927] transaction","detail":"{read_only:false; response_revision:1053; number_of_response:1; }","duration":"182.345282ms","start":"2026-03-25T21:08:59.954633Z","end":"2026-03-25T21:09:00.136978Z","steps":["trace[345215927] 'process raft request' (duration: 181.941622ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:09:00.137072Z","caller":"traceutil/trace.go:171","msg":"trace[1446646396] transaction","detail":"{read_only:false; response_revision:1056; number_of_response:1; }","duration":"181.761718ms","start":"2026-03-25T21:08:59.955273Z","end":"2026-03-25T21:09:00.137034Z","steps":["trace[1446646396] 'process raft request' (duration: 181.685996ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:09:00.137161Z","caller":"traceutil/trace.go:171","msg":"trace[1083423122] transaction","detail":"{read_only:false; response_revision:1055; number_of_response:1; }","duration":"182.108906ms","start":"2026-03-25T21:08:59.955038Z","end":"2026-03-25T21:09:00.137147Z","steps":["trace[1083423122] 'process raft request' (duration: 181.880561ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:09:00.137074Z","caller":"traceutil/trace.go:171","msg":"trace[717444322] transaction","detail":"{read_only:false; response_revision:1054; number_of_response:1; }","duration":"182.390423ms","start":"2026-03-25T21:08:59.954633Z","end":"2026-03-25T21:09:00.137024Z","steps":["trace[717444322] 'process raft request' (duration: 182.233859ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:09:02.59465Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14933264729494070795,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-25T21:09:02.670943Z","caller":"traceutil/trace.go:171","msg":"trace[204662258] transaction","detail":"{read_only:false; response_revision:1057; number_of_response:1; }","duration":"714.935538ms","start":"2026-03-25T21:09:01.95599Z","end":"2026-03-25T21:09:02.670925Z","steps":["trace[204662258] 'process raft request' (duration: 714.818945ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:09:02.671053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:09:01.955962Z","time spent":"715.03191ms","remote":"127.0.0.1:44388","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-25T21:09:02.897648Z","caller":"traceutil/trace.go:171","msg":"trace[869506429] linearizableReadLoop","detail":"{readStateIndex:1099; appliedIndex:1096; }","duration":"803.165684ms","start":"2026-03-25T21:09:02.094458Z","end":"2026-03-25T21:09:02.897624Z","steps":["trace[869506429] 'read index received' (duration: 576.422413ms)","trace[869506429] 'applied index is now lower than readState.Index' (duration: 226.742491ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:09:02.897813Z","caller":"traceutil/trace.go:171","msg":"trace[560191390] transaction","detail":"{read_only:false; response_revision:1059; number_of_response:1; }","duration":"720.529669ms","start":"2026-03-25T21:09:02.177226Z","end":"2026-03-25T21:09:02.897756Z","steps":["trace[560191390] 'process raft request' (duration: 720.360755ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:09:02.897893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"803.41172ms","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-03-25T21:09:02.897927Z","caller":"traceutil/trace.go:171","msg":"trace[1667967279] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1059; }","duration":"803.505142ms","start":"2026-03-25T21:09:02.094413Z","end":"2026-03-25T21:09:02.897918Z","steps":["trace[1667967279] 'agreement among raft nodes before linearized reading' (duration: 803.334618ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:09:02.897905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"755.57004ms","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":"warn","ts":"2026-03-25T21:09:02.897956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:09:02.0944Z","time spent":"803.549373ms","remote":"127.0.0.1:44388","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-25T21:09:02.897744Z","caller":"traceutil/trace.go:171","msg":"trace[533369769] transaction","detail":"{read_only:false; response_revision:1058; number_of_response:1; }","duration":"820.998472ms","start":"2026-03-25T21:09:02.076707Z","end":"2026-03-25T21:09:02.897705Z","steps":["trace[533369769] 'process raft request' (duration: 820.719766ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:09:02.898057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:09:02.177208Z","time spent":"720.724183ms","remote":"127.0.0.1:44388","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-25T21:09:02.898065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"755.302823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-03-25T21:09:02.898126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"755.646681ms","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-03-25T21:09:02.898169Z","caller":"traceutil/trace.go:171","msg":"trace[1952282205] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1059; }","duration":"755.457097ms","start":"2026-03-25T21:09:02.142684Z","end":"2026-03-25T21:09:02.898141Z","steps":["trace[1952282205] 'agreement among raft nodes before linearized reading' (duration: 755.16218ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:09:02.898161Z","caller":"traceutil/trace.go:171","msg":"trace[399878886] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1059; }","duration":"755.699043ms","start":"2026-03-25T21:09:02.142452Z","end":"2026-03-25T21:09:02.898151Z","steps":["trace[399878886] 'agreement among raft nodes before linearized reading' (duration: 755.57737ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:09:02.898223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:09:02.142445Z","time spent":"755.769524ms","remote":"127.0.0.1:44388","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-03-25T21:09:02.898219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:09:02.076684Z","time spent":"821.382291ms","remote":"127.0.0.1:44388","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-25T21:09:02.898249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"755.824505ms","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-03-25T21:09:02.898277Z","caller":"traceutil/trace.go:171","msg":"trace[214422335] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1059; }","duration":"755.873837ms","start":"2026-03-25T21:09:02.142396Z","end":"2026-03-25T21:09:02.89827Z","steps":["trace[214422335] 'agreement among raft nodes before linearized reading' (duration: 755.808355ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:09:02.898296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:09:02.142382Z","time spent":"755.909367ms","remote":"127.0.0.1:44388","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":"info","ts":"2026-03-25T21:09:02.89798Z","caller":"traceutil/trace.go:171","msg":"trace[2106424696] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1059; }","duration":"755.703882ms","start":"2026-03-25T21:09:02.142264Z","end":"2026-03-25T21:09:02.897968Z","steps":["trace[2106424696] 'agreement among raft nodes before linearized reading' (duration: 755.517168ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:09:02.898328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:09:02.142247Z","time spent":"756.075321ms","remote":"127.0.0.1:44388","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-25T21:09:02.898232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:09:02.142664Z","time spent":"755.555419ms","remote":"127.0.0.1:44388","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-25T21:09:03.256811Z","caller":"traceutil/trace.go:171","msg":"trace[1925214573] transaction","detail":"{read_only:false; response_revision:1060; number_of_response:1; }","duration":"354.826761ms","start":"2026-03-25T21:09:02.901944Z","end":"2026-03-25T21:09:03.256771Z","steps":["trace[1925214573] 'process raft request' (duration: 293.178437ms)","trace[1925214573] 'compare' (duration: 61.369718ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T21:09:03.256982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:09:02.901905Z","time spent":"354.991905ms","remote":"127.0.0.1:44388","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-03-25T21:09:03.279179Z","caller":"traceutil/trace.go:171","msg":"trace[445743663] transaction","detail":"{read_only:false; response_revision:1061; number_of_response:1; }","duration":"375.850123ms","start":"2026-03-25T21:09:02.903305Z","end":"2026-03-25T21:09:03.279155Z","steps":["trace[445743663] 'process raft request' (duration: 375.68914ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:09:03.279349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:09:02.90329Z","time spent":"375.965007ms","remote":"127.0.0.1:44388","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-25T21:09:03.279455Z","caller":"traceutil/trace.go:171","msg":"trace[413253577] transaction","detail":"{read_only:false; response_revision:1062; number_of_response:1; }","duration":"376.033788ms","start":"2026-03-25T21:09:02.903402Z","end":"2026-03-25T21:09:03.279435Z","steps":["trace[413253577] 'process raft request' (duration: 375.71428ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:09:03.279552Z","caller":"traceutil/trace.go:171","msg":"trace[282722032] transaction","detail":"{read_only:false; response_revision:1064; number_of_response:1; }","duration":"375.171648ms","start":"2026-03-25T21:09:02.90436Z","end":"2026-03-25T21:09:03.279532Z","steps":["trace[282722032] 'process raft request' (duration: 375.063016ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:09:03.279588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:09:02.903375Z","time spent":"376.12522ms","remote":"127.0.0.1:44388","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-03-25T21:09:03.279648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:09:02.90435Z","time spent":"375.24953ms","remote":"127.0.0.1:44388","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-25T21:09:03.279662Z","caller":"traceutil/trace.go:171","msg":"trace[1152321140] transaction","detail":"{read_only:false; response_revision:1063; number_of_response:1; }","duration":"375.844834ms","start":"2026-03-25T21:09:02.903796Z","end":"2026-03-25T21:09:03.27964Z","steps":["trace[1152321140] 'process raft request' (duration: 375.530296ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:09:03.279762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:09:02.903787Z","time spent":"375.922515ms","remote":"127.0.0.1:44388","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-25T21:09:04.827484Z","caller":"traceutil/trace.go:171","msg":"trace[681536488] transaction","detail":"{read_only:false; response_revision:1065; number_of_response:1; }","duration":"150.229919ms","start":"2026-03-25T21:09:04.677233Z","end":"2026-03-25T21:09:04.827463Z","steps":["trace[681536488] 'process raft request' (duration: 150.085635ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:09:05.561655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.542931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-03-25T21:09:05.56175Z","caller":"traceutil/trace.go:171","msg":"trace[1876463444] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1065; }","duration":"277.674634ms","start":"2026-03-25T21:09:05.284056Z","end":"2026-03-25T21:09:05.561731Z","steps":["trace[1876463444] 'range keys from in-memory index tree' (duration: 277.369176ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:09:05.562135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.681164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-03-25T21:09:05.56219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.592151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-03-25T21:09:05.562226Z","caller":"traceutil/trace.go:171","msg":"trace[743747769] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1065; }","duration":"277.796576ms","start":"2026-03-25T21:09:05.284408Z","end":"2026-03-25T21:09:05.562205Z","steps":["trace[743747769] 'range keys from in-memory index tree' (duration: 277.51892ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:09:05.562246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.051126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-25T21:09:05.562259Z","caller":"traceutil/trace.go:171","msg":"trace[1163033072] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1065; }","duration":"277.689114ms","start":"2026-03-25T21:09:05.284552Z","end":"2026-03-25T21:09:05.562241Z","steps":["trace[1163033072] 'range keys from in-memory index tree' (duration: 277.456879ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:09:05.56229Z","caller":"traceutil/trace.go:171","msg":"trace[1190721526] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:1065; }","duration":"279.135288ms","start":"2026-03-25T21:09:05.283145Z","end":"2026-03-25T21:09:05.56228Z","steps":["trace[1190721526] 'count revisions from in-memory index tree' (duration: 278.947774ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:09:05.562304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.669913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-03-25T21:09:05.562422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.092926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-03-25T21:09:05.562435Z","caller":"traceutil/trace.go:171","msg":"trace[2056251199] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1065; }","duration":"277.856448ms","start":"2026-03-25T21:09:05.284553Z","end":"2026-03-25T21:09:05.562409Z","steps":["trace[2056251199] 'range keys from in-memory index tree' (duration: 277.460828ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:09:05.562453Z","caller":"traceutil/trace.go:171","msg":"trace[824642907] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1065; }","duration":"302.161707ms","start":"2026-03-25T21:09:05.260282Z","end":"2026-03-25T21:09:05.562444Z","steps":["trace[824642907] 'range keys from in-memory index tree' (duration: 301.977153ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:09:05.562563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:09:05.260261Z","time spent":"302.28905ms","remote":"127.0.0.1:44388","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-25T21:09:09.763433Z","caller":"traceutil/trace.go:171","msg":"trace[593495669] transaction","detail":"{read_only:false; response_revision:1081; number_of_response:1; }","duration":"101.498078ms","start":"2026-03-25T21:09:09.661909Z","end":"2026-03-25T21:09:09.763407Z","steps":["trace[593495669] 'process raft request' (duration: 101.354285ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:09:09.763654Z","caller":"traceutil/trace.go:171","msg":"trace[682891122] transaction","detail":"{read_only:false; response_revision:1082; number_of_response:1; }","duration":"101.649022ms","start":"2026-03-25T21:09:09.661995Z","end":"2026-03-25T21:09:09.763644Z","steps":["trace[682891122] 'process raft request' (duration: 101.347564ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:09:09.763794Z","caller":"traceutil/trace.go:171","msg":"trace[1119876940] transaction","detail":"{read_only:false; response_revision:1083; number_of_response:1; }","duration":"101.742614ms","start":"2026-03-25T21:09:09.662042Z","end":"2026-03-25T21:09:09.763785Z","steps":["trace[1119876940] 'process raft request' (duration: 101.56631ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:09:17.72496Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14933264729494071006,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-25T21:09:18.225137Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14933264729494071006,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-25T21:09:18.558117Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.685699168s","expected-duration":"1s"} {"level":"info","ts":"2026-03-25T21:09:18.558465Z","caller":"traceutil/trace.go:171","msg":"trace[936119791] linearizableReadLoop","detail":"{readStateIndex:1200; appliedIndex:1199; }","duration":"1.334553252s","start":"2026-03-25T21:09:17.223888Z","end":"2026-03-25T21:09:18.558442Z","steps":["trace[936119791] 'read index received' (duration: 1.334376078s)","trace[936119791] 'applied index is now lower than readState.Index' (duration: 175.904µs)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:09:18.558551Z","caller":"traceutil/trace.go:171","msg":"trace[1681545888] transaction","detail":"{read_only:false; response_revision:1155; number_of_response:1; }","duration":"1.686185429s","start":"2026-03-25T21:09:16.87234Z","end":"2026-03-25T21:09:18.558525Z","steps":["trace[1681545888] 'process raft request' (duration: 1.685957084s)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:09:18.558645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.334733046s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-25T21:09:18.558682Z","caller":"traceutil/trace.go:171","msg":"trace[1392779402] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:1155; }","duration":"1.334851849s","start":"2026-03-25T21:09:17.223821Z","end":"2026-03-25T21:09:18.558673Z","steps":["trace[1392779402] 'agreement among raft nodes before linearized reading' (duration: 1.334748716s)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:09:18.558681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:09:16.872327Z","time spent":"1.686272371s","remote":"127.0.0.1:44388","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-25T21:09:18.558753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:09:17.223792Z","time spent":"1.334914011s","remote":"127.0.0.1:44478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":30,"request content":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true "} {"level":"warn","ts":"2026-03-25T21:09:18.558782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.151265978s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T21:09:18.558831Z","caller":"traceutil/trace.go:171","msg":"trace[1579112253] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1155; }","duration":"1.15136635s","start":"2026-03-25T21:09:17.407453Z","end":"2026-03-25T21:09:18.558819Z","steps":["trace[1579112253] 'agreement among raft nodes before linearized reading' (duration: 1.151287918s)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:09:18.558852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:09:17.407425Z","time spent":"1.151423492s","remote":"127.0.0.1:54946","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-03-25T21:09:18.559029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"746.717852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.3\" ","response":"range_response_count:1 size:131"} {"level":"info","ts":"2026-03-25T21:09:18.559044Z","caller":"traceutil/trace.go:171","msg":"trace[2133149190] range","detail":"{range_begin:/registry/masterleases/199.204.45.3; range_end:; response_count:1; response_revision:1155; }","duration":"746.733042ms","start":"2026-03-25T21:09:17.812307Z","end":"2026-03-25T21:09:18.55904Z","steps":["trace[2133149190] 'agreement among raft nodes before linearized reading' (duration: 746.668341ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:09:18.559058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:09:17.812289Z","time spent":"746.763733ms","remote":"127.0.0.1:54986","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":1,"response size":154,"request content":"key:\"/registry/masterleases/199.204.45.3\" "} {"level":"warn","ts":"2026-03-25T21:09:18.559211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"838.44308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-03-25T21:09:18.559221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"761.931288ms","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-03-25T21:09:18.559245Z","caller":"traceutil/trace.go:171","msg":"trace[324951033] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1155; }","duration":"838.515362ms","start":"2026-03-25T21:09:17.720719Z","end":"2026-03-25T21:09:18.559235Z","steps":["trace[324951033] 'agreement among raft nodes before linearized reading' (duration: 838.386879ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:09:18.55927Z","caller":"traceutil/trace.go:171","msg":"trace[822452530] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1155; }","duration":"761.998699ms","start":"2026-03-25T21:09:17.797258Z","end":"2026-03-25T21:09:18.559256Z","steps":["trace[822452530] 'agreement among raft nodes before linearized reading' (duration: 761.847316ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:09:18.559274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:09:17.720702Z","time spent":"838.566754ms","remote":"127.0.0.1:44388","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-03-25T21:09:18.559303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:09:17.797247Z","time spent":"762.048761ms","remote":"127.0.0.1:44388","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-03-25T21:09:18.559381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"757.950095ms","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-03-25T21:09:18.559397Z","caller":"traceutil/trace.go:171","msg":"trace[1127868856] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1155; }","duration":"757.967196ms","start":"2026-03-25T21:09:17.801425Z","end":"2026-03-25T21:09:18.559392Z","steps":["trace[1127868856] 'agreement among raft nodes before linearized reading' (duration: 757.918185ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:09:18.559415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:09:17.801403Z","time spent":"758.008206ms","remote":"127.0.0.1:44388","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-03-25T21:09:18.559433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"759.01046ms","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-03-25T21:09:18.559459Z","caller":"traceutil/trace.go:171","msg":"trace[68591746] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1155; }","duration":"759.052811ms","start":"2026-03-25T21:09:17.800398Z","end":"2026-03-25T21:09:18.559451Z","steps":["trace[68591746] 'agreement among raft nodes before linearized reading' (duration: 758.981279ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:09:18.559487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:09:17.800389Z","time spent":"759.090992ms","remote":"127.0.0.1:44388","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-25T21:09:18.559592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"796.186441ms","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-03-25T21:09:18.559624Z","caller":"traceutil/trace.go:171","msg":"trace[780548306] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1155; }","duration":"796.240202ms","start":"2026-03-25T21:09:17.763375Z","end":"2026-03-25T21:09:18.559615Z","steps":["trace[780548306] 'agreement among raft nodes before linearized reading' (duration: 796.15161ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:09:18.559652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:09:17.763363Z","time spent":"796.282203ms","remote":"127.0.0.1:44388","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-25T21:09:22.725351Z","caller":"traceutil/trace.go:171","msg":"trace[18580413] transaction","detail":"{read_only:false; response_revision:1172; number_of_response:1; }","duration":"113.145671ms","start":"2026-03-25T21:09:22.612174Z","end":"2026-03-25T21:09:22.72532Z","steps":["trace[18580413] 'process raft request' (duration: 112.538147ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:09:22.789242Z","caller":"traceutil/trace.go:171","msg":"trace[715733563] transaction","detail":"{read_only:false; response_revision:1173; number_of_response:1; }","duration":"131.417219ms","start":"2026-03-25T21:09:22.657801Z","end":"2026-03-25T21:09:22.789218Z","steps":["trace[715733563] 'process raft request' (duration: 131.270115ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:09:33.649335Z","caller":"traceutil/trace.go:171","msg":"trace[1150864964] linearizableReadLoop","detail":"{readStateIndex:1263; appliedIndex:1262; }","duration":"242.968252ms","start":"2026-03-25T21:09:33.406321Z","end":"2026-03-25T21:09:33.649289Z","steps":["trace[1150864964] 'read index received' (duration: 242.717716ms)","trace[1150864964] 'applied index is now lower than readState.Index' (duration: 249.446µs)"],"step_count":2} {"level":"warn","ts":"2026-03-25T21:09:33.64947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.126185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T21:09:33.649521Z","caller":"traceutil/trace.go:171","msg":"trace[1968703397] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1215; }","duration":"243.227908ms","start":"2026-03-25T21:09:33.40628Z","end":"2026-03-25T21:09:33.649508Z","steps":["trace[1968703397] 'agreement among raft nodes before linearized reading' (duration: 243.131676ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:09:33.649373Z","caller":"traceutil/trace.go:171","msg":"trace[62480368] transaction","detail":"{read_only:false; response_revision:1215; number_of_response:1; }","duration":"289.336308ms","start":"2026-03-25T21:09:33.359998Z","end":"2026-03-25T21:09:33.649335Z","steps":["trace[62480368] 'process raft request' (duration: 289.126313ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:09:35.335163Z","caller":"traceutil/trace.go:171","msg":"trace[1047883258] transaction","detail":"{read_only:false; response_revision:1231; number_of_response:1; }","duration":"166.009229ms","start":"2026-03-25T21:09:35.169129Z","end":"2026-03-25T21:09:35.335139Z","steps":["trace[1047883258] 'process raft request' (duration: 165.848015ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:09:35.531903Z","caller":"traceutil/trace.go:171","msg":"trace[636188977] linearizableReadLoop","detail":"{readStateIndex:1283; appliedIndex:1281; }","duration":"122.592932ms","start":"2026-03-25T21:09:35.40928Z","end":"2026-03-25T21:09:35.531873Z","steps":["trace[636188977] 'read index received' (duration: 122.197572ms)","trace[636188977] 'applied index is now lower than readState.Index' (duration: 394.01µs)"],"step_count":2} {"level":"warn","ts":"2026-03-25T21:09:35.53211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.806006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T21:09:35.532183Z","caller":"traceutil/trace.go:171","msg":"trace[1407020856] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1234; }","duration":"122.921859ms","start":"2026-03-25T21:09:35.409243Z","end":"2026-03-25T21:09:35.532165Z","steps":["trace[1407020856] 'agreement among raft nodes before linearized reading' (duration: 122.765056ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:09:35.53248Z","caller":"traceutil/trace.go:171","msg":"trace[1405602079] transaction","detail":"{read_only:false; response_revision:1234; number_of_response:1; }","duration":"186.55038ms","start":"2026-03-25T21:09:35.345913Z","end":"2026-03-25T21:09:35.532463Z","steps":["trace[1405602079] 'process raft request' (duration: 185.838324ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:09:35.532555Z","caller":"traceutil/trace.go:171","msg":"trace[547930025] transaction","detail":"{read_only:false; response_revision:1233; number_of_response:1; }","duration":"189.447048ms","start":"2026-03-25T21:09:35.342524Z","end":"2026-03-25T21:09:35.531971Z","steps":["trace[547930025] 'process raft request' (duration: 189.061699ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:09:36.829694Z","caller":"traceutil/trace.go:171","msg":"trace[244603330] linearizableReadLoop","detail":"{readStateIndex:1284; appliedIndex:1283; }","duration":"135.490214ms","start":"2026-03-25T21:09:36.69418Z","end":"2026-03-25T21:09:36.82967Z","steps":["trace[244603330] 'read index received' (duration: 135.3228ms)","trace[244603330] 'applied index is now lower than readState.Index' (duration: 166.564µs)"],"step_count":2} {"level":"warn","ts":"2026-03-25T21:09:36.829971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.781991ms","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-03-25T21:09:36.830045Z","caller":"traceutil/trace.go:171","msg":"trace[461345277] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1235; }","duration":"135.898684ms","start":"2026-03-25T21:09:36.694123Z","end":"2026-03-25T21:09:36.830022Z","steps":["trace[461345277] 'agreement among raft nodes before linearized reading' (duration: 135.690099ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:09:36.830512Z","caller":"traceutil/trace.go:171","msg":"trace[1271610635] transaction","detail":"{read_only:false; response_revision:1235; number_of_response:1; }","duration":"208.391551ms","start":"2026-03-25T21:09:36.62136Z","end":"2026-03-25T21:09:36.829752Z","steps":["trace[1271610635] 'process raft request' (duration: 208.163216ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:09:37.055374Z","caller":"traceutil/trace.go:171","msg":"trace[421951311] transaction","detail":"{read_only:false; response_revision:1238; number_of_response:1; }","duration":"219.785818ms","start":"2026-03-25T21:09:36.83557Z","end":"2026-03-25T21:09:37.055355Z","steps":["trace[421951311] 'process raft request' (duration: 219.715467ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:09:37.055574Z","caller":"traceutil/trace.go:171","msg":"trace[1584381718] transaction","detail":"{read_only:false; response_revision:1237; number_of_response:1; }","duration":"220.781062ms","start":"2026-03-25T21:09:36.834783Z","end":"2026-03-25T21:09:37.055565Z","steps":["trace[1584381718] 'process raft request' (duration: 220.25886ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:09:37.055713Z","caller":"traceutil/trace.go:171","msg":"trace[1425953581] linearizableReadLoop","detail":"{readStateIndex:1288; appliedIndex:1284; }","duration":"201.796107ms","start":"2026-03-25T21:09:36.853881Z","end":"2026-03-25T21:09:37.055677Z","steps":["trace[1425953581] 'read index received' (duration: 56.35815ms)","trace[1425953581] 'applied index is now lower than readState.Index' (duration: 145.436227ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T21:09:37.055945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.044043ms","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-03-25T21:09:37.055921Z","caller":"traceutil/trace.go:171","msg":"trace[1523710448] transaction","detail":"{read_only:false; response_revision:1239; number_of_response:1; }","duration":"219.960333ms","start":"2026-03-25T21:09:36.835938Z","end":"2026-03-25T21:09:37.055898Z","steps":["trace[1523710448] 'process raft request' (duration: 219.3911ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:09:37.05598Z","caller":"traceutil/trace.go:171","msg":"trace[1314342123] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1239; }","duration":"202.136965ms","start":"2026-03-25T21:09:36.853834Z","end":"2026-03-25T21:09:37.055971Z","steps":["trace[1314342123] 'agreement among raft nodes before linearized reading' (duration: 201.94018ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:09:38.452449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.682888ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-03-25T21:09:38.452527Z","caller":"traceutil/trace.go:171","msg":"trace[1457949975] linearizableReadLoop","detail":"{readStateIndex:1291; appliedIndex:1290; }","duration":"275.585296ms","start":"2026-03-25T21:09:38.176928Z","end":"2026-03-25T21:09:38.452513Z","steps":["trace[1457949975] 'read index received' (duration: 37.841626ms)","trace[1457949975] 'applied index is now lower than readState.Index' (duration: 237.74275ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T21:09:38.45258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.651547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T21:09:38.452597Z","caller":"traceutil/trace.go:171","msg":"trace[165285592] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:1241; }","duration":"275.713359ms","start":"2026-03-25T21:09:38.176878Z","end":"2026-03-25T21:09:38.452591Z","steps":["trace[165285592] 'agreement among raft nodes before linearized reading' (duration: 275.667418ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:09:38.452734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:09:37.814383Z","time spent":"638.348454ms","remote":"127.0.0.1:54986","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-03-25T21:09:39.153067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.002922ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-25T21:09:39.153241Z","caller":"traceutil/trace.go:171","msg":"trace[667565920] linearizableReadLoop","detail":"{readStateIndex:1293; appliedIndex:1291; }","duration":"238.096748ms","start":"2026-03-25T21:09:38.915129Z","end":"2026-03-25T21:09:39.153225Z","steps":["trace[667565920] 'read index received' (duration: 32.785758ms)","trace[667565920] 'applied index is now lower than readState.Index' (duration: 205.31004ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:09:39.153328Z","caller":"traceutil/trace.go:171","msg":"trace[484024200] transaction","detail":"{read_only:false; response_revision:1242; number_of_response:1; }","duration":"699.615648ms","start":"2026-03-25T21:09:38.453702Z","end":"2026-03-25T21:09:39.153318Z","steps":["trace[484024200] 'process raft request' (duration: 494.291408ms)","trace[484024200] 'compare' (duration: 204.795548ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T21:09:39.153389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:09:38.453682Z","time spent":"699.6758ms","remote":"127.0.0.1:54986","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":116,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-25T21:09:39.153678Z","caller":"traceutil/trace.go:171","msg":"trace[794654434] transaction","detail":"{read_only:false; response_revision:1243; number_of_response:1; }","duration":"317.129579ms","start":"2026-03-25T21:09:38.836516Z","end":"2026-03-25T21:09:39.153646Z","steps":["trace[794654434] 'process raft request' (duration: 316.650848ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:09:39.153863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:09:38.836497Z","time spent":"317.284922ms","remote":"127.0.0.1:44388","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-25T21:09:39.153781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.634531ms","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-03-25T21:09:39.153929Z","caller":"traceutil/trace.go:171","msg":"trace[200420056] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1243; }","duration":"238.822444ms","start":"2026-03-25T21:09:38.915089Z","end":"2026-03-25T21:09:39.153911Z","steps":["trace[200420056] 'agreement among raft nodes before linearized reading' (duration: 238.500667ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:09:39.658351Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14933264729494071206,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-25T21:09:39.86434Z","caller":"traceutil/trace.go:171","msg":"trace[1316693668] transaction","detail":"{read_only:false; response_revision:1244; number_of_response:1; }","duration":"707.503743ms","start":"2026-03-25T21:09:39.156811Z","end":"2026-03-25T21:09:39.864315Z","steps":["trace[1316693668] 'process raft request' (duration: 706.229874ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:09:39.864484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:09:39.156798Z","time spent":"707.613496ms","remote":"127.0.0.1:44388","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-03-25T21:09:39.907671Z","caller":"traceutil/trace.go:171","msg":"trace[1961222617] linearizableReadLoop","detail":"{readStateIndex:1298; appliedIndex:1293; }","duration":"750.029619ms","start":"2026-03-25T21:09:39.157619Z","end":"2026-03-25T21:09:39.907648Z","steps":["trace[1961222617] 'read index received' (duration: 705.605629ms)","trace[1961222617] 'applied index is now lower than readState.Index' (duration: 44.42312ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T21:09:39.907884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"750.237974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:131"} {"level":"info","ts":"2026-03-25T21:09:39.907915Z","caller":"traceutil/trace.go:171","msg":"trace[2018676411] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:1248; }","duration":"750.307706ms","start":"2026-03-25T21:09:39.157599Z","end":"2026-03-25T21:09:39.907906Z","steps":["trace[2018676411] 'agreement among raft nodes before linearized reading' (duration: 750.144643ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:09:39.90795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:09:39.157592Z","time spent":"750.350217ms","remote":"127.0.0.1:54986","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":1,"response size":154,"request content":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" "} {"level":"info","ts":"2026-03-25T21:09:39.908277Z","caller":"traceutil/trace.go:171","msg":"trace[1378218413] transaction","detail":"{read_only:false; response_revision:1245; number_of_response:1; }","duration":"750.748416ms","start":"2026-03-25T21:09:39.157496Z","end":"2026-03-25T21:09:39.908244Z","steps":["trace[1378218413] 'process raft request' (duration: 749.899556ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:09:39.908369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:09:39.157482Z","time spent":"750.821238ms","remote":"127.0.0.1:44388","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-25T21:09:39.908532Z","caller":"traceutil/trace.go:171","msg":"trace[1939440184] transaction","detail":"{read_only:false; response_revision:1246; number_of_response:1; }","duration":"750.753427ms","start":"2026-03-25T21:09:39.157768Z","end":"2026-03-25T21:09:39.908522Z","steps":["trace[1939440184] 'process raft request' (duration: 749.770234ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:09:39.908584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:09:39.15776Z","time spent":"750.793838ms","remote":"127.0.0.1:44388","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-25T21:09:39.908702Z","caller":"traceutil/trace.go:171","msg":"trace[1158276168] transaction","detail":"{read_only:false; response_revision:1247; number_of_response:1; }","duration":"750.570102ms","start":"2026-03-25T21:09:39.158123Z","end":"2026-03-25T21:09:39.908693Z","steps":["trace[1158276168] 'process raft request' (duration: 749.455546ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:09:39.90875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:09:39.158114Z","time spent":"750.608603ms","remote":"127.0.0.1:44388","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-25T21:09:39.908866Z","caller":"traceutil/trace.go:171","msg":"trace[1262245579] transaction","detail":"{read_only:false; response_revision:1248; number_of_response:1; }","duration":"750.061801ms","start":"2026-03-25T21:09:39.158796Z","end":"2026-03-25T21:09:39.908857Z","steps":["trace[1262245579] 'process raft request' (duration: 748.821102ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:09:39.908913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:09:39.158785Z","time spent":"750.100401ms","remote":"127.0.0.1:44388","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-03-25T21:09:39.909607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"502.985572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T21:09:39.909639Z","caller":"traceutil/trace.go:171","msg":"trace[1861691226] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1248; }","duration":"503.038924ms","start":"2026-03-25T21:09:39.40659Z","end":"2026-03-25T21:09:39.909629Z","steps":["trace[1861691226] 'agreement among raft nodes before linearized reading' (duration: 502.991143ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:09:39.909664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:09:39.406577Z","time spent":"503.082125ms","remote":"127.0.0.1:54946","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-03-25T21:09:39.90991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.506229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T21:09:39.909939Z","caller":"traceutil/trace.go:171","msg":"trace[1952997809] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1248; }","duration":"154.571661ms","start":"2026-03-25T21:09:39.755359Z","end":"2026-03-25T21:09:39.90993Z","steps":["trace[1952997809] 'agreement among raft nodes before linearized reading' (duration: 154.53077ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:09:40.588635Z","caller":"traceutil/trace.go:171","msg":"trace[2128215549] linearizableReadLoop","detail":"{readStateIndex:1300; appliedIndex:1299; }","duration":"218.58278ms","start":"2026-03-25T21:09:40.370032Z","end":"2026-03-25T21:09:40.588614Z","steps":["trace[2128215549] 'read index received' (duration: 218.473528ms)","trace[2128215549] 'applied index is now lower than readState.Index' (duration: 108.442µs)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:09:40.588851Z","caller":"traceutil/trace.go:171","msg":"trace[757645742] transaction","detail":"{read_only:false; response_revision:1250; number_of_response:1; }","duration":"241.683541ms","start":"2026-03-25T21:09:40.347152Z","end":"2026-03-25T21:09:40.588835Z","steps":["trace[757645742] 'process raft request' (duration: 241.351904ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:09:40.589066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.010381ms","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-03-25T21:09:40.589781Z","caller":"traceutil/trace.go:171","msg":"trace[889946703] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1250; }","duration":"219.82902ms","start":"2026-03-25T21:09:40.369924Z","end":"2026-03-25T21:09:40.589753Z","steps":["trace[889946703] 'agreement among raft nodes before linearized reading' (duration: 218.96511ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:09:40.605966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.65018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T21:09:40.606033Z","caller":"traceutil/trace.go:171","msg":"trace[784251284] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1251; }","duration":"197.731322ms","start":"2026-03-25T21:09:40.408288Z","end":"2026-03-25T21:09:40.606019Z","steps":["trace[784251284] 'agreement among raft nodes before linearized reading' (duration: 197.589179ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:09:40.718934Z","caller":"traceutil/trace.go:171","msg":"trace[1613871905] transaction","detail":"{read_only:false; response_revision:1253; number_of_response:1; }","duration":"109.653938ms","start":"2026-03-25T21:09:40.609258Z","end":"2026-03-25T21:09:40.718912Z","steps":["trace[1613871905] 'process raft request' (duration: 109.614347ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:09:40.719001Z","caller":"traceutil/trace.go:171","msg":"trace[84342438] linearizableReadLoop","detail":"{readStateIndex:1302; appliedIndex:1301; }","duration":"113.12324ms","start":"2026-03-25T21:09:40.605856Z","end":"2026-03-25T21:09:40.718979Z","steps":["trace[84342438] 'read index received' (duration: 25.950168ms)","trace[84342438] 'applied index is now lower than readState.Index' (duration: 87.170392ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:09:40.718995Z","caller":"traceutil/trace.go:171","msg":"trace[1914721172] transaction","detail":"{read_only:false; response_revision:1252; number_of_response:1; }","duration":"119.98549ms","start":"2026-03-25T21:09:40.598968Z","end":"2026-03-25T21:09:40.718953Z","steps":["trace[1914721172] 'process raft request' (duration: 32.769817ms)","trace[1914721172] 'compare' (duration: 86.986108ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T21:09:40.71926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.394287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:473"} {"level":"info","ts":"2026-03-25T21:09:40.719303Z","caller":"traceutil/trace.go:171","msg":"trace[2136354208] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1253; }","duration":"122.593761ms","start":"2026-03-25T21:09:40.596699Z","end":"2026-03-25T21:09:40.719292Z","steps":["trace[2136354208] 'agreement among raft nodes before linearized reading' (duration: 122.347305ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:09:41.728942Z","caller":"traceutil/trace.go:171","msg":"trace[1700285793] linearizableReadLoop","detail":"{readStateIndex:1305; appliedIndex:1304; }","duration":"319.667518ms","start":"2026-03-25T21:09:41.409254Z","end":"2026-03-25T21:09:41.728921Z","steps":["trace[1700285793] 'read index received' (duration: 319.468953ms)","trace[1700285793] 'applied index is now lower than readState.Index' (duration: 197.655µs)"],"step_count":2} {"level":"warn","ts":"2026-03-25T21:09:41.729096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.810511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T21:09:41.729104Z","caller":"traceutil/trace.go:171","msg":"trace[1081005613] transaction","detail":"{read_only:false; response_revision:1255; number_of_response:1; }","duration":"565.708301ms","start":"2026-03-25T21:09:41.163328Z","end":"2026-03-25T21:09:41.729036Z","steps":["trace[1081005613] 'process raft request' (duration: 565.463445ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:09:41.729165Z","caller":"traceutil/trace.go:171","msg":"trace[525732346] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1255; }","duration":"319.940344ms","start":"2026-03-25T21:09:41.409215Z","end":"2026-03-25T21:09:41.729156Z","steps":["trace[525732346] 'agreement among raft nodes before linearized reading' (duration: 319.819641ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:09:41.729201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:09:41.409197Z","time spent":"319.996576ms","remote":"127.0.0.1:54946","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-03-25T21:09:41.72927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:09:41.163305Z","time spent":"565.876905ms","remote":"127.0.0.1:44388","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-25T21:09:42.109857Z","caller":"traceutil/trace.go:171","msg":"trace[1728519104] transaction","detail":"{read_only:false; response_revision:1260; number_of_response:1; }","duration":"191.25105ms","start":"2026-03-25T21:09:41.918583Z","end":"2026-03-25T21:09:42.109834Z","steps":["trace[1728519104] 'process raft request' (duration: 191.187448ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:09:42.109877Z","caller":"traceutil/trace.go:171","msg":"trace[1286918431] transaction","detail":"{read_only:false; response_revision:1258; number_of_response:1; }","duration":"192.268304ms","start":"2026-03-25T21:09:41.917576Z","end":"2026-03-25T21:09:42.109844Z","steps":["trace[1286918431] 'process raft request' (duration: 125.349237ms)","trace[1286918431] 'compare' (duration: 66.109178ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:09:42.109901Z","caller":"traceutil/trace.go:171","msg":"trace[1220760024] transaction","detail":"{read_only:false; response_revision:1259; number_of_response:1; }","duration":"191.333232ms","start":"2026-03-25T21:09:41.918539Z","end":"2026-03-25T21:09:42.109872Z","steps":["trace[1220760024] 'process raft request' (duration: 191.188938ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:09:49.102286Z","caller":"traceutil/trace.go:171","msg":"trace[1591542332] transaction","detail":"{read_only:false; response_revision:1316; number_of_response:1; }","duration":"112.298895ms","start":"2026-03-25T21:09:48.989959Z","end":"2026-03-25T21:09:49.102257Z","steps":["trace[1591542332] 'process raft request' (duration: 112.127552ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:09:49.365293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.219726ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-25T21:09:49.365563Z","caller":"traceutil/trace.go:171","msg":"trace[647745778] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:1316; }","duration":"209.546884ms","start":"2026-03-25T21:09:49.155971Z","end":"2026-03-25T21:09:49.365518Z","steps":["trace[647745778] 'count revisions from in-memory index tree' (duration: 209.107054ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:09:50.039333Z","caller":"traceutil/trace.go:171","msg":"trace[86640804] transaction","detail":"{read_only:false; response_revision:1317; number_of_response:1; }","duration":"668.118627ms","start":"2026-03-25T21:09:49.371178Z","end":"2026-03-25T21:09:50.039297Z","steps":["trace[86640804] 'process raft request' (duration: 667.910393ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:09:50.039821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:09:49.371141Z","time spent":"668.485826ms","remote":"127.0.0.1:44388","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":492,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-25T21:09:50.160283Z","caller":"traceutil/trace.go:171","msg":"trace[541799114] linearizableReadLoop","detail":"{readStateIndex:1370; appliedIndex:1368; }","duration":"227.572228ms","start":"2026-03-25T21:09:49.932684Z","end":"2026-03-25T21:09:50.160257Z","steps":["trace[541799114] 'read index received' (duration: 106.500126ms)","trace[541799114] 'applied index is now lower than readState.Index' (duration: 121.054331ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:09:50.160327Z","caller":"traceutil/trace.go:171","msg":"trace[1752291327] transaction","detail":"{read_only:false; response_revision:1318; number_of_response:1; }","duration":"341.96421ms","start":"2026-03-25T21:09:49.81833Z","end":"2026-03-25T21:09:50.160294Z","steps":["trace[1752291327] 'process raft request' (duration: 338.200266ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:09:50.16052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.827013ms","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-03-25T21:09:50.160562Z","caller":"traceutil/trace.go:171","msg":"trace[1872887181] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1318; }","duration":"227.934866ms","start":"2026-03-25T21:09:49.932616Z","end":"2026-03-25T21:09:50.160551Z","steps":["trace[1872887181] 'agreement among raft nodes before linearized reading' (duration: 227.772033ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:09:50.16058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:09:49.818299Z","time spent":"342.103734ms","remote":"127.0.0.1:44388","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-25T21:09:50.160827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.323808ms","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-03-25T21:09:50.160894Z","caller":"traceutil/trace.go:171","msg":"trace[2032245791] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1318; }","duration":"167.472452ms","start":"2026-03-25T21:09:49.993406Z","end":"2026-03-25T21:09:50.160879Z","steps":["trace[2032245791] 'agreement among raft nodes before linearized reading' (duration: 167.271757ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:10:01.434794Z","caller":"traceutil/trace.go:171","msg":"trace[398245564] transaction","detail":"{read_only:false; response_revision:1372; number_of_response:1; }","duration":"224.200743ms","start":"2026-03-25T21:10:01.210557Z","end":"2026-03-25T21:10:01.434758Z","steps":["trace[398245564] 'process raft request' (duration: 223.839315ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:10:06.4628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.257488ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-25T21:10:06.463062Z","caller":"traceutil/trace.go:171","msg":"trace[662451756] transaction","detail":"{read_only:false; response_revision:1396; number_of_response:1; }","duration":"189.229588ms","start":"2026-03-25T21:10:06.273796Z","end":"2026-03-25T21:10:06.463025Z","steps":["trace[662451756] 'process raft request' (duration: 87.640282ms)","trace[662451756] 'compare' (duration: 101.109975ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T21:10:18.890997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.514396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T21:10:18.891087Z","caller":"traceutil/trace.go:171","msg":"trace[710452868] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:1454; }","duration":"106.66839ms","start":"2026-03-25T21:10:18.784397Z","end":"2026-03-25T21:10:18.891065Z","steps":["trace[710452868] 'count revisions from in-memory index tree' (duration: 106.423454ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:10:20.56266Z","caller":"traceutil/trace.go:171","msg":"trace[377215416] transaction","detail":"{read_only:false; response_revision:1458; number_of_response:1; }","duration":"148.029706ms","start":"2026-03-25T21:10:20.414587Z","end":"2026-03-25T21:10:20.562617Z","steps":["trace[377215416] 'process raft request' (duration: 87.049161ms)","trace[377215416] 'compare' (duration: 60.580346ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:10:20.785194Z","caller":"traceutil/trace.go:171","msg":"trace[380479770] linearizableReadLoop","detail":"{readStateIndex:1517; appliedIndex:1516; }","duration":"134.675727ms","start":"2026-03-25T21:10:20.65049Z","end":"2026-03-25T21:10:20.785166Z","steps":["trace[380479770] 'read index received' (duration: 91.897389ms)","trace[380479770] 'applied index is now lower than readState.Index' (duration: 42.777158ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T21:10:20.785672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.585575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-03-25T21:10:20.785757Z","caller":"traceutil/trace.go:171","msg":"trace[96396771] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1459; }","duration":"117.702297ms","start":"2026-03-25T21:10:20.668033Z","end":"2026-03-25T21:10:20.785735Z","steps":["trace[96396771] 'agreement among raft nodes before linearized reading' (duration: 117.475752ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:10:20.785882Z","caller":"traceutil/trace.go:171","msg":"trace[1447230515] transaction","detail":"{read_only:false; response_revision:1459; number_of_response:1; }","duration":"217.746246ms","start":"2026-03-25T21:10:20.568112Z","end":"2026-03-25T21:10:20.785858Z","steps":["trace[1447230515] 'process raft request' (duration: 174.354525ms)","trace[1447230515] 'compare' (duration: 42.565743ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T21:10:20.786029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.516016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T21:10:20.786067Z","caller":"traceutil/trace.go:171","msg":"trace[185064152] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:1459; }","duration":"135.581467ms","start":"2026-03-25T21:10:20.650471Z","end":"2026-03-25T21:10:20.786052Z","steps":["trace[185064152] 'agreement among raft nodes before linearized reading' (duration: 135.514745ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:10:34.953074Z","caller":"traceutil/trace.go:171","msg":"trace[2084451102] transaction","detail":"{read_only:false; response_revision:1523; number_of_response:1; }","duration":"129.07681ms","start":"2026-03-25T21:10:34.823965Z","end":"2026-03-25T21:10:34.953042Z","steps":["trace[2084451102] 'process raft request' (duration: 80.757799ms)","trace[2084451102] 'compare' (duration: 47.942653ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:10:35.255078Z","caller":"traceutil/trace.go:171","msg":"trace[983911001] transaction","detail":"{read_only:false; response_revision:1527; number_of_response:1; }","duration":"126.241269ms","start":"2026-03-25T21:10:35.128785Z","end":"2026-03-25T21:10:35.255026Z","steps":["trace[983911001] 'process raft request' (duration: 125.929202ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:11:07.390446Z","caller":"traceutil/trace.go:171","msg":"trace[1232696339] transaction","detail":"{read_only:false; response_revision:1675; number_of_response:1; }","duration":"128.538072ms","start":"2026-03-25T21:11:07.261876Z","end":"2026-03-25T21:11:07.390414Z","steps":["trace[1232696339] 'process raft request' (duration: 83.162192ms)","trace[1232696339] 'compare' (duration: 45.228776ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:11:09.187007Z","caller":"traceutil/trace.go:171","msg":"trace[621832721] transaction","detail":"{read_only:false; response_revision:1680; number_of_response:1; }","duration":"122.294506ms","start":"2026-03-25T21:11:09.064681Z","end":"2026-03-25T21:11:09.186975Z","steps":["trace[621832721] 'process raft request' (duration: 121.956888ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:12:19.823388Z","caller":"traceutil/trace.go:171","msg":"trace[1975138262] transaction","detail":"{read_only:false; response_revision:2003; number_of_response:1; }","duration":"111.589991ms","start":"2026-03-25T21:12:19.711753Z","end":"2026-03-25T21:12:19.823343Z","steps":["trace[1975138262] 'process raft request' (duration: 111.298024ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:12:44.101959Z","caller":"traceutil/trace.go:171","msg":"trace[92262821] linearizableReadLoop","detail":"{readStateIndex:2201; appliedIndex:2200; }","duration":"103.311457ms","start":"2026-03-25T21:12:43.998619Z","end":"2026-03-25T21:12:44.101931Z","steps":["trace[92262821] 'read index received' (duration: 26.977981ms)","trace[92262821] 'applied index is now lower than readState.Index' (duration: 76.332246ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:12:44.102356Z","caller":"traceutil/trace.go:171","msg":"trace[1570884627] transaction","detail":"{read_only:false; response_revision:2114; number_of_response:1; }","duration":"138.631384ms","start":"2026-03-25T21:12:43.963705Z","end":"2026-03-25T21:12:44.102337Z","steps":["trace[1570884627] 'process raft request' (duration: 61.853678ms)","trace[1570884627] 'compare' (duration: 76.205893ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T21:12:44.10271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.064915ms","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-03-25T21:12:44.102808Z","caller":"traceutil/trace.go:171","msg":"trace[2069072149] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2114; }","duration":"104.206918ms","start":"2026-03-25T21:12:43.998578Z","end":"2026-03-25T21:12:44.102785Z","steps":["trace[2069072149] 'agreement among raft nodes before linearized reading' (duration: 103.920732ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T21:12:53.620947Z","caller":"traceutil/trace.go:171","msg":"trace[1405855603] transaction","detail":"{read_only:false; response_revision:2162; number_of_response:1; }","duration":"111.821836ms","start":"2026-03-25T21:12:53.509098Z","end":"2026-03-25T21:12:53.62092Z","steps":["trace[1405855603] 'process raft request' (duration: 40.131759ms)","trace[1405855603] 'compare' (duration: 71.570105ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T21:13:16.174676Z","caller":"traceutil/trace.go:171","msg":"trace[208161750] linearizableReadLoop","detail":"{readStateIndex:2382; appliedIndex:2381; }","duration":"458.715556ms","start":"2026-03-25T21:13:15.715941Z","end":"2026-03-25T21:13:16.174657Z","steps":["trace[208161750] 'read index received' (duration: 458.566692ms)","trace[208161750] 'applied index is now lower than readState.Index' (duration: 147.894µs)"],"step_count":2} {"level":"warn","ts":"2026-03-25T21:13:16.174813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.852729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T21:13:16.174773Z","caller":"traceutil/trace.go:171","msg":"trace[340923353] transaction","detail":"{read_only:false; response_revision:2288; number_of_response:1; }","duration":"480.525196ms","start":"2026-03-25T21:13:15.694202Z","end":"2026-03-25T21:13:16.174727Z","steps":["trace[340923353] 'process raft request' (duration: 480.319281ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:13:16.175013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:13:15.694185Z","time spent":"480.694799ms","remote":"127.0.0.1:44388","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":493,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-25T21:13:16.17484Z","caller":"traceutil/trace.go:171","msg":"trace[1460570336] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:2288; }","duration":"458.920601ms","start":"2026-03-25T21:13:15.715912Z","end":"2026-03-25T21:13:16.174832Z","steps":["trace[1460570336] 'agreement among raft nodes before linearized reading' (duration: 458.857269ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T21:13:16.175451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T21:13:15.715901Z","time spent":"459.527265ms","remote":"127.0.0.1:38996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":130,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true "} {"level":"info","ts":"2026-03-25T21:13:17.115981Z","caller":"traceutil/trace.go:171","msg":"trace[36507417] transaction","detail":"{read_only:false; response_revision:2299; number_of_response:1; }","duration":"144.224126ms","start":"2026-03-25T21:13:16.971734Z","end":"2026-03-25T21:13:17.115958Z","steps":["trace[36507417] 'process raft request' (duration: 144.102203ms)"],"step_count":1}