{"level":"warn","ts":"2026-04-13T20:53:23.747256Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-13T20:53:23.747373Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.209:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.209:2380","--initial-cluster=instance=https://199.19.213.209:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.209:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.209:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-13T20:53:23.747451Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-13T20:53:23.747462Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.209:2380"]} {"level":"info","ts":"2026-04-13T20:53:23.747488Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-13T20:53:23.74832Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.209:2379"]} {"level":"info","ts":"2026-04-13T20:53:23.748407Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.209:2380"],"listen-peer-urls":["https://199.19.213.209:2380"],"advertise-client-urls":["https://199.19.213.209:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.209:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.209:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-13T20:53:23.753147Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.411713ms"} {"level":"info","ts":"2026-04-13T20:53:23.759693Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"9409e054be1d0d72","cluster-id":"ff5f9c0688884e7d"} {"level":"info","ts":"2026-04-13T20:53:23.759797Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9409e054be1d0d72 switched to configuration voters=()"} {"level":"info","ts":"2026-04-13T20:53:23.759823Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9409e054be1d0d72 became follower at term 0"} {"level":"info","ts":"2026-04-13T20:53:23.759969Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 9409e054be1d0d72 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-13T20:53:23.759988Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9409e054be1d0d72 became follower at term 1"} {"level":"info","ts":"2026-04-13T20:53:23.760074Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9409e054be1d0d72 switched to configuration voters=(10667303846975180146)"} {"level":"warn","ts":"2026-04-13T20:53:23.766742Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-13T20:53:23.770863Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-13T20:53:23.771817Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-13T20:53:23.774211Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"9409e054be1d0d72","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-13T20:53:23.77464Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"9409e054be1d0d72","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-13T20:53:23.774773Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-13T20:53:23.774833Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-13T20:53:23.774843Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-13T20:53:23.775576Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9409e054be1d0d72 switched to configuration voters=(10667303846975180146)"} {"level":"info","ts":"2026-04-13T20:53:23.778744Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ff5f9c0688884e7d","local-member-id":"9409e054be1d0d72","added-peer-id":"9409e054be1d0d72","added-peer-peer-urls":["https://199.19.213.209:2380"]} {"level":"info","ts":"2026-04-13T20:53:23.780331Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-13T20:53:23.780422Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.209:2380"} {"level":"info","ts":"2026-04-13T20:53:23.780598Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.209:2380"} {"level":"info","ts":"2026-04-13T20:53:23.781021Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"9409e054be1d0d72","initial-advertise-peer-urls":["https://199.19.213.209:2380"],"listen-peer-urls":["https://199.19.213.209:2380"],"advertise-client-urls":["https://199.19.213.209:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.209:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-13T20:53:23.781184Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-13T20:53:24.260586Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9409e054be1d0d72 is starting a new election at term 1"} {"level":"info","ts":"2026-04-13T20:53:24.26069Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9409e054be1d0d72 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-13T20:53:24.260728Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9409e054be1d0d72 received MsgPreVoteResp from 9409e054be1d0d72 at term 1"} {"level":"info","ts":"2026-04-13T20:53:24.260751Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9409e054be1d0d72 became candidate at term 2"} {"level":"info","ts":"2026-04-13T20:53:24.260762Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9409e054be1d0d72 received MsgVoteResp from 9409e054be1d0d72 at term 2"} {"level":"info","ts":"2026-04-13T20:53:24.260777Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9409e054be1d0d72 became leader at term 2"} {"level":"info","ts":"2026-04-13T20:53:24.260793Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 9409e054be1d0d72 elected leader 9409e054be1d0d72 at term 2"} {"level":"info","ts":"2026-04-13T20:53:24.26158Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-13T20:53:24.262486Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-13T20:53:24.262487Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"9409e054be1d0d72","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.209:2379]}","request-path":"/0/members/9409e054be1d0d72/attributes","cluster-id":"ff5f9c0688884e7d","publish-timeout":"7s"} {"level":"info","ts":"2026-04-13T20:53:24.263286Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-13T20:53:24.265959Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ff5f9c0688884e7d","local-member-id":"9409e054be1d0d72","cluster-version":"3.5"} {"level":"info","ts":"2026-04-13T20:53:24.266067Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-13T20:53:24.26639Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-13T20:53:24.267948Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-13T20:53:24.268165Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.209:2379"} {"level":"info","ts":"2026-04-13T20:53:24.270653Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-13T20:53:24.270701Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-13T20:53:40.032211Z","caller":"traceutil/trace.go:171","msg":"trace[421434748] transaction","detail":"{read_only:false; response_revision:367; number_of_response:1; }","duration":"101.658791ms","start":"2026-04-13T20:53:39.930517Z","end":"2026-04-13T20:53:40.032176Z","steps":["trace[421434748] 'process raft request' (duration: 42.844037ms)","trace[421434748] 'compare' (duration: 58.538086ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T20:53:40.28036Z","caller":"traceutil/trace.go:171","msg":"trace[823494949] transaction","detail":"{read_only:false; response_revision:368; number_of_response:1; }","duration":"236.712886ms","start":"2026-04-13T20:53:40.043583Z","end":"2026-04-13T20:53:40.280296Z","steps":["trace[823494949] 'process raft request' (duration: 235.02074ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T20:53:42.591296Z","caller":"traceutil/trace.go:171","msg":"trace[725303076] transaction","detail":"{read_only:false; response_revision:388; number_of_response:1; }","duration":"102.875026ms","start":"2026-04-13T20:53:42.488384Z","end":"2026-04-13T20:53:42.591259Z","steps":["trace[725303076] 'process raft request' (duration: 62.6268ms)","trace[725303076] 'compare' (duration: 39.955247ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T20:53:42.592181Z","caller":"traceutil/trace.go:171","msg":"trace[643826854] transaction","detail":"{read_only:false; response_revision:389; number_of_response:1; }","duration":"103.452962ms","start":"2026-04-13T20:53:42.488684Z","end":"2026-04-13T20:53:42.592137Z","steps":["trace[643826854] 'process raft request' (duration: 103.131583ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:53:42.592199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.959945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4195"} {"level":"info","ts":"2026-04-13T20:53:42.592485Z","caller":"traceutil/trace.go:171","msg":"trace[2100967322] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:389; }","duration":"104.275445ms","start":"2026-04-13T20:53:42.488182Z","end":"2026-04-13T20:53:42.592457Z","steps":["trace[2100967322] 'agreement among raft nodes before linearized reading' (duration: 103.884194ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:53:42.975617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.455325ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-13T20:53:42.975779Z","caller":"traceutil/trace.go:171","msg":"trace[505669718] transaction","detail":"{read_only:false; response_revision:406; number_of_response:1; }","duration":"205.580227ms","start":"2026-04-13T20:53:42.770161Z","end":"2026-04-13T20:53:42.975742Z","steps":["trace[505669718] 'process raft request' (duration: 73.34255ms)","trace[505669718] 'compare' (duration: 131.340032ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T20:53:42.975931Z","caller":"traceutil/trace.go:171","msg":"trace[1265631422] linearizableReadLoop","detail":"{readStateIndex:421; appliedIndex:419; }","duration":"197.400518ms","start":"2026-04-13T20:53:42.778503Z","end":"2026-04-13T20:53:42.975903Z","steps":["trace[1265631422] 'read index received' (duration: 6.309446ms)","trace[1265631422] 'applied index is now lower than readState.Index' (duration: 191.087412ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T20:53:42.997221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.021239ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-13T20:53:42.997318Z","caller":"traceutil/trace.go:171","msg":"trace[616511355] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:406; }","duration":"162.178073ms","start":"2026-04-13T20:53:42.835121Z","end":"2026-04-13T20:53:42.997299Z","steps":["trace[616511355] 'agreement among raft nodes before linearized reading' (duration: 162.002668ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:53:42.997347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.786156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4195"} {"level":"info","ts":"2026-04-13T20:53:42.997491Z","caller":"traceutil/trace.go:171","msg":"trace[515037077] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:406; }","duration":"218.94529ms","start":"2026-04-13T20:53:42.778497Z","end":"2026-04-13T20:53:42.997442Z","steps":["trace[515037077] 'agreement among raft nodes before linearized reading' (duration: 197.662596ms)","trace[515037077] 'range keys from in-memory index tree' (duration: 20.977176ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T20:53:56.887541Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":969010079943930918,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-13T20:53:57.138202Z","caller":"traceutil/trace.go:171","msg":"trace[1433561006] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"789.580432ms","start":"2026-04-13T20:53:56.348561Z","end":"2026-04-13T20:53:57.138141Z","steps":["trace[1433561006] 'process raft request' (duration: 789.285263ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:53:57.138434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:53:56.348528Z","time spent":"789.808627ms","remote":"127.0.0.1:39270","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-13T20:53:57.172787Z","caller":"traceutil/trace.go:171","msg":"trace[1073514751] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"803.398298ms","start":"2026-04-13T20:53:56.369352Z","end":"2026-04-13T20:53:57.172751Z","steps":["trace[1073514751] 'process raft request' (duration: 798.612075ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T20:53:57.172903Z","caller":"traceutil/trace.go:171","msg":"trace[585044806] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"798.326356ms","start":"2026-04-13T20:53:56.374539Z","end":"2026-04-13T20:53:57.172866Z","steps":["trace[585044806] 'process raft request' (duration: 798.11851ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:53:57.173017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:53:56.369324Z","time spent":"803.614223ms","remote":"127.0.0.1:39270","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-13T20:53:57.173018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:53:56.374524Z","time spent":"798.430819ms","remote":"127.0.0.1:39270","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-13T20:53:57.173155Z","caller":"traceutil/trace.go:171","msg":"trace[150514386] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"766.012542ms","start":"2026-04-13T20:53:56.407113Z","end":"2026-04-13T20:53:57.173125Z","steps":["trace[150514386] 'process raft request' (duration: 765.806546ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:53:57.173235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:53:56.407041Z","time spent":"766.164946ms","remote":"127.0.0.1:39270","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-13T20:53:57.173175Z","caller":"traceutil/trace.go:171","msg":"trace[1481784813] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"790.622459ms","start":"2026-04-13T20:53:56.382478Z","end":"2026-04-13T20:53:57.173101Z","steps":["trace[1481784813] 'process raft request' (duration: 790.364893ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T20:53:57.173426Z","caller":"traceutil/trace.go:171","msg":"trace[1794179857] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"755.410805ms","start":"2026-04-13T20:53:56.418007Z","end":"2026-04-13T20:53:57.173418Z","steps":["trace[1794179857] 'process raft request' (duration: 755.128058ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T20:53:57.173351Z","caller":"traceutil/trace.go:171","msg":"trace[880221595] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"765.006374ms","start":"2026-04-13T20:53:56.408209Z","end":"2026-04-13T20:53:57.173215Z","steps":["trace[880221595] 'process raft request' (duration: 764.775067ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:53:57.173474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:53:56.417999Z","time spent":"755.455948ms","remote":"127.0.0.1:39270","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-13T20:53:57.173472Z","caller":"traceutil/trace.go:171","msg":"trace[1627726017] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"752.719951ms","start":"2026-04-13T20:53:56.420737Z","end":"2026-04-13T20:53:57.173457Z","steps":["trace[1627726017] 'process raft request' (duration: 752.485865ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:53:57.173529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:53:56.420713Z","time spent":"752.789813ms","remote":"127.0.0.1:39270","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-13T20:53:57.173649Z","caller":"traceutil/trace.go:171","msg":"trace[1609384570] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"737.055944ms","start":"2026-04-13T20:53:56.436583Z","end":"2026-04-13T20:53:57.173639Z","steps":["trace[1609384570] 'process raft request' (duration: 736.749986ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:53:57.173705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:53:56.436556Z","time spent":"737.125655ms","remote":"127.0.0.1:39270","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-13T20:53:57.173743Z","caller":"traceutil/trace.go:171","msg":"trace[1908994607] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"605.812254ms","start":"2026-04-13T20:53:56.567919Z","end":"2026-04-13T20:53:57.173731Z","steps":["trace[1908994607] 'process raft request' (duration: 605.64593ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T20:53:57.173752Z","caller":"traceutil/trace.go:171","msg":"trace[1607036848] linearizableReadLoop","detail":"{readStateIndex:501; appliedIndex:488; }","duration":"786.921617ms","start":"2026-04-13T20:53:56.386805Z","end":"2026-04-13T20:53:57.173726Z","steps":["trace[1607036848] 'read index received' (duration: 749.162602ms)","trace[1607036848] 'applied index is now lower than readState.Index' (duration: 37.757875ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T20:53:57.173738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:53:56.408174Z","time spent":"765.349083ms","remote":"127.0.0.1:39270","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-13T20:53:57.173801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:53:56.567881Z","time spent":"605.895827ms","remote":"127.0.0.1:39270","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-13T20:53:57.173727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:53:56.382455Z","time spent":"790.96997ms","remote":"127.0.0.1:39270","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-13T20:53:57.173376Z","caller":"traceutil/trace.go:171","msg":"trace[958473024] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"755.475848ms","start":"2026-04-13T20:53:56.417888Z","end":"2026-04-13T20:53:57.173364Z","steps":["trace[958473024] 'process raft request' (duration: 755.20285ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:53:57.173997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:53:56.417852Z","time spent":"756.119715ms","remote":"127.0.0.1:39270","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-13T20:53:57.173872Z","caller":"traceutil/trace.go:171","msg":"trace[1270676528] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"601.303958ms","start":"2026-04-13T20:53:56.572558Z","end":"2026-04-13T20:53:57.173862Z","steps":["trace[1270676528] 'process raft request' (duration: 601.088852ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:53:57.174132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"787.307468ms","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-04-13T20:53:57.1742Z","caller":"traceutil/trace.go:171","msg":"trace[1510985038] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:483; }","duration":"787.41547ms","start":"2026-04-13T20:53:56.386769Z","end":"2026-04-13T20:53:57.174184Z","steps":["trace[1510985038] 'agreement among raft nodes before linearized reading' (duration: 787.271076ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:53:57.174142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:53:56.572542Z","time spent":"601.542905ms","remote":"127.0.0.1:39508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T20:53:57.174323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:53:56.386748Z","time spent":"787.561315ms","remote":"127.0.0.1:39508","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-04-13T20:53:57.173887Z","caller":"traceutil/trace.go:171","msg":"trace[1217875416] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"725.85884ms","start":"2026-04-13T20:53:56.448021Z","end":"2026-04-13T20:53:57.173879Z","steps":["trace[1217875416] 'process raft request' (duration: 725.414968ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:53:57.175938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:53:56.447994Z","time spent":"727.775664ms","remote":"127.0.0.1:39270","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-13T20:53:57.190545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.680664ms","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-04-13T20:53:57.190639Z","caller":"traceutil/trace.go:171","msg":"trace[791291629] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:485; }","duration":"339.88968ms","start":"2026-04-13T20:53:56.850728Z","end":"2026-04-13T20:53:57.190618Z","steps":["trace[791291629] 'agreement among raft nodes before linearized reading' (duration: 339.610812ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:53:57.190686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:53:56.850701Z","time spent":"339.976403ms","remote":"127.0.0.1:39508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-13T20:53:57.191162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.870337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T20:53:57.191199Z","caller":"traceutil/trace.go:171","msg":"trace[1710757345] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:485; }","duration":"238.96365ms","start":"2026-04-13T20:53:56.952226Z","end":"2026-04-13T20:53:57.19119Z","steps":["trace[1710757345] 'agreement among raft nodes before linearized reading' (duration: 238.908299ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T20:53:57.735385Z","caller":"traceutil/trace.go:171","msg":"trace[612830160] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"173.062308ms","start":"2026-04-13T20:53:57.562296Z","end":"2026-04-13T20:53:57.735358Z","steps":["trace[612830160] 'process raft request' (duration: 165.740663ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T20:53:57.737721Z","caller":"traceutil/trace.go:171","msg":"trace[868145305] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"101.060936ms","start":"2026-04-13T20:53:57.636629Z","end":"2026-04-13T20:53:57.73769Z","steps":["trace[868145305] 'process raft request' (duration: 100.775748ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T20:53:57.738173Z","caller":"traceutil/trace.go:171","msg":"trace[775945613] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"150.060344ms","start":"2026-04-13T20:53:57.588063Z","end":"2026-04-13T20:53:57.738124Z","steps":["trace[775945613] 'process raft request' (duration: 149.101827ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:53:58.924979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"886.803658ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-13T20:53:58.925146Z","caller":"traceutil/trace.go:171","msg":"trace[2096785576] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"1.078217379s","start":"2026-04-13T20:53:57.846905Z","end":"2026-04-13T20:53:58.925122Z","steps":["trace[2096785576] 'process raft request' (duration: 191.152913ms)","trace[2096785576] 'compare' (duration: 886.569952ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T20:53:58.925218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:53:57.846857Z","time spent":"1.078329581s","remote":"127.0.0.1:39270","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":151221,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T20:53:59.228192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.812389ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-13T20:53:59.228444Z","caller":"traceutil/trace.go:171","msg":"trace[1116391656] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"1.349029478s","start":"2026-04-13T20:53:57.87938Z","end":"2026-04-13T20:53:59.228409Z","steps":["trace[1116391656] 'process raft request' (duration: 1.199828998s)","trace[1116391656] 'compare' (duration: 148.522012ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T20:53:59.228619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:53:57.879351Z","time spent":"1.349171353s","remote":"127.0.0.1:39270","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":65412,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T20:53:59.228671Z","caller":"traceutil/trace.go:171","msg":"trace[463240861] linearizableReadLoop","detail":"{readStateIndex:557; appliedIndex:555; }","duration":"1.269561888s","start":"2026-04-13T20:53:57.959081Z","end":"2026-04-13T20:53:59.228643Z","steps":["trace[463240861] 'read index received' (duration: 78.991358ms)","trace[463240861] 'applied index is now lower than readState.Index' (duration: 1.19056781s)"],"step_count":2} {"level":"warn","ts":"2026-04-13T20:53:59.228816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.269710791s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T20:53:59.228882Z","caller":"traceutil/trace.go:171","msg":"trace[563186035] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:533; }","duration":"1.269827965s","start":"2026-04-13T20:53:57.959035Z","end":"2026-04-13T20:53:59.228863Z","steps":["trace[563186035] 'agreement among raft nodes before linearized reading' (duration: 1.269708462s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:53:59.228921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:53:57.959012Z","time spent":"1.269899638s","remote":"127.0.0.1:39234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-13T20:53:59.229824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.216189465s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-13T20:53:59.229955Z","caller":"traceutil/trace.go:171","msg":"trace[1021834925] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:535; }","duration":"1.216317619s","start":"2026-04-13T20:53:58.013568Z","end":"2026-04-13T20:53:59.229885Z","steps":["trace[1021834925] 'agreement among raft nodes before linearized reading' (duration: 1.21600463s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:53:59.230031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:53:58.013539Z","time spent":"1.216458854s","remote":"127.0.0.1:39508","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-04-13T20:53:59.23007Z","caller":"traceutil/trace.go:171","msg":"trace[840078745] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"1.156149627s","start":"2026-04-13T20:53:58.073904Z","end":"2026-04-13T20:53:59.230053Z","steps":["trace[840078745] 'process raft request' (duration: 1.155069457s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:53:59.230155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:53:58.073874Z","time spent":"1.15622687s","remote":"127.0.0.1:39270","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":569094,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-13T20:53:59.230226Z","caller":"traceutil/trace.go:171","msg":"trace[1017041227] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"274.286747ms","start":"2026-04-13T20:53:58.95593Z","end":"2026-04-13T20:53:59.230217Z","steps":["trace[1017041227] 'process raft request' (duration: 273.546956ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T20:53:59.663392Z","caller":"traceutil/trace.go:171","msg":"trace[474824240] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"115.983932ms","start":"2026-04-13T20:53:59.547375Z","end":"2026-04-13T20:53:59.663359Z","steps":["trace[474824240] 'process raft request' (duration: 40.268945ms)","trace[474824240] 'compare' (duration: 75.366666ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T20:53:59.674812Z","caller":"traceutil/trace.go:171","msg":"trace[804456540] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"116.96683ms","start":"2026-04-13T20:53:59.557814Z","end":"2026-04-13T20:53:59.674781Z","steps":["trace[804456540] 'process raft request' (duration: 116.792605ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:54:00.076775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.59967ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-13T20:54:00.077075Z","caller":"traceutil/trace.go:171","msg":"trace[959754470] linearizableReadLoop","detail":"{readStateIndex:574; appliedIndex:573; }","duration":"309.708787ms","start":"2026-04-13T20:53:59.767339Z","end":"2026-04-13T20:54:00.077048Z","steps":["trace[959754470] 'read index received' (duration: 173.772238ms)","trace[959754470] 'applied index is now lower than readState.Index' (duration: 135.934229ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T20:54:00.077189Z","caller":"traceutil/trace.go:171","msg":"trace[1278225806] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"327.281159ms","start":"2026-04-13T20:53:59.749801Z","end":"2026-04-13T20:54:00.077082Z","steps":["trace[1278225806] 'process raft request' (duration: 191.234936ms)","trace[1278225806] 'compare' (duration: 135.409565ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T20:54:00.07733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.962264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/grpcroutes.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:80703"} {"level":"info","ts":"2026-04-13T20:54:00.077377Z","caller":"traceutil/trace.go:171","msg":"trace[1660706567] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/grpcroutes.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:550; }","duration":"310.065547ms","start":"2026-04-13T20:53:59.7673Z","end":"2026-04-13T20:54:00.077366Z","steps":["trace[1660706567] 'agreement among raft nodes before linearized reading' (duration: 309.837221ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:54:00.077402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:53:59.767283Z","time spent":"310.113507ms","remote":"127.0.0.1:39270","response type":"/etcdserverpb.KV/Range","request count":0,"request size":95,"response count":1,"response size":80727,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/grpcroutes.gateway.networking.k8s.io\" "} {"level":"warn","ts":"2026-04-13T20:54:00.077386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:53:59.749791Z","time spent":"327.498774ms","remote":"127.0.0.1:39270","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":15664,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T20:54:00.111154Z","caller":"traceutil/trace.go:171","msg":"trace[1213022720] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"194.813755ms","start":"2026-04-13T20:53:59.916311Z","end":"2026-04-13T20:54:00.111125Z","steps":["trace[1213022720] 'process raft request' (duration: 194.400564ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T20:54:00.460133Z","caller":"traceutil/trace.go:171","msg":"trace[2042415260] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"300.82918ms","start":"2026-04-13T20:54:00.159268Z","end":"2026-04-13T20:54:00.460097Z","steps":["trace[2042415260] 'process raft request' (duration: 228.59769ms)","trace[2042415260] 'compare' (duration: 71.967862ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T20:54:00.460309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:54:00.159241Z","time spent":"300.975324ms","remote":"127.0.0.1:39270","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":208461,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T20:54:00.46981Z","caller":"traceutil/trace.go:171","msg":"trace[1679826598] linearizableReadLoop","detail":"{readStateIndex:577; appliedIndex:575; }","duration":"295.379637ms","start":"2026-04-13T20:54:00.174414Z","end":"2026-04-13T20:54:00.469794Z","steps":["trace[1679826598] 'read index received' (duration: 213.440416ms)","trace[1679826598] 'applied index is now lower than readState.Index' (duration: 81.938201ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T20:54:00.469976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.550961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/referencegrants.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:7708"} {"level":"info","ts":"2026-04-13T20:54:00.470021Z","caller":"traceutil/trace.go:171","msg":"trace[1273844128] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/referencegrants.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:554; }","duration":"295.639424ms","start":"2026-04-13T20:54:00.174368Z","end":"2026-04-13T20:54:00.470007Z","steps":["trace[1273844128] 'agreement among raft nodes before linearized reading' (duration: 295.491799ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T20:54:00.470019Z","caller":"traceutil/trace.go:171","msg":"trace[695525853] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"143.360338ms","start":"2026-04-13T20:54:00.326635Z","end":"2026-04-13T20:54:00.469996Z","steps":["trace[695525853] 'process raft request' (duration: 143.10703ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T20:54:00.470208Z","caller":"traceutil/trace.go:171","msg":"trace[1353512936] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"307.512507ms","start":"2026-04-13T20:54:00.162609Z","end":"2026-04-13T20:54:00.470121Z","steps":["trace[1353512936] 'process raft request' (duration: 307.040483ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:54:00.470329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:54:00.16258Z","time spent":"307.699451ms","remote":"127.0.0.1:39270","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":15673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T20:54:00.953782Z","caller":"traceutil/trace.go:171","msg":"trace[1921369157] linearizableReadLoop","detail":"{readStateIndex:579; appliedIndex:578; }","duration":"385.224519ms","start":"2026-04-13T20:54:00.568516Z","end":"2026-04-13T20:54:00.95374Z","steps":["trace[1921369157] 'read index received' (duration: 363.038868ms)","trace[1921369157] 'applied index is now lower than readState.Index' (duration: 22.18364ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T20:54:00.953898Z","caller":"traceutil/trace.go:171","msg":"trace[1622125724] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"395.841815ms","start":"2026-04-13T20:54:00.558Z","end":"2026-04-13T20:54:00.953841Z","steps":["trace[1622125724] 'process raft request' (duration: 373.783049ms)","trace[1622125724] 'compare' (duration: 21.46289ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T20:54:00.954324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.775095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/clienttrafficpolicies.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:65436"} {"level":"info","ts":"2026-04-13T20:54:00.954399Z","caller":"traceutil/trace.go:171","msg":"trace[1375955154] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/clienttrafficpolicies.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:555; }","duration":"385.902627ms","start":"2026-04-13T20:54:00.568479Z","end":"2026-04-13T20:54:00.954382Z","steps":["trace[1375955154] 'agreement among raft nodes before linearized reading' (duration: 385.458045ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:54:00.954439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:54:00.568459Z","time spent":"385.96934ms","remote":"127.0.0.1:39270","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":65460,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/clienttrafficpolicies.gateway.envoyproxy.io\" "} {"level":"warn","ts":"2026-04-13T20:54:00.954577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:54:00.557982Z","time spent":"396.359049ms","remote":"127.0.0.1:39270","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":208470,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T20:54:05.10449Z","caller":"traceutil/trace.go:171","msg":"trace[294715556] linearizableReadLoop","detail":"{readStateIndex:603; appliedIndex:602; }","duration":"150.339743ms","start":"2026-04-13T20:54:04.954118Z","end":"2026-04-13T20:54:05.104458Z","steps":["trace[294715556] 'read index received' (duration: 149.077807ms)","trace[294715556] 'applied index is now lower than readState.Index' (duration: 1.260286ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T20:54:05.104563Z","caller":"traceutil/trace.go:171","msg":"trace[1210071842] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"400.69726ms","start":"2026-04-13T20:54:04.703815Z","end":"2026-04-13T20:54:05.104512Z","steps":["trace[1210071842] 'process raft request' (duration: 399.517338ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:54:05.104648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.501827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T20:54:05.104733Z","caller":"traceutil/trace.go:171","msg":"trace[1700690916] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:578; }","duration":"150.626951ms","start":"2026-04-13T20:54:04.954048Z","end":"2026-04-13T20:54:05.104675Z","steps":["trace[1700690916] 'agreement among raft nodes before linearized reading' (duration: 150.524178ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:54:05.104766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:54:04.703782Z","time spent":"400.870037ms","remote":"127.0.0.1:39330","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036225,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T20:54:05.767249Z","caller":"traceutil/trace.go:171","msg":"trace[354932513] linearizableReadLoop","detail":"{readStateIndex:604; appliedIndex:603; }","duration":"477.484167ms","start":"2026-04-13T20:54:05.28973Z","end":"2026-04-13T20:54:05.767214Z","steps":["trace[354932513] 'read index received' (duration: 466.903852ms)","trace[354932513] 'applied index is now lower than readState.Index' (duration: 10.578845ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T20:54:05.767363Z","caller":"traceutil/trace.go:171","msg":"trace[1224823142] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"593.48852ms","start":"2026-04-13T20:54:05.173795Z","end":"2026-04-13T20:54:05.767283Z","steps":["trace[1224823142] 'process raft request' (duration: 582.76494ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:54:05.767679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.923489ms","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-04-13T20:54:05.767749Z","caller":"traceutil/trace.go:171","msg":"trace[663647155] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:579; }","duration":"478.067683ms","start":"2026-04-13T20:54:05.289664Z","end":"2026-04-13T20:54:05.767732Z","steps":["trace[663647155] 'agreement among raft nodes before linearized reading' (duration: 477.772724ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:54:05.767804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:54:05.289634Z","time spent":"478.157686ms","remote":"127.0.0.1:39508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-13T20:54:05.768202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.598991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-13T20:54:05.768293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.612505ms","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-04-13T20:54:05.768329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.074355ms","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-04-13T20:54:05.76835Z","caller":"traceutil/trace.go:171","msg":"trace[610665624] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:579; }","duration":"431.670726ms","start":"2026-04-13T20:54:05.336666Z","end":"2026-04-13T20:54:05.768337Z","steps":["trace[610665624] 'agreement among raft nodes before linearized reading' (duration: 431.533143ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T20:54:05.768377Z","caller":"traceutil/trace.go:171","msg":"trace[1073343910] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:579; }","duration":"433.168669ms","start":"2026-04-13T20:54:05.335196Z","end":"2026-04-13T20:54:05.768365Z","steps":["trace[1073343910] 'agreement among raft nodes before linearized reading' (duration: 433.005324ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:54:05.76839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:54:05.336631Z","time spent":"431.749138ms","remote":"127.0.0.1:39508","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-04-13T20:54:05.768422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:54:05.335167Z","time spent":"433.247261ms","remote":"127.0.0.1:39508","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-04-13T20:54:05.768207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:54:05.173757Z","time spent":"594.328353ms","remote":"127.0.0.1:39598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1092,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-13T20:54:05.768537Z","caller":"traceutil/trace.go:171","msg":"trace[47622522] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:579; }","duration":"437.86608ms","start":"2026-04-13T20:54:05.330453Z","end":"2026-04-13T20:54:05.768319Z","steps":["trace[47622522] 'agreement among raft nodes before linearized reading' (duration: 437.607523ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:54:05.768634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:54:05.330421Z","time spent":"438.194458ms","remote":"127.0.0.1:39508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-13T20:54:05.977035Z","caller":"traceutil/trace.go:171","msg":"trace[1059805893] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"203.440847ms","start":"2026-04-13T20:54:05.773541Z","end":"2026-04-13T20:54:05.976982Z","steps":["trace[1059805893] 'process raft request' (duration: 150.58826ms)","trace[1059805893] 'compare' (duration: 52.700932ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T20:54:06.031299Z","caller":"traceutil/trace.go:171","msg":"trace[1851464418] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"257.559961ms","start":"2026-04-13T20:54:05.773701Z","end":"2026-04-13T20:54:06.031261Z","steps":["trace[1851464418] 'process raft request' (duration: 257.038856ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:54:06.031449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.426007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/envoy-gateway-gateway-helm-certgen:envoy-gateway-system\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T20:54:06.031299Z","caller":"traceutil/trace.go:171","msg":"trace[992420] linearizableReadLoop","detail":"{readStateIndex:608; appliedIndex:604; }","duration":"251.263313ms","start":"2026-04-13T20:54:05.779995Z","end":"2026-04-13T20:54:06.031258Z","steps":["trace[992420] 'read index received' (duration: 144.121098ms)","trace[992420] 'applied index is now lower than readState.Index' (duration: 107.140235ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T20:54:06.031558Z","caller":"traceutil/trace.go:171","msg":"trace[1159105530] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"256.949002ms","start":"2026-04-13T20:54:05.774595Z","end":"2026-04-13T20:54:06.031544Z","steps":["trace[1159105530] 'process raft request' (duration: 256.549971ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T20:54:06.031511Z","caller":"traceutil/trace.go:171","msg":"trace[1592237193] range","detail":"{range_begin:/registry/clusterrolebindings/envoy-gateway-gateway-helm-certgen:envoy-gateway-system; range_end:; response_count:0; response_revision:583; }","duration":"251.533422ms","start":"2026-04-13T20:54:05.779956Z","end":"2026-04-13T20:54:06.031489Z","steps":["trace[1592237193] 'agreement among raft nodes before linearized reading' (duration: 251.420749ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T20:54:06.031793Z","caller":"traceutil/trace.go:171","msg":"trace[1209605302] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"256.182491ms","start":"2026-04-13T20:54:05.775593Z","end":"2026-04-13T20:54:06.031775Z","steps":["trace[1209605302] 'process raft request' (duration: 255.598584ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:54:08.008893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:54:06.485653Z","time spent":"1.523222098s","remote":"127.0.0.1:50356","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-04-13T20:54:08.041667Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":969010079943931177,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-13T20:54:08.073337Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.622898554s","expected-duration":"1s"} {"level":"info","ts":"2026-04-13T20:54:08.07479Z","caller":"traceutil/trace.go:171","msg":"trace[346380968] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"1.625816067s","start":"2026-04-13T20:54:06.448937Z","end":"2026-04-13T20:54:08.074753Z","steps":["trace[346380968] 'process raft request' (duration: 1.625023404s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:54:08.075005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:54:06.4489Z","time spent":"1.625992661s","remote":"127.0.0.1:39330","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036261,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T20:54:08.076798Z","caller":"traceutil/trace.go:171","msg":"trace[508775139] linearizableReadLoop","detail":"{readStateIndex:610; appliedIndex:608; }","duration":"535.420346ms","start":"2026-04-13T20:54:07.54135Z","end":"2026-04-13T20:54:08.07677Z","steps":["trace[508775139] 'read index received' (duration: 532.396112ms)","trace[508775139] 'applied index is now lower than readState.Index' (duration: 3.022664ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T20:54:08.077091Z","caller":"traceutil/trace.go:171","msg":"trace[908019550] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"761.797304ms","start":"2026-04-13T20:54:07.315281Z","end":"2026-04-13T20:54:08.077078Z","steps":["trace[908019550] 'process raft request' (duration: 761.337832ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:54:08.077228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:54:07.315253Z","time spent":"761.870137ms","remote":"127.0.0.1:39508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T20:54:08.07748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"536.137516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.209\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-13T20:54:08.077523Z","caller":"traceutil/trace.go:171","msg":"trace[1933922622] range","detail":"{range_begin:/registry/masterleases/199.19.213.209; range_end:; response_count:1; response_revision:585; }","duration":"536.225809ms","start":"2026-04-13T20:54:07.541284Z","end":"2026-04-13T20:54:08.077509Z","steps":["trace[1933922622] 'agreement among raft nodes before linearized reading' (duration: 536.062345ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:54:08.077561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:54:07.541254Z","time spent":"536.297901ms","remote":"127.0.0.1:39252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/199.19.213.209\" "} {"level":"warn","ts":"2026-04-13T20:54:08.077826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.962383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T20:54:08.077898Z","caller":"traceutil/trace.go:171","msg":"trace[316745794] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:585; }","duration":"125.164018ms","start":"2026-04-13T20:54:07.952724Z","end":"2026-04-13T20:54:08.077888Z","steps":["trace[316745794] 'agreement among raft nodes before linearized reading' (duration: 125.073866ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T20:54:10.033849Z","caller":"traceutil/trace.go:171","msg":"trace[1914567987] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"117.746596ms","start":"2026-04-13T20:54:09.915754Z","end":"2026-04-13T20:54:10.0335Z","steps":["trace[1914567987] 'process raft request' (duration: 116.441369ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T20:54:13.255401Z","caller":"traceutil/trace.go:171","msg":"trace[2020710102] linearizableReadLoop","detail":"{readStateIndex:695; appliedIndex:694; }","duration":"342.620981ms","start":"2026-04-13T20:54:12.912739Z","end":"2026-04-13T20:54:13.25536Z","steps":["trace[2020710102] 'read index received' (duration: 333.736386ms)","trace[2020710102] 'applied index is now lower than readState.Index' (duration: 8.881595ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T20:54:13.255718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.95675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:1 size:10377"} {"level":"info","ts":"2026-04-13T20:54:13.255765Z","caller":"traceutil/trace.go:171","msg":"trace[1207963308] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:1; response_revision:668; }","duration":"343.086814ms","start":"2026-04-13T20:54:12.912665Z","end":"2026-04-13T20:54:13.255752Z","steps":["trace[1207963308] 'agreement among raft nodes before linearized reading' (duration: 342.867788ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:54:13.255811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:54:12.912635Z","time spent":"343.166426ms","remote":"127.0.0.1:39270","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":1,"response size":10401,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" "} {"level":"warn","ts":"2026-04-13T20:54:13.256023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.464527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T20:54:13.256057Z","caller":"traceutil/trace.go:171","msg":"trace[609996798] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:669; }","duration":"302.538091ms","start":"2026-04-13T20:54:12.953511Z","end":"2026-04-13T20:54:13.256049Z","steps":["trace[609996798] 'agreement among raft nodes before linearized reading' (duration: 302.458278ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:54:13.256082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:54:12.953484Z","time spent":"302.592531ms","remote":"127.0.0.1:39234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-13T20:54:13.256142Z","caller":"traceutil/trace.go:171","msg":"trace[1849775669] transaction","detail":"{read_only:false; response_revision:669; number_of_response:1; }","duration":"116.48176ms","start":"2026-04-13T20:54:13.139627Z","end":"2026-04-13T20:54:13.256109Z","steps":["trace[1849775669] 'process raft request' (duration: 115.77422ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T20:54:24.043348Z","caller":"traceutil/trace.go:171","msg":"trace[112393380] transaction","detail":"{read_only:false; response_revision:749; number_of_response:1; }","duration":"446.132843ms","start":"2026-04-13T20:54:23.59719Z","end":"2026-04-13T20:54:24.043323Z","steps":["trace[112393380] 'process raft request' (duration: 445.986218ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:54:24.043504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:54:23.59716Z","time spent":"446.267165ms","remote":"127.0.0.1:39508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T20:54:24.686749Z","caller":"traceutil/trace.go:171","msg":"trace[1118044401] linearizableReadLoop","detail":"{readStateIndex:780; appliedIndex:778; }","duration":"733.068509ms","start":"2026-04-13T20:54:23.953655Z","end":"2026-04-13T20:54:24.686724Z","steps":["trace[1118044401] 'read index received' (duration: 89.569954ms)","trace[1118044401] 'applied index is now lower than readState.Index' (duration: 643.497425ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T20:54:24.686937Z","caller":"traceutil/trace.go:171","msg":"trace[351289751] transaction","detail":"{read_only:false; response_revision:750; number_of_response:1; }","duration":"926.901285ms","start":"2026-04-13T20:54:23.760006Z","end":"2026-04-13T20:54:24.686908Z","steps":["trace[351289751] 'process raft request' (duration: 926.550425ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:54:24.686953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"733.273736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T20:54:24.687239Z","caller":"traceutil/trace.go:171","msg":"trace[2072810075] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:750; }","duration":"733.627986ms","start":"2026-04-13T20:54:23.953591Z","end":"2026-04-13T20:54:24.687219Z","steps":["trace[2072810075] 'agreement among raft nodes before linearized reading' (duration: 733.293136ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:54:24.687245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.167569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-04-13T20:54:24.687305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:54:23.953561Z","time spent":"733.725337ms","remote":"127.0.0.1:39234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-13T20:54:24.687325Z","caller":"traceutil/trace.go:171","msg":"trace[462051359] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:750; }","duration":"461.285923ms","start":"2026-04-13T20:54:24.226009Z","end":"2026-04-13T20:54:24.687294Z","steps":["trace[462051359] 'agreement among raft nodes before linearized reading' (duration: 461.093557ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:54:24.687392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:54:24.225994Z","time spent":"461.375055ms","remote":"127.0.0.1:39508","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-04-13T20:54:24.687333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"485.939676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-04-13T20:54:24.687473Z","caller":"traceutil/trace.go:171","msg":"trace[133247694] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:750; }","duration":"486.134931ms","start":"2026-04-13T20:54:24.20132Z","end":"2026-04-13T20:54:24.687455Z","steps":["trace[133247694] 'agreement among raft nodes before linearized reading' (duration: 485.811103ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:54:24.68752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:54:24.20129Z","time spent":"486.219793ms","remote":"127.0.0.1:39508","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-04-13T20:54:24.687337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.872382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-13T20:54:24.687768Z","caller":"traceutil/trace.go:171","msg":"trace[483456607] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:750; }","duration":"443.221381ms","start":"2026-04-13T20:54:24.244405Z","end":"2026-04-13T20:54:24.687627Z","steps":["trace[483456607] 'agreement among raft nodes before linearized reading' (duration: 442.8094ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:54:24.687867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:54:24.244394Z","time spent":"443.463827ms","remote":"127.0.0.1:39508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-13T20:54:24.68813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:54:23.759986Z","time spent":"927.018338ms","remote":"127.0.0.1:39488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T20:54:24.688296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.590271ms","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-04-13T20:54:24.689048Z","caller":"traceutil/trace.go:171","msg":"trace[2059189414] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:750; }","duration":"496.661113ms","start":"2026-04-13T20:54:24.191659Z","end":"2026-04-13T20:54:24.68832Z","steps":["trace[2059189414] 'agreement among raft nodes before linearized reading' (duration: 495.385388ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:54:24.68913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:54:24.191638Z","time spent":"497.470535ms","remote":"127.0.0.1:39508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-13T20:54:26.931686Z","caller":"traceutil/trace.go:171","msg":"trace[496498402] transaction","detail":"{read_only:false; response_revision:768; number_of_response:1; }","duration":"160.45386ms","start":"2026-04-13T20:54:26.771194Z","end":"2026-04-13T20:54:26.931648Z","steps":["trace[496498402] 'process raft request' (duration: 160.221963ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T20:54:29.313253Z","caller":"traceutil/trace.go:171","msg":"trace[1817651553] transaction","detail":"{read_only:false; response_revision:813; number_of_response:1; }","duration":"116.297005ms","start":"2026-04-13T20:54:29.196922Z","end":"2026-04-13T20:54:29.313219Z","steps":["trace[1817651553] 'process raft request' (duration: 116.092499ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T20:54:29.959103Z","caller":"traceutil/trace.go:171","msg":"trace[876360220] transaction","detail":"{read_only:false; response_revision:816; number_of_response:1; }","duration":"126.854448ms","start":"2026-04-13T20:54:29.832226Z","end":"2026-04-13T20:54:29.95908Z","steps":["trace[876360220] 'process raft request' (duration: 123.68703ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T20:54:34.875366Z","caller":"traceutil/trace.go:171","msg":"trace[111755444] transaction","detail":"{read_only:false; response_revision:875; number_of_response:1; }","duration":"113.459356ms","start":"2026-04-13T20:54:34.761876Z","end":"2026-04-13T20:54:34.875335Z","steps":["trace[111755444] 'process raft request' (duration: 94.833769ms)","trace[111755444] 'compare' (duration: 18.3932ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T20:54:34.875591Z","caller":"traceutil/trace.go:171","msg":"trace[1814318101] transaction","detail":"{read_only:false; response_revision:876; number_of_response:1; }","duration":"112.480379ms","start":"2026-04-13T20:54:34.763101Z","end":"2026-04-13T20:54:34.875581Z","steps":["trace[1814318101] 'process raft request' (duration: 112.16376ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T20:54:48.315292Z","caller":"traceutil/trace.go:171","msg":"trace[1414967386] transaction","detail":"{read_only:false; response_revision:929; number_of_response:1; }","duration":"111.701138ms","start":"2026-04-13T20:54:48.20356Z","end":"2026-04-13T20:54:48.315261Z","steps":["trace[1414967386] 'process raft request' (duration: 111.505503ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:54:55.084893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.686728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T20:54:55.084982Z","caller":"traceutil/trace.go:171","msg":"trace[660006825] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:951; }","duration":"133.826412ms","start":"2026-04-13T20:54:54.951134Z","end":"2026-04-13T20:54:55.08496Z","steps":["trace[660006825] 'range keys from in-memory index tree' (duration: 133.574545ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T20:54:57.12775Z","caller":"traceutil/trace.go:171","msg":"trace[1740106423] transaction","detail":"{read_only:false; response_revision:958; number_of_response:1; }","duration":"109.297941ms","start":"2026-04-13T20:54:57.018424Z","end":"2026-04-13T20:54:57.127722Z","steps":["trace[1740106423] 'process raft request' (duration: 109.042384ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T20:55:01.059601Z","caller":"traceutil/trace.go:171","msg":"trace[160563017] linearizableReadLoop","detail":"{readStateIndex:1012; appliedIndex:1011; }","duration":"164.840412ms","start":"2026-04-13T20:55:00.894722Z","end":"2026-04-13T20:55:01.059562Z","steps":["trace[160563017] 'read index received' (duration: 164.544083ms)","trace[160563017] 'applied index is now lower than readState.Index' (duration: 294.458µs)"],"step_count":2} {"level":"info","ts":"2026-04-13T20:55:01.059699Z","caller":"traceutil/trace.go:171","msg":"trace[2025239672] transaction","detail":"{read_only:false; response_revision:973; number_of_response:1; }","duration":"679.868254ms","start":"2026-04-13T20:55:00.379792Z","end":"2026-04-13T20:55:01.05966Z","steps":["trace[2025239672] 'process raft request' (duration: 679.545585ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:55:01.059863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:55:00.379783Z","time spent":"679.988787ms","remote":"127.0.0.1:39508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T20:55:01.059935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.185591ms","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-04-13T20:55:01.059986Z","caller":"traceutil/trace.go:171","msg":"trace[238669621] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:973; }","duration":"165.266423ms","start":"2026-04-13T20:55:00.894707Z","end":"2026-04-13T20:55:01.059973Z","steps":["trace[238669621] 'agreement among raft nodes before linearized reading' (duration: 165.055767ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:55:01.060058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.592328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T20:55:01.060101Z","caller":"traceutil/trace.go:171","msg":"trace[281927335] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:973; }","duration":"105.65483ms","start":"2026-04-13T20:55:00.954433Z","end":"2026-04-13T20:55:01.060088Z","steps":["trace[281927335] 'agreement among raft nodes before linearized reading' (duration: 105.583248ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:55:01.060159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.340374ms","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-04-13T20:55:01.060307Z","caller":"traceutil/trace.go:171","msg":"trace[2088928512] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:973; }","duration":"161.54179ms","start":"2026-04-13T20:55:00.898742Z","end":"2026-04-13T20:55:01.060284Z","steps":["trace[2088928512] 'agreement among raft nodes before linearized reading' (duration: 161.200351ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T20:55:01.258023Z","caller":"traceutil/trace.go:171","msg":"trace[1445661642] transaction","detail":"{read_only:false; response_revision:974; number_of_response:1; }","duration":"192.979221ms","start":"2026-04-13T20:55:01.064996Z","end":"2026-04-13T20:55:01.257975Z","steps":["trace[1445661642] 'process raft request' (duration: 192.763095ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T20:55:01.269022Z","caller":"traceutil/trace.go:171","msg":"trace[757952143] linearizableReadLoop","detail":"{readStateIndex:1014; appliedIndex:1012; }","duration":"121.815158ms","start":"2026-04-13T20:55:01.14718Z","end":"2026-04-13T20:55:01.268995Z","steps":["trace[757952143] 'read index received' (duration: 110.585797ms)","trace[757952143] 'applied index is now lower than readState.Index' (duration: 11.228091ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T20:55:01.269115Z","caller":"traceutil/trace.go:171","msg":"trace[1675596744] transaction","detail":"{read_only:false; response_revision:975; number_of_response:1; }","duration":"203.806912ms","start":"2026-04-13T20:55:01.065285Z","end":"2026-04-13T20:55:01.269092Z","steps":["trace[1675596744] 'process raft request' (duration: 203.567775ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:55:01.269324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.006961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-04-13T20:55:01.269331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.127457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-13T20:55:01.269367Z","caller":"traceutil/trace.go:171","msg":"trace[1085042850] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:975; }","duration":"119.123204ms","start":"2026-04-13T20:55:01.150232Z","end":"2026-04-13T20:55:01.269356Z","steps":["trace[1085042850] 'agreement among raft nodes before linearized reading' (duration: 118.976859ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T20:55:01.269397Z","caller":"traceutil/trace.go:171","msg":"trace[1989109121] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:975; }","duration":"122.190759ms","start":"2026-04-13T20:55:01.147169Z","end":"2026-04-13T20:55:01.26936Z","steps":["trace[1989109121] 'agreement among raft nodes before linearized reading' (duration: 121.989743ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:55:02.652353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"648.511364ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-13T20:55:02.652507Z","caller":"traceutil/trace.go:171","msg":"trace[1646449262] transaction","detail":"{read_only:false; response_revision:976; number_of_response:1; }","duration":"1.379825446s","start":"2026-04-13T20:55:01.272654Z","end":"2026-04-13T20:55:02.65248Z","steps":["trace[1646449262] 'process raft request' (duration: 731.054155ms)","trace[1646449262] 'compare' (duration: 648.263627ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T20:55:02.652584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:55:01.272624Z","time spent":"1.379925217s","remote":"127.0.0.1:39508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T20:55:02.947488Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":969010079943931984,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-13T20:55:03.094566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.705174ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T20:55:03.0947Z","caller":"traceutil/trace.go:171","msg":"trace[602047622] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:976; }","duration":"293.859069ms","start":"2026-04-13T20:55:02.800821Z","end":"2026-04-13T20:55:03.09468Z","steps":["trace[602047622] 'range keys from in-memory index tree' (duration: 293.682164ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T20:55:03.095421Z","caller":"traceutil/trace.go:171","msg":"trace[1729308117] transaction","detail":"{read_only:false; response_revision:977; number_of_response:1; }","duration":"1.820919957s","start":"2026-04-13T20:55:01.274458Z","end":"2026-04-13T20:55:03.095378Z","steps":["trace[1729308117] 'process raft request' (duration: 1.727316782s)","trace[1729308117] 'compare' (duration: 93.300986ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T20:55:03.095582Z","caller":"traceutil/trace.go:171","msg":"trace[614312095] transaction","detail":"{read_only:false; response_revision:979; number_of_response:1; }","duration":"1.588386678s","start":"2026-04-13T20:55:01.507173Z","end":"2026-04-13T20:55:03.09556Z","steps":["trace[614312095] 'process raft request' (duration: 1.58808257s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:55:03.095638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:55:01.274447Z","time spent":"1.821077432s","remote":"127.0.0.1:39508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T20:55:03.095599Z","caller":"traceutil/trace.go:171","msg":"trace[1245464202] transaction","detail":"{read_only:false; response_revision:978; number_of_response:1; }","duration":"1.656246119s","start":"2026-04-13T20:55:01.439251Z","end":"2026-04-13T20:55:03.095497Z","steps":["trace[1245464202] 'process raft request' (duration: 1.655943932s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:55:03.095722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:55:01.507148Z","time spent":"1.588491581s","remote":"127.0.0.1:39508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T20:55:03.095844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:55:01.439229Z","time spent":"1.656477736s","remote":"127.0.0.1:39508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T20:55:03.360127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.814513ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-04-13T20:55:03.360269Z","caller":"traceutil/trace.go:171","msg":"trace[1265866274] linearizableReadLoop","detail":"{readStateIndex:1019; appliedIndex:1015; }","duration":"913.188773ms","start":"2026-04-13T20:55:02.447057Z","end":"2026-04-13T20:55:03.360246Z","steps":["trace[1265866274] 'read index received' (duration: 554.730253ms)","trace[1265866274] 'applied index is now lower than readState.Index' (duration: 358.45661ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T20:55:03.360553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.18352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T20:55:03.360595Z","caller":"traceutil/trace.go:171","msg":"trace[1379154975] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:979; }","duration":"407.283923ms","start":"2026-04-13T20:55:02.9533Z","end":"2026-04-13T20:55:03.360584Z","steps":["trace[1379154975] 'agreement among raft nodes before linearized reading' (duration: 407.207571ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:55:03.360626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:55:02.95327Z","time spent":"407.348114ms","remote":"127.0.0.1:39234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-13T20:55:03.36062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"913.535693ms","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-04-13T20:55:03.360737Z","caller":"traceutil/trace.go:171","msg":"trace[1075132938] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:979; }","duration":"913.713076ms","start":"2026-04-13T20:55:02.446989Z","end":"2026-04-13T20:55:03.360702Z","steps":["trace[1075132938] 'agreement among raft nodes before linearized reading' (duration: 913.514701ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:55:03.360791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:55:02.446965Z","time spent":"913.815201ms","remote":"127.0.0.1:39756","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":29,"request content":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-04-13T20:55:03.360827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.255926ms","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-04-13T20:55:03.36086Z","caller":"traceutil/trace.go:171","msg":"trace[1749580522] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:979; }","duration":"295.332868ms","start":"2026-04-13T20:55:03.065519Z","end":"2026-04-13T20:55:03.360851Z","steps":["trace[1749580522] 'agreement among raft nodes before linearized reading' (duration: 295.245206ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:55:25.877403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.701519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-13T20:55:25.877525Z","caller":"traceutil/trace.go:171","msg":"trace[35414678] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:1054; }","duration":"165.914435ms","start":"2026-04-13T20:55:25.711584Z","end":"2026-04-13T20:55:25.877499Z","steps":["trace[35414678] 'count revisions from in-memory index tree' (duration: 165.556094ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:55:29.454374Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":969010079943932212,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-13T20:55:29.473376Z","caller":"traceutil/trace.go:171","msg":"trace[415624774] transaction","detail":"{read_only:false; response_revision:1062; number_of_response:1; }","duration":"575.574106ms","start":"2026-04-13T20:55:28.897769Z","end":"2026-04-13T20:55:29.473343Z","steps":["trace[415624774] 'process raft request' (duration: 575.313098ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:55:29.473592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:55:28.897746Z","time spent":"575.738611ms","remote":"127.0.0.1:39508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T20:55:29.661184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.134285ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-13T20:55:29.661371Z","caller":"traceutil/trace.go:171","msg":"trace[1167408817] linearizableReadLoop","detail":"{readStateIndex:1108; appliedIndex:1106; }","duration":"707.671889ms","start":"2026-04-13T20:55:28.953677Z","end":"2026-04-13T20:55:29.661349Z","steps":["trace[1167408817] 'read index received' (duration: 519.452965ms)","trace[1167408817] 'applied index is now lower than readState.Index' (duration: 188.217504ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T20:55:29.661526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"631.385308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T20:55:29.661497Z","caller":"traceutil/trace.go:171","msg":"trace[133569022] transaction","detail":"{read_only:false; response_revision:1063; number_of_response:1; }","duration":"461.421871ms","start":"2026-04-13T20:55:29.200031Z","end":"2026-04-13T20:55:29.661453Z","steps":["trace[133569022] 'process raft request' (duration: 337.899616ms)","trace[133569022] 'compare' (duration: 122.963509ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T20:55:29.661559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"707.858164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T20:55:29.661627Z","caller":"traceutil/trace.go:171","msg":"trace[373124913] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1063; }","duration":"707.975197ms","start":"2026-04-13T20:55:28.953633Z","end":"2026-04-13T20:55:29.661609Z","steps":["trace[373124913] 'agreement among raft nodes before linearized reading' (duration: 707.817753ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:55:29.661715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:55:29.199995Z","time spent":"461.610536ms","remote":"127.0.0.1:39508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T20:55:29.661775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:55:28.953561Z","time spent":"708.198993ms","remote":"127.0.0.1:39234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-13T20:55:29.661562Z","caller":"traceutil/trace.go:171","msg":"trace[1909179743] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:1063; }","duration":"631.468659ms","start":"2026-04-13T20:55:29.030083Z","end":"2026-04-13T20:55:29.661551Z","steps":["trace[1909179743] 'agreement among raft nodes before linearized reading' (duration: 631.392938ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:55:29.662046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:55:29.030051Z","time spent":"631.940423ms","remote":"127.0.0.1:39454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":29,"request content":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true "} {"level":"warn","ts":"2026-04-13T20:55:29.662176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.111708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-13T20:55:29.66224Z","caller":"traceutil/trace.go:171","msg":"trace[870255344] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1063; }","duration":"422.212032ms","start":"2026-04-13T20:55:29.240017Z","end":"2026-04-13T20:55:29.662229Z","steps":["trace[870255344] 'agreement among raft nodes before linearized reading' (duration: 421.961214ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:55:29.662295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:55:29.239992Z","time spent":"422.293513ms","remote":"127.0.0.1:39508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-13T20:55:29.838807Z","caller":"traceutil/trace.go:171","msg":"trace[2098402719] transaction","detail":"{read_only:false; response_revision:1065; number_of_response:1; }","duration":"171.224352ms","start":"2026-04-13T20:55:29.667555Z","end":"2026-04-13T20:55:29.838779Z","steps":["trace[2098402719] 'process raft request' (duration: 107.696655ms)","trace[2098402719] 'compare' (duration: 63.349192ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T20:55:29.838963Z","caller":"traceutil/trace.go:171","msg":"trace[1739980427] linearizableReadLoop","detail":"{readStateIndex:1111; appliedIndex:1109; }","duration":"148.88953ms","start":"2026-04-13T20:55:29.690039Z","end":"2026-04-13T20:55:29.838928Z","steps":["trace[1739980427] 'read index received' (duration: 85.132607ms)","trace[1739980427] 'applied index is now lower than readState.Index' (duration: 63.754873ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T20:55:29.839152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.077515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-13T20:55:29.839191Z","caller":"traceutil/trace.go:171","msg":"trace[1836247968] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1066; }","duration":"149.18662ms","start":"2026-04-13T20:55:29.689994Z","end":"2026-04-13T20:55:29.83918Z","steps":["trace[1836247968] 'agreement among raft nodes before linearized reading' (duration: 149.069086ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T20:55:29.839145Z","caller":"traceutil/trace.go:171","msg":"trace[1902004620] transaction","detail":"{read_only:false; response_revision:1066; number_of_response:1; }","duration":"171.453508ms","start":"2026-04-13T20:55:29.667614Z","end":"2026-04-13T20:55:29.839068Z","steps":["trace[1902004620] 'process raft request' (duration: 171.123748ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:55:32.194884Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":969010079943932230,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-13T20:55:32.66516Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.182498874s","expected-duration":"1s"} {"level":"info","ts":"2026-04-13T20:55:32.66565Z","caller":"traceutil/trace.go:171","msg":"trace[1873722855] transaction","detail":"{read_only:false; response_revision:1069; number_of_response:1; }","duration":"1.18307883s","start":"2026-04-13T20:55:31.48255Z","end":"2026-04-13T20:55:32.665629Z","steps":["trace[1873722855] 'process raft request' (duration: 1.182922866s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:55:32.665891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:55:31.482525Z","time spent":"1.183281515s","remote":"127.0.0.1:39508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T20:55:33.356169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"555.544949ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T20:55:33.356321Z","caller":"traceutil/trace.go:171","msg":"trace[1243205767] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1069; }","duration":"555.727224ms","start":"2026-04-13T20:55:32.800575Z","end":"2026-04-13T20:55:33.356302Z","steps":["trace[1243205767] 'range keys from in-memory index tree' (duration: 555.522018ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T20:55:33.356506Z","caller":"traceutil/trace.go:171","msg":"trace[1757152781] transaction","detail":"{read_only:false; response_revision:1070; number_of_response:1; }","duration":"1.689092991s","start":"2026-04-13T20:55:31.667379Z","end":"2026-04-13T20:55:33.356472Z","steps":["trace[1757152781] 'process raft request' (duration: 1.607310147s)","trace[1757152781] 'compare' (duration: 81.508357ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T20:55:33.356492Z","caller":"traceutil/trace.go:171","msg":"trace[351110953] linearizableReadLoop","detail":"{readStateIndex:1115; appliedIndex:1114; }","duration":"1.661923005s","start":"2026-04-13T20:55:31.694545Z","end":"2026-04-13T20:55:33.356468Z","steps":["trace[351110953] 'read index received' (duration: 971.267039ms)","trace[351110953] 'applied index is now lower than readState.Index' (duration: 690.654596ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T20:55:33.356668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:55:31.667356Z","time spent":"1.689229386s","remote":"127.0.0.1:39508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T20:55:33.357304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.662749298s","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-04-13T20:55:33.357364Z","caller":"traceutil/trace.go:171","msg":"trace[1652709149] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1070; }","duration":"1.662835492s","start":"2026-04-13T20:55:31.694508Z","end":"2026-04-13T20:55:33.357344Z","steps":["trace[1652709149] 'agreement among raft nodes before linearized reading' (duration: 1.662183124s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:55:33.357571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:55:31.694491Z","time spent":"1.663058888s","remote":"127.0.0.1:39508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-13T20:55:33.429775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.46389211s","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":"warn","ts":"2026-04-13T20:55:33.429808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"945.653998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-13T20:55:33.429859Z","caller":"traceutil/trace.go:171","msg":"trace[2052611313] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:1070; }","duration":"1.464041624s","start":"2026-04-13T20:55:31.965799Z","end":"2026-04-13T20:55:33.42984Z","steps":["trace[2052611313] 'agreement among raft nodes before linearized reading' (duration: 1.463884139s)"],"step_count":1} {"level":"info","ts":"2026-04-13T20:55:33.42989Z","caller":"traceutil/trace.go:171","msg":"trace[1720283359] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1070; }","duration":"945.798862ms","start":"2026-04-13T20:55:32.484073Z","end":"2026-04-13T20:55:33.429872Z","steps":["trace[1720283359] 'agreement among raft nodes before linearized reading' (duration: 945.569096ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:55:33.429895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.58585392s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-13T20:55:33.429778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.585874262s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-13T20:55:33.42991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.659874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-13T20:55:33.42995Z","caller":"traceutil/trace.go:171","msg":"trace[23078565] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1070; }","duration":"1.585963235s","start":"2026-04-13T20:55:31.843971Z","end":"2026-04-13T20:55:33.429934Z","steps":["trace[23078565] 'agreement among raft nodes before linearized reading' (duration: 1.585851172s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:55:33.429982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.104521586s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-13T20:55:33.429993Z","caller":"traceutil/trace.go:171","msg":"trace[1520421538] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:1070; }","duration":"249.801498ms","start":"2026-04-13T20:55:33.180172Z","end":"2026-04-13T20:55:33.429974Z","steps":["trace[1520421538] 'agreement among raft nodes before linearized reading' (duration: 249.636253ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T20:55:33.430007Z","caller":"traceutil/trace.go:171","msg":"trace[864272131] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:1070; }","duration":"1.104588576s","start":"2026-04-13T20:55:32.325412Z","end":"2026-04-13T20:55:33.43Z","steps":["trace[864272131] 'agreement among raft nodes before linearized reading' (duration: 1.104546245s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:55:33.430002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:55:31.84394Z","time spent":"1.586045966s","remote":"127.0.0.1:39508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-13T20:55:33.430069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:55:32.325387Z","time spent":"1.10465424s","remote":"127.0.0.1:39516","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":3,"response size":31,"request content":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true "} {"level":"warn","ts":"2026-04-13T20:55:33.430069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.803225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-13T20:55:33.430095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.310250757s","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-04-13T20:55:33.430162Z","caller":"traceutil/trace.go:171","msg":"trace[2033350967] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1070; }","duration":"474.932018ms","start":"2026-04-13T20:55:32.955217Z","end":"2026-04-13T20:55:33.430149Z","steps":["trace[2033350967] 'agreement among raft nodes before linearized reading' (duration: 474.753833ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:55:33.430161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"672.368177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T20:55:33.430167Z","caller":"traceutil/trace.go:171","msg":"trace[731283571] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1070; }","duration":"1.310402971s","start":"2026-04-13T20:55:32.119755Z","end":"2026-04-13T20:55:33.430158Z","steps":["trace[731283571] 'agreement among raft nodes before linearized reading' (duration: 1.310265867s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:55:33.430496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:55:32.119717Z","time spent":"1.310765831s","remote":"127.0.0.1:39656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":31,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-04-13T20:55:33.429908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:55:31.965772Z","time spent":"1.464124966s","remote":"127.0.0.1:39620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":31,"request content":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true "} {"level":"info","ts":"2026-04-13T20:55:33.430428Z","caller":"traceutil/trace.go:171","msg":"trace[1863225192] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:1070; }","duration":"672.668396ms","start":"2026-04-13T20:55:32.75774Z","end":"2026-04-13T20:55:33.430408Z","steps":["trace[1863225192] 'agreement among raft nodes before linearized reading' (duration: 672.337767ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T20:55:33.429941Z","caller":"traceutil/trace.go:171","msg":"trace[791037949] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1070; }","duration":"1.586081748s","start":"2026-04-13T20:55:31.843851Z","end":"2026-04-13T20:55:33.429932Z","steps":["trace[791037949] 'agreement among raft nodes before linearized reading' (duration: 1.585758269s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:55:33.430692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:55:32.757673Z","time spent":"672.969335ms","remote":"127.0.0.1:39646","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":29,"request content":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true "} {"level":"warn","ts":"2026-04-13T20:55:33.43079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:55:31.843831Z","time spent":"1.586939291s","remote":"127.0.0.1:39508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-13T20:55:33.429941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:55:32.484055Z","time spent":"945.876064ms","remote":"127.0.0.1:39778","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":93,"response size":31,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"warn","ts":"2026-04-13T20:55:33.430232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:55:32.955196Z","time spent":"475.025671ms","remote":"127.0.0.1:39234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-13T20:55:33.430233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.500019964s","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-04-13T20:55:33.431248Z","caller":"traceutil/trace.go:171","msg":"trace[228486915] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1070; }","duration":"1.501169486s","start":"2026-04-13T20:55:31.930065Z","end":"2026-04-13T20:55:33.431234Z","steps":["trace[228486915] 'agreement among raft nodes before linearized reading' (duration: 1.499703905s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:55:33.431285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:55:31.929986Z","time spent":"1.50128759s","remote":"127.0.0.1:39508","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-04-13T20:55:33.839202Z","caller":"traceutil/trace.go:171","msg":"trace[1241741276] transaction","detail":"{read_only:false; response_revision:1074; number_of_response:1; }","duration":"403.994146ms","start":"2026-04-13T20:55:33.435183Z","end":"2026-04-13T20:55:33.839178Z","steps":["trace[1241741276] 'process raft request' (duration: 403.953574ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:55:33.839347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:55:33.435174Z","time spent":"404.100528ms","remote":"127.0.0.1:39508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T20:55:33.839334Z","caller":"traceutil/trace.go:171","msg":"trace[912875441] transaction","detail":"{read_only:false; response_revision:1072; number_of_response:1; }","duration":"404.443738ms","start":"2026-04-13T20:55:33.434851Z","end":"2026-04-13T20:55:33.839295Z","steps":["trace[912875441] 'process raft request' (duration: 404.105429ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:55:33.839578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:55:33.434833Z","time spent":"404.584201ms","remote":"127.0.0.1:39508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T20:55:33.839607Z","caller":"traceutil/trace.go:171","msg":"trace[2123245697] transaction","detail":"{read_only:false; response_revision:1073; number_of_response:1; }","duration":"404.465297ms","start":"2026-04-13T20:55:33.435117Z","end":"2026-04-13T20:55:33.839583Z","steps":["trace[2123245697] 'process raft request' (duration: 403.983794ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:55:33.839769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:55:33.434874Z","time spent":"404.811217ms","remote":"127.0.0.1:39508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T20:55:35.56752Z","caller":"traceutil/trace.go:171","msg":"trace[306283188] transaction","detail":"{read_only:false; response_revision:1078; number_of_response:1; }","duration":"111.147901ms","start":"2026-04-13T20:55:35.456326Z","end":"2026-04-13T20:55:35.567474Z","steps":["trace[306283188] 'process raft request' (duration: 45.344501ms)","trace[306283188] 'compare' (duration: 65.590034ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T20:55:35.983028Z","caller":"traceutil/trace.go:171","msg":"trace[206233178] transaction","detail":"{read_only:false; response_revision:1079; number_of_response:1; }","duration":"133.912873ms","start":"2026-04-13T20:55:35.849085Z","end":"2026-04-13T20:55:35.982997Z","steps":["trace[206233178] 'process raft request' (duration: 133.674916ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T20:55:36.047683Z","caller":"traceutil/trace.go:171","msg":"trace[1336181103] transaction","detail":"{read_only:false; response_revision:1081; number_of_response:1; }","duration":"197.243684ms","start":"2026-04-13T20:55:35.850392Z","end":"2026-04-13T20:55:36.047636Z","steps":["trace[1336181103] 'process raft request' (duration: 197.187553ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T20:55:36.047699Z","caller":"traceutil/trace.go:171","msg":"trace[1684448761] transaction","detail":"{read_only:false; response_revision:1080; number_of_response:1; }","duration":"197.376317ms","start":"2026-04-13T20:55:35.850278Z","end":"2026-04-13T20:55:36.047654Z","steps":["trace[1684448761] 'process raft request' (duration: 162.748695ms)","trace[1684448761] 'compare' (duration: 34.385546ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T20:55:37.719678Z","caller":"traceutil/trace.go:171","msg":"trace[942441417] linearizableReadLoop","detail":"{readStateIndex:1131; appliedIndex:1130; }","duration":"147.201864ms","start":"2026-04-13T20:55:37.572451Z","end":"2026-04-13T20:55:37.719653Z","steps":["trace[942441417] 'read index received' (duration: 123.161185ms)","trace[942441417] 'applied index is now lower than readState.Index' (duration: 24.037809ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T20:55:37.719777Z","caller":"traceutil/trace.go:171","msg":"trace[1872093445] transaction","detail":"{read_only:false; response_revision:1084; number_of_response:1; }","duration":"162.751686ms","start":"2026-04-13T20:55:37.556964Z","end":"2026-04-13T20:55:37.719716Z","steps":["trace[1872093445] 'process raft request' (duration: 138.711028ms)","trace[1872093445] 'compare' (duration: 23.855444ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T20:55:37.719953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.491002ms","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-04-13T20:55:37.719999Z","caller":"traceutil/trace.go:171","msg":"trace[1899602141] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1084; }","duration":"147.598985ms","start":"2026-04-13T20:55:37.572389Z","end":"2026-04-13T20:55:37.719988Z","steps":["trace[1899602141] 'agreement among raft nodes before linearized reading' (duration: 147.380059ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T20:55:46.30185Z","caller":"traceutil/trace.go:171","msg":"trace[1773668468] transaction","detail":"{read_only:false; response_revision:1113; number_of_response:1; }","duration":"143.205562ms","start":"2026-04-13T20:55:46.158619Z","end":"2026-04-13T20:55:46.301824Z","steps":["trace[1773668468] 'process raft request' (duration: 130.594061ms)","trace[1773668468] 'compare' (duration: 12.274602ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T20:55:46.330799Z","caller":"traceutil/trace.go:171","msg":"trace[996991727] transaction","detail":"{read_only:false; response_revision:1114; number_of_response:1; }","duration":"172.127106ms","start":"2026-04-13T20:55:46.158651Z","end":"2026-04-13T20:55:46.330778Z","steps":["trace[996991727] 'process raft request' (duration: 172.031163ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T20:55:47.125729Z","caller":"traceutil/trace.go:171","msg":"trace[1296061251] linearizableReadLoop","detail":"{readStateIndex:1165; appliedIndex:1164; }","duration":"173.229618ms","start":"2026-04-13T20:55:46.952473Z","end":"2026-04-13T20:55:47.125703Z","steps":["trace[1296061251] 'read index received' (duration: 173.012662ms)","trace[1296061251] 'applied index is now lower than readState.Index' (duration: 215.946µs)"],"step_count":2} {"level":"info","ts":"2026-04-13T20:55:47.125816Z","caller":"traceutil/trace.go:171","msg":"trace[1266498906] transaction","detail":"{read_only:false; response_revision:1116; number_of_response:1; }","duration":"180.602453ms","start":"2026-04-13T20:55:46.945164Z","end":"2026-04-13T20:55:47.125766Z","steps":["trace[1266498906] 'process raft request' (duration: 180.393547ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:55:47.125961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.462054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T20:55:47.126149Z","caller":"traceutil/trace.go:171","msg":"trace[1206805382] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1116; }","duration":"173.700841ms","start":"2026-04-13T20:55:46.952425Z","end":"2026-04-13T20:55:47.126126Z","steps":["trace[1206805382] 'agreement among raft nodes before linearized reading' (duration: 173.375152ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T20:55:47.485699Z","caller":"traceutil/trace.go:171","msg":"trace[324408590] transaction","detail":"{read_only:false; response_revision:1125; number_of_response:1; }","duration":"209.64776ms","start":"2026-04-13T20:55:47.27602Z","end":"2026-04-13T20:55:47.485668Z","steps":["trace[324408590] 'process raft request' (duration: 209.597689ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T20:55:47.485778Z","caller":"traceutil/trace.go:171","msg":"trace[343285526] transaction","detail":"{read_only:false; response_revision:1124; number_of_response:1; }","duration":"210.190185ms","start":"2026-04-13T20:55:47.275544Z","end":"2026-04-13T20:55:47.485734Z","steps":["trace[343285526] 'process raft request' (duration: 116.16256ms)","trace[343285526] 'compare' (duration: 93.718476ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T20:55:47.82122Z","caller":"traceutil/trace.go:171","msg":"trace[1722460359] transaction","detail":"{read_only:false; response_revision:1128; number_of_response:1; }","duration":"272.363554ms","start":"2026-04-13T20:55:47.548819Z","end":"2026-04-13T20:55:47.821182Z","steps":["trace[1722460359] 'process raft request' (duration: 248.341696ms)","trace[1722460359] 'compare' (duration: 23.776672ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T20:55:47.832796Z","caller":"traceutil/trace.go:171","msg":"trace[979956709] transaction","detail":"{read_only:false; response_revision:1129; number_of_response:1; }","duration":"254.652962ms","start":"2026-04-13T20:55:47.578111Z","end":"2026-04-13T20:55:47.832764Z","steps":["trace[979956709] 'process raft request' (duration: 254.539349ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T20:55:49.97169Z","caller":"traceutil/trace.go:171","msg":"trace[62676568] linearizableReadLoop","detail":"{readStateIndex:1225; appliedIndex:1224; }","duration":"112.849899ms","start":"2026-04-13T20:55:49.858818Z","end":"2026-04-13T20:55:49.971668Z","steps":["trace[62676568] 'read index received' (duration: 112.656913ms)","trace[62676568] 'applied index is now lower than readState.Index' (duration: 191.786µs)"],"step_count":2} {"level":"warn","ts":"2026-04-13T20:55:49.971885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.047744ms","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-04-13T20:55:49.971908Z","caller":"traceutil/trace.go:171","msg":"trace[1351310158] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1174; }","duration":"113.118905ms","start":"2026-04-13T20:55:49.858782Z","end":"2026-04-13T20:55:49.971901Z","steps":["trace[1351310158] 'agreement among raft nodes before linearized reading' (duration: 112.982501ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T20:55:49.972355Z","caller":"traceutil/trace.go:171","msg":"trace[2095416591] transaction","detail":"{read_only:false; response_revision:1174; number_of_response:1; }","duration":"145.941628ms","start":"2026-04-13T20:55:49.8264Z","end":"2026-04-13T20:55:49.972341Z","steps":["trace[2095416591] 'process raft request' (duration: 145.139066ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:55:50.531576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.713674ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-13T20:55:50.531802Z","caller":"traceutil/trace.go:171","msg":"trace[2127983640] linearizableReadLoop","detail":"{readStateIndex:1226; appliedIndex:1225; }","duration":"364.159488ms","start":"2026-04-13T20:55:50.16762Z","end":"2026-04-13T20:55:50.53178Z","steps":["trace[2127983640] 'read index received' (duration: 251.142514ms)","trace[2127983640] 'applied index is now lower than readState.Index' (duration: 113.014804ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T20:55:50.531813Z","caller":"traceutil/trace.go:171","msg":"trace[1941020887] transaction","detail":"{read_only:false; response_revision:1175; number_of_response:1; }","duration":"555.853368ms","start":"2026-04-13T20:55:49.97594Z","end":"2026-04-13T20:55:50.531794Z","steps":["trace[1941020887] 'process raft request' (duration: 442.860686ms)","trace[1941020887] 'compare' (duration: 112.643302ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T20:55:50.531892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:55:49.975919Z","time spent":"555.92954ms","remote":"127.0.0.1:39508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T20:55:50.531985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.360953ms","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-04-13T20:55:50.532026Z","caller":"traceutil/trace.go:171","msg":"trace[454979761] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1176; }","duration":"364.439625ms","start":"2026-04-13T20:55:50.167575Z","end":"2026-04-13T20:55:50.532015Z","steps":["trace[454979761] 'agreement among raft nodes before linearized reading' (duration: 364.289441ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:55:50.532062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:55:50.167554Z","time spent":"364.500756ms","remote":"127.0.0.1:39508","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-04-13T20:55:50.532157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.879782ms","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-04-13T20:55:50.532185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.284031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-13T20:55:50.532192Z","caller":"traceutil/trace.go:171","msg":"trace[1937303131] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1176; }","duration":"215.959965ms","start":"2026-04-13T20:55:50.316224Z","end":"2026-04-13T20:55:50.532184Z","steps":["trace[1937303131] 'agreement among raft nodes before linearized reading' (duration: 215.881544ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T20:55:50.532214Z","caller":"traceutil/trace.go:171","msg":"trace[361783390] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1176; }","duration":"190.342613ms","start":"2026-04-13T20:55:50.341862Z","end":"2026-04-13T20:55:50.532205Z","steps":["trace[361783390] 'agreement among raft nodes before linearized reading' (duration: 190.265591ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:55:51.450602Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":969010079943932472,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-13T20:55:51.95165Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":969010079943932472,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-13T20:55:52.452044Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":969010079943932472,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-13T20:55:52.473016Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.648963705s","expected-duration":"1s"} {"level":"info","ts":"2026-04-13T20:55:52.473471Z","caller":"traceutil/trace.go:171","msg":"trace[1402794812] transaction","detail":"{read_only:false; response_revision:1180; number_of_response:1; }","duration":"1.649539801s","start":"2026-04-13T20:55:50.823905Z","end":"2026-04-13T20:55:52.473445Z","steps":["trace[1402794812] 'process raft request' (duration: 1.649336835s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:55:52.473626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:55:50.823879Z","time spent":"1.649643485s","remote":"127.0.0.1:39508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T20:55:52.732364Z","caller":"traceutil/trace.go:171","msg":"trace[1210876014] linearizableReadLoop","detail":"{readStateIndex:1232; appliedIndex:1230; }","duration":"1.782569311s","start":"2026-04-13T20:55:50.949774Z","end":"2026-04-13T20:55:52.732344Z","steps":["trace[1210876014] 'read index received' (duration: 1.523576459s)","trace[1210876014] 'applied index is now lower than readState.Index' (duration: 258.991612ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T20:55:52.732464Z","caller":"traceutil/trace.go:171","msg":"trace[1107484622] transaction","detail":"{read_only:false; response_revision:1181; number_of_response:1; }","duration":"754.991595ms","start":"2026-04-13T20:55:51.977464Z","end":"2026-04-13T20:55:52.732455Z","steps":["trace[1107484622] 'process raft request' (duration: 753.131063ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:55:52.732552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:55:51.977438Z","time spent":"755.044436ms","remote":"127.0.0.1:39508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T20:55:52.732574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"600.510229ms","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-04-13T20:55:52.732668Z","caller":"traceutil/trace.go:171","msg":"trace[809139658] 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:1181; }","duration":"600.617132ms","start":"2026-04-13T20:55:52.132028Z","end":"2026-04-13T20:55:52.732645Z","steps":["trace[809139658] 'agreement among raft nodes before linearized reading' (duration: 600.472198ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:55:52.732711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:55:52.131966Z","time spent":"600.733245ms","remote":"127.0.0.1:50328","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true "} {"level":"warn","ts":"2026-04-13T20:55:52.732725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.436793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-13T20:55:52.732747Z","caller":"traceutil/trace.go:171","msg":"trace[2141014340] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1181; }","duration":"187.500474ms","start":"2026-04-13T20:55:52.54524Z","end":"2026-04-13T20:55:52.73274Z","steps":["trace[2141014340] 'agreement among raft nodes before linearized reading' (duration: 187.410061ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:55:52.73277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.782983112s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-13T20:55:52.732835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.168875ms","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-04-13T20:55:52.732828Z","caller":"traceutil/trace.go:171","msg":"trace[1733204908] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1181; }","duration":"1.783072904s","start":"2026-04-13T20:55:50.949741Z","end":"2026-04-13T20:55:52.732814Z","steps":["trace[1733204908] 'agreement among raft nodes before linearized reading' (duration: 1.78295971s)"],"step_count":1} {"level":"info","ts":"2026-04-13T20:55:52.732851Z","caller":"traceutil/trace.go:171","msg":"trace[2103896966] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1181; }","duration":"187.229497ms","start":"2026-04-13T20:55:52.545617Z","end":"2026-04-13T20:55:52.732846Z","steps":["trace[2103896966] 'agreement among raft nodes before linearized reading' (duration: 187.177235ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:55:52.732872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:55:50.949725Z","time spent":"1.783138146s","remote":"127.0.0.1:39234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-13T20:55:52.732928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.763725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-13T20:55:52.732975Z","caller":"traceutil/trace.go:171","msg":"trace[1418665154] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:1181; }","duration":"477.846888ms","start":"2026-04-13T20:55:52.255115Z","end":"2026-04-13T20:55:52.732962Z","steps":["trace[1418665154] 'agreement among raft nodes before linearized reading' (duration: 477.779346ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:55:52.732982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.34366ms","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-04-13T20:55:52.733005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:55:52.255093Z","time spent":"477.90287ms","remote":"127.0.0.1:39504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":31,"request content":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true "} {"level":"info","ts":"2026-04-13T20:55:52.733039Z","caller":"traceutil/trace.go:171","msg":"trace[402413297] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1181; }","duration":"196.466963ms","start":"2026-04-13T20:55:52.53656Z","end":"2026-04-13T20:55:52.733027Z","steps":["trace[402413297] 'agreement among raft nodes before linearized reading' (duration: 196.30932ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:55:52.733215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.449411ms","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-04-13T20:55:52.733293Z","caller":"traceutil/trace.go:171","msg":"trace[391962853] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1181; }","duration":"188.599535ms","start":"2026-04-13T20:55:52.544675Z","end":"2026-04-13T20:55:52.733275Z","steps":["trace[391962853] 'agreement among raft nodes before linearized reading' (duration: 188.222174ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T20:55:57.006951Z","caller":"traceutil/trace.go:171","msg":"trace[1907743552] transaction","detail":"{read_only:false; response_revision:1193; number_of_response:1; }","duration":"517.565993ms","start":"2026-04-13T20:55:56.489358Z","end":"2026-04-13T20:55:57.006924Z","steps":["trace[1907743552] 'process raft request' (duration: 517.270934ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:55:57.007123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:55:56.489338Z","time spent":"517.692477ms","remote":"127.0.0.1:39508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T20:55:57.010152Z","caller":"traceutil/trace.go:171","msg":"trace[1272469846] linearizableReadLoop","detail":"{readStateIndex:1246; appliedIndex:1244; }","duration":"256.06516ms","start":"2026-04-13T20:55:56.754067Z","end":"2026-04-13T20:55:57.010132Z","steps":["trace[1272469846] 'read index received' (duration: 252.539103ms)","trace[1272469846] 'applied index is now lower than readState.Index' (duration: 3.524507ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T20:55:57.010277Z","caller":"traceutil/trace.go:171","msg":"trace[353024358] transaction","detail":"{read_only:false; response_revision:1194; number_of_response:1; }","duration":"264.401023ms","start":"2026-04-13T20:55:56.745813Z","end":"2026-04-13T20:55:57.010214Z","steps":["trace[353024358] 'process raft request' (duration: 264.203627ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:55:57.010383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.307697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-13T20:55:57.010422Z","caller":"traceutil/trace.go:171","msg":"trace[1821713198] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1194; }","duration":"256.40334ms","start":"2026-04-13T20:55:56.754009Z","end":"2026-04-13T20:55:57.010412Z","steps":["trace[1821713198] 'agreement among raft nodes before linearized reading' (duration: 256.227375ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:55:57.010419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.671669ms","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-04-13T20:55:57.010487Z","caller":"traceutil/trace.go:171","msg":"trace[812911846] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1194; }","duration":"251.793941ms","start":"2026-04-13T20:55:56.758679Z","end":"2026-04-13T20:55:57.010473Z","steps":["trace[812911846] 'agreement among raft nodes before linearized reading' (duration: 251.612326ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:55:57.010601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.091202ms","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-04-13T20:55:57.010626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.484373ms","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-04-13T20:55:57.010635Z","caller":"traceutil/trace.go:171","msg":"trace[471320720] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1194; }","duration":"251.163324ms","start":"2026-04-13T20:55:56.759465Z","end":"2026-04-13T20:55:57.010628Z","steps":["trace[471320720] 'agreement among raft nodes before linearized reading' (duration: 251.073602ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T20:55:57.010643Z","caller":"traceutil/trace.go:171","msg":"trace[916040875] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1194; }","duration":"251.521963ms","start":"2026-04-13T20:55:56.759116Z","end":"2026-04-13T20:55:57.010638Z","steps":["trace[916040875] 'agreement among raft nodes before linearized reading' (duration: 251.467852ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T20:56:11.057505Z","caller":"traceutil/trace.go:171","msg":"trace[54581764] linearizableReadLoop","detail":"{readStateIndex:1309; appliedIndex:1308; }","duration":"104.449015ms","start":"2026-04-13T20:56:10.953029Z","end":"2026-04-13T20:56:11.057478Z","steps":["trace[54581764] 'read index received' (duration: 104.228659ms)","trace[54581764] 'applied index is now lower than readState.Index' (duration: 219.446µs)"],"step_count":2} {"level":"info","ts":"2026-04-13T20:56:11.057903Z","caller":"traceutil/trace.go:171","msg":"trace[35178122] transaction","detail":"{read_only:false; response_revision:1254; number_of_response:1; }","duration":"172.702272ms","start":"2026-04-13T20:56:10.88519Z","end":"2026-04-13T20:56:11.057892Z","steps":["trace[35178122] 'process raft request' (duration: 172.145717ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:56:11.058121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.078272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T20:56:11.058166Z","caller":"traceutil/trace.go:171","msg":"trace[112563522] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1254; }","duration":"105.176464ms","start":"2026-04-13T20:56:10.952979Z","end":"2026-04-13T20:56:11.058156Z","steps":["trace[112563522] 'agreement among raft nodes before linearized reading' (duration: 105.092072ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T20:56:12.363435Z","caller":"traceutil/trace.go:171","msg":"trace[541542684] transaction","detail":"{read_only:false; response_revision:1269; number_of_response:1; }","duration":"171.248872ms","start":"2026-04-13T20:56:12.192156Z","end":"2026-04-13T20:56:12.363405Z","steps":["trace[541542684] 'process raft request' (duration: 171.094558ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T20:56:15.629554Z","caller":"traceutil/trace.go:171","msg":"trace[788279751] linearizableReadLoop","detail":"{readStateIndex:1351; appliedIndex:1350; }","duration":"447.954828ms","start":"2026-04-13T20:56:15.181579Z","end":"2026-04-13T20:56:15.629533Z","steps":["trace[788279751] 'read index received' (duration: 447.770223ms)","trace[788279751] 'applied index is now lower than readState.Index' (duration: 183.435µs)"],"step_count":2} {"level":"info","ts":"2026-04-13T20:56:15.629691Z","caller":"traceutil/trace.go:171","msg":"trace[9172375] transaction","detail":"{read_only:false; response_revision:1295; number_of_response:1; }","duration":"454.411187ms","start":"2026-04-13T20:56:15.175224Z","end":"2026-04-13T20:56:15.629635Z","steps":["trace[9172375] 'process raft request' (duration: 454.156749ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:56:15.62978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.179184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-13T20:56:15.629808Z","caller":"traceutil/trace.go:171","msg":"trace[1693403296] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1295; }","duration":"448.250085ms","start":"2026-04-13T20:56:15.181549Z","end":"2026-04-13T20:56:15.629799Z","steps":["trace[1693403296] 'agreement among raft nodes before linearized reading' (duration: 448.104792ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:56:15.629838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:56:15.181537Z","time spent":"448.295957ms","remote":"127.0.0.1:39508","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-04-13T20:56:15.629955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.780587ms","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-04-13T20:56:15.629934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:56:15.175198Z","time spent":"454.609991ms","remote":"127.0.0.1:39508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T20:56:15.629977Z","caller":"traceutil/trace.go:171","msg":"trace[128484862] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1295; }","duration":"436.834627ms","start":"2026-04-13T20:56:15.193137Z","end":"2026-04-13T20:56:15.629971Z","steps":["trace[128484862] 'agreement among raft nodes before linearized reading' (duration: 436.783185ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:56:15.629992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:56:15.193112Z","time spent":"436.87602ms","remote":"127.0.0.1:39508","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-04-13T20:56:15.630206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.504299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-04-13T20:56:15.630204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.943926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-13T20:56:15.630279Z","caller":"traceutil/trace.go:171","msg":"trace[1902851538] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1295; }","duration":"436.571719ms","start":"2026-04-13T20:56:15.193669Z","end":"2026-04-13T20:56:15.63024Z","steps":["trace[1902851538] 'agreement among raft nodes before linearized reading' (duration: 436.419406ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T20:56:15.630298Z","caller":"traceutil/trace.go:171","msg":"trace[153527638] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1295; }","duration":"435.048798ms","start":"2026-04-13T20:56:15.195233Z","end":"2026-04-13T20:56:15.630282Z","steps":["trace[153527638] 'agreement among raft nodes before linearized reading' (duration: 434.835122ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:56:15.630295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.55683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-04-13T20:56:15.630333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:56:15.195227Z","time spent":"435.098759ms","remote":"127.0.0.1:39508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-13T20:56:15.630346Z","caller":"traceutil/trace.go:171","msg":"trace[1062798215] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1295; }","duration":"436.610231ms","start":"2026-04-13T20:56:15.193722Z","end":"2026-04-13T20:56:15.630333Z","steps":["trace[1062798215] 'agreement among raft nodes before linearized reading' (duration: 436.438147ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:56:15.630314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:56:15.193648Z","time spent":"436.658404ms","remote":"127.0.0.1:39508","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-04-13T20:56:15.630383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:56:15.193705Z","time spent":"436.670054ms","remote":"127.0.0.1:39508","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-04-13T20:56:15.750596Z","caller":"traceutil/trace.go:171","msg":"trace[1211930599] transaction","detail":"{read_only:false; response_revision:1296; number_of_response:1; }","duration":"115.629926ms","start":"2026-04-13T20:56:15.634936Z","end":"2026-04-13T20:56:15.750566Z","steps":["trace[1211930599] 'process raft request' (duration: 57.335954ms)","trace[1211930599] 'compare' (duration: 58.083296ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T20:56:15.750911Z","caller":"traceutil/trace.go:171","msg":"trace[108559227] transaction","detail":"{read_only:false; response_revision:1297; number_of_response:1; }","duration":"115.944103ms","start":"2026-04-13T20:56:15.634918Z","end":"2026-04-13T20:56:15.750862Z","steps":["trace[108559227] 'process raft request' (duration: 115.593244ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T20:56:15.750981Z","caller":"traceutil/trace.go:171","msg":"trace[75881479] transaction","detail":"{read_only:false; response_revision:1300; number_of_response:1; }","duration":"114.925275ms","start":"2026-04-13T20:56:15.636045Z","end":"2026-04-13T20:56:15.75097Z","steps":["trace[75881479] 'process raft request' (duration: 114.900765ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T20:56:15.751137Z","caller":"traceutil/trace.go:171","msg":"trace[993968426] transaction","detail":"{read_only:false; response_revision:1299; number_of_response:1; }","duration":"115.666676ms","start":"2026-04-13T20:56:15.635448Z","end":"2026-04-13T20:56:15.751115Z","steps":["trace[993968426] 'process raft request' (duration: 115.474781ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T20:56:15.751101Z","caller":"traceutil/trace.go:171","msg":"trace[1698670435] transaction","detail":"{read_only:false; response_revision:1298; number_of_response:1; }","duration":"115.595784ms","start":"2026-04-13T20:56:15.635455Z","end":"2026-04-13T20:56:15.75105Z","steps":["trace[1698670435] 'process raft request' (duration: 115.345957ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:56:18.153042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:56:17.549039Z","time spent":"603.998157ms","remote":"127.0.0.1:39252","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-04-13T20:56:18.383614Z","caller":"traceutil/trace.go:171","msg":"trace[1456124921] linearizableReadLoop","detail":"{readStateIndex:1358; appliedIndex:1356; }","duration":"627.865651ms","start":"2026-04-13T20:56:17.755724Z","end":"2026-04-13T20:56:18.383589Z","steps":["trace[1456124921] 'read index received' (duration: 397.149684ms)","trace[1456124921] 'applied index is now lower than readState.Index' (duration: 230.714746ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T20:56:18.383751Z","caller":"traceutil/trace.go:171","msg":"trace[1333296876] transaction","detail":"{read_only:false; response_revision:1301; number_of_response:1; }","duration":"746.715806ms","start":"2026-04-13T20:56:17.636988Z","end":"2026-04-13T20:56:18.383703Z","steps":["trace[1333296876] 'process raft request' (duration: 746.437218ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:56:18.383994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:56:17.636952Z","time spent":"746.90339ms","remote":"127.0.0.1:39508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T20:56:18.384038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"628.287881ms","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-04-13T20:56:18.384219Z","caller":"traceutil/trace.go:171","msg":"trace[789031464] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1301; }","duration":"628.401775ms","start":"2026-04-13T20:56:17.755671Z","end":"2026-04-13T20:56:18.384073Z","steps":["trace[789031464] 'agreement among raft nodes before linearized reading' (duration: 628.159248ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:56:18.384274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:56:17.755649Z","time spent":"628.613101ms","remote":"127.0.0.1:39508","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-04-13T20:56:18.671038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.603362ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-13T20:56:18.671303Z","caller":"traceutil/trace.go:171","msg":"trace[1496837015] transaction","detail":"{read_only:false; response_revision:1302; number_of_response:1; }","duration":"516.39988ms","start":"2026-04-13T20:56:18.154822Z","end":"2026-04-13T20:56:18.671222Z","steps":["trace[1496837015] 'process raft request' (duration: 298.594094ms)","trace[1496837015] 'compare' (duration: 217.349204ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T20:56:18.671349Z","caller":"traceutil/trace.go:171","msg":"trace[1281291547] linearizableReadLoop","detail":"{readStateIndex:1359; appliedIndex:1358; }","duration":"287.532316ms","start":"2026-04-13T20:56:18.383786Z","end":"2026-04-13T20:56:18.671318Z","steps":["trace[1281291547] 'read index received' (duration: 69.558085ms)","trace[1281291547] 'applied index is now lower than readState.Index' (duration: 217.971211ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T20:56:18.671543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:56:18.1548Z","time spent":"516.608136ms","remote":"127.0.0.1:39252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T20:56:18.672037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"915.407766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-13T20:56:18.672053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"915.428817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-04-13T20:56:18.672287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"600.236561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-13T20:56:18.672343Z","caller":"traceutil/trace.go:171","msg":"trace[1880250466] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1303; }","duration":"600.332845ms","start":"2026-04-13T20:56:18.07199Z","end":"2026-04-13T20:56:18.672322Z","steps":["trace[1880250466] 'agreement among raft nodes before linearized reading' (duration: 600.144309ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T20:56:18.672303Z","caller":"traceutil/trace.go:171","msg":"trace[901229705] transaction","detail":"{read_only:false; response_revision:1303; number_of_response:1; }","duration":"279.800651ms","start":"2026-04-13T20:56:18.392484Z","end":"2026-04-13T20:56:18.672285Z","steps":["trace[901229705] 'process raft request' (duration: 278.702191ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:56:18.672419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:56:18.071955Z","time spent":"600.447347ms","remote":"127.0.0.1:39508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-04-13T20:56:18.672344Z","caller":"traceutil/trace.go:171","msg":"trace[2109417056] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1303; }","duration":"915.719585ms","start":"2026-04-13T20:56:17.756574Z","end":"2026-04-13T20:56:18.672293Z","steps":["trace[2109417056] 'agreement among raft nodes before linearized reading' (duration: 915.14923ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:56:18.672596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:56:17.756569Z","time spent":"916.017564ms","remote":"127.0.0.1:39508","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-04-13T20:56:18.672182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"915.318933ms","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-04-13T20:56:18.672729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"915.238152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-04-13T20:56:18.67281Z","caller":"traceutil/trace.go:171","msg":"trace[305562504] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1303; }","duration":"915.945231ms","start":"2026-04-13T20:56:17.756837Z","end":"2026-04-13T20:56:18.672782Z","steps":["trace[305562504] 'agreement among raft nodes before linearized reading' (duration: 914.699386ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T20:56:18.672831Z","caller":"traceutil/trace.go:171","msg":"trace[999555560] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1303; }","duration":"916.27562ms","start":"2026-04-13T20:56:17.756532Z","end":"2026-04-13T20:56:18.672808Z","steps":["trace[999555560] 'agreement among raft nodes before linearized reading' (duration: 914.877091ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:56:18.672906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:56:17.756524Z","time spent":"916.359162ms","remote":"127.0.0.1:39508","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-04-13T20:56:18.672853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:56:17.75683Z","time spent":"916.010232ms","remote":"127.0.0.1:39508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-13T20:56:18.67211Z","caller":"traceutil/trace.go:171","msg":"trace[1158129193] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1303; }","duration":"915.492437ms","start":"2026-04-13T20:56:17.756599Z","end":"2026-04-13T20:56:18.672091Z","steps":["trace[1158129193] 'agreement among raft nodes before linearized reading' (duration: 915.030105ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:56:18.673125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:56:17.756594Z","time spent":"916.515466ms","remote":"127.0.0.1:39508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-13T20:56:18.672212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"894.688139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T20:56:18.673258Z","caller":"traceutil/trace.go:171","msg":"trace[919607574] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:1303; }","duration":"895.768591ms","start":"2026-04-13T20:56:17.77747Z","end":"2026-04-13T20:56:18.673239Z","steps":["trace[919607574] 'agreement among raft nodes before linearized reading' (duration: 894.691471ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:56:18.673319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:56:17.777447Z","time spent":"895.855103ms","remote":"127.0.0.1:39286","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true "} {"level":"info","ts":"2026-04-13T20:56:18.917315Z","caller":"traceutil/trace.go:171","msg":"trace[155531753] transaction","detail":"{read_only:false; response_revision:1305; number_of_response:1; }","duration":"237.72256ms","start":"2026-04-13T20:56:18.679547Z","end":"2026-04-13T20:56:18.91727Z","steps":["trace[155531753] 'process raft request' (duration: 237.35723ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T20:56:18.917324Z","caller":"traceutil/trace.go:171","msg":"trace[828979362] transaction","detail":"{read_only:false; response_revision:1307; number_of_response:1; }","duration":"236.402624ms","start":"2026-04-13T20:56:18.680886Z","end":"2026-04-13T20:56:18.917289Z","steps":["trace[828979362] 'process raft request' (duration: 236.280431ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T20:56:18.917519Z","caller":"traceutil/trace.go:171","msg":"trace[1833593787] linearizableReadLoop","detail":"{readStateIndex:1365; appliedIndex:1360; }","duration":"200.751093ms","start":"2026-04-13T20:56:18.716728Z","end":"2026-04-13T20:56:18.917479Z","steps":["trace[1833593787] 'read index received' (duration: 3.840087ms)","trace[1833593787] 'applied index is now lower than readState.Index' (duration: 196.908136ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T20:56:18.917546Z","caller":"traceutil/trace.go:171","msg":"trace[2064150918] transaction","detail":"{read_only:false; response_revision:1308; number_of_response:1; }","duration":"236.551098ms","start":"2026-04-13T20:56:18.680976Z","end":"2026-04-13T20:56:18.917527Z","steps":["trace[2064150918] 'process raft request' (duration: 236.227199ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:56:18.917893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.145314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-04-13T20:56:18.917941Z","caller":"traceutil/trace.go:171","msg":"trace[1137595602] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1308; }","duration":"201.260837ms","start":"2026-04-13T20:56:18.716669Z","end":"2026-04-13T20:56:18.91793Z","steps":["trace[1137595602] 'agreement among raft nodes before linearized reading' (duration: 200.950508ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T20:56:18.918288Z","caller":"traceutil/trace.go:171","msg":"trace[973706768] transaction","detail":"{read_only:false; response_revision:1306; number_of_response:1; }","duration":"237.416392ms","start":"2026-04-13T20:56:18.679945Z","end":"2026-04-13T20:56:18.917362Z","steps":["trace[973706768] 'process raft request' (duration: 237.174766ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T20:56:19.298883Z","caller":"traceutil/trace.go:171","msg":"trace[1026430520] linearizableReadLoop","detail":"{readStateIndex:1367; appliedIndex:1365; }","duration":"345.059546ms","start":"2026-04-13T20:56:18.953785Z","end":"2026-04-13T20:56:19.298844Z","steps":["trace[1026430520] 'read index received' (duration: 286.347613ms)","trace[1026430520] 'applied index is now lower than readState.Index' (duration: 58.710242ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T20:56:19.299096Z","caller":"traceutil/trace.go:171","msg":"trace[1650253217] transaction","detail":"{read_only:false; response_revision:1310; number_of_response:1; }","duration":"375.983585ms","start":"2026-04-13T20:56:18.923092Z","end":"2026-04-13T20:56:19.299076Z","steps":["trace[1650253217] 'process raft request' (duration: 375.649886ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T20:56:19.29921Z","caller":"traceutil/trace.go:171","msg":"trace[1524320469] transaction","detail":"{read_only:false; response_revision:1309; number_of_response:1; }","duration":"494.138261ms","start":"2026-04-13T20:56:18.805038Z","end":"2026-04-13T20:56:19.299176Z","steps":["trace[1524320469] 'process raft request' (duration: 435.076919ms)","trace[1524320469] 'compare' (duration: 58.120465ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T20:56:19.299294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:56:18.923065Z","time spent":"376.084148ms","remote":"127.0.0.1:39508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T20:56:19.299361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:56:18.805002Z","time spent":"494.289305ms","remote":"127.0.0.1:39300","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":886,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-13T20:56:19.299381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.173411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-13T20:56:19.29947Z","caller":"traceutil/trace.go:171","msg":"trace[1283759268] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:1310; }","duration":"171.324275ms","start":"2026-04-13T20:56:19.128127Z","end":"2026-04-13T20:56:19.299451Z","steps":["trace[1283759268] 'agreement among raft nodes before linearized reading' (duration: 171.141359ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:56:19.299569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.771346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T20:56:19.299689Z","caller":"traceutil/trace.go:171","msg":"trace[717593643] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1310; }","duration":"345.890449ms","start":"2026-04-13T20:56:18.953777Z","end":"2026-04-13T20:56:19.299667Z","steps":["trace[717593643] 'agreement among raft nodes before linearized reading' (duration: 345.496987ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:56:19.299948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:56:18.953733Z","time spent":"346.163526ms","remote":"127.0.0.1:39234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-13T20:56:19.877762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.823779ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-13T20:56:19.878008Z","caller":"traceutil/trace.go:171","msg":"trace[1731072925] transaction","detail":"{read_only:false; response_revision:1311; number_of_response:1; }","duration":"479.112694ms","start":"2026-04-13T20:56:19.398869Z","end":"2026-04-13T20:56:19.877982Z","steps":["trace[1731072925] 'process raft request' (duration: 351.960936ms)","trace[1731072925] 'compare' (duration: 126.593872ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T20:56:19.878143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:56:19.398848Z","time spent":"479.245737ms","remote":"127.0.0.1:39508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T20:56:20.497134Z","caller":"traceutil/trace.go:171","msg":"trace[1935565331] transaction","detail":"{read_only:false; response_revision:1312; number_of_response:1; }","duration":"103.249601ms","start":"2026-04-13T20:56:20.393852Z","end":"2026-04-13T20:56:20.497102Z","steps":["trace[1935565331] 'process raft request' (duration: 103.078126ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T20:56:20.65378Z","caller":"traceutil/trace.go:171","msg":"trace[2000125621] transaction","detail":"{read_only:false; response_revision:1313; number_of_response:1; }","duration":"108.746914ms","start":"2026-04-13T20:56:20.544983Z","end":"2026-04-13T20:56:20.65373Z","steps":["trace[2000125621] 'process raft request' (duration: 27.019684ms)","trace[2000125621] 'compare' (duration: 81.256587ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T20:56:25.252018Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":969010079943932808,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-13T20:56:25.327404Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.017556383s","expected-duration":"1s"} {"level":"info","ts":"2026-04-13T20:56:25.32792Z","caller":"traceutil/trace.go:171","msg":"trace[1398734141] transaction","detail":"{read_only:false; response_revision:1345; number_of_response:1; }","duration":"1.01815802s","start":"2026-04-13T20:56:24.309726Z","end":"2026-04-13T20:56:25.327884Z","steps":["trace[1398734141] 'process raft request' (duration: 1.017953174s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:56:25.328157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:56:24.309703Z","time spent":"1.018345806s","remote":"127.0.0.1:39508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T20:56:27.435982Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.108425972s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-13T20:56:27.747172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.934496ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-13T20:56:27.747405Z","caller":"traceutil/trace.go:171","msg":"trace[2040319512] linearizableReadLoop","detail":"{readStateIndex:1405; appliedIndex:1402; }","duration":"2.995939537s","start":"2026-04-13T20:56:24.751447Z","end":"2026-04-13T20:56:27.747387Z","steps":["trace[2040319512] 'read index received' (duration: 576.165607ms)","trace[2040319512] 'applied index is now lower than readState.Index' (duration: 2.41977262s)"],"step_count":2} {"level":"info","ts":"2026-04-13T20:56:27.747507Z","caller":"traceutil/trace.go:171","msg":"trace[721965919] transaction","detail":"{read_only:false; response_revision:1347; number_of_response:1; }","duration":"2.715972185s","start":"2026-04-13T20:56:25.031466Z","end":"2026-04-13T20:56:27.747438Z","steps":["trace[721965919] 'process raft request' (duration: 2.715813521s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:56:27.747603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.996161054s","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-04-13T20:56:27.747693Z","caller":"traceutil/trace.go:171","msg":"trace[1772542742] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1347; }","duration":"2.996288637s","start":"2026-04-13T20:56:24.751391Z","end":"2026-04-13T20:56:27.74768Z","steps":["trace[1772542742] 'agreement among raft nodes before linearized reading' (duration: 2.996098692s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:56:27.747741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:56:24.751371Z","time spent":"2.996359038s","remote":"127.0.0.1:39508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-13T20:56:27.747869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.989893598s","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-04-13T20:56:27.747931Z","caller":"traceutil/trace.go:171","msg":"trace[1792780592] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1347; }","duration":"2.990021233s","start":"2026-04-13T20:56:24.757897Z","end":"2026-04-13T20:56:27.747918Z","steps":["trace[1792780592] 'agreement among raft nodes before linearized reading' (duration: 2.989807757s)"],"step_count":1} {"level":"info","ts":"2026-04-13T20:56:27.747592Z","caller":"traceutil/trace.go:171","msg":"trace[1470684082] transaction","detail":"{read_only:false; response_revision:1346; number_of_response:1; }","duration":"3.235107521s","start":"2026-04-13T20:56:24.512443Z","end":"2026-04-13T20:56:27.747551Z","steps":["trace[1470684082] 'process raft request' (duration: 2.923682541s)","trace[1470684082] 'compare' (duration: 310.649247ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T20:56:27.747811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:56:25.031429Z","time spent":"2.716198931s","remote":"127.0.0.1:39508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T20:56:27.748058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.851997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.209\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-04-13T20:56:27.748063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:56:24.512416Z","time spent":"3.235600394s","remote":"127.0.0.1:39508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T20:56:27.748091Z","caller":"traceutil/trace.go:171","msg":"trace[1455457884] range","detail":"{range_begin:/registry/masterleases/199.19.213.209; range_end:; response_count:1; response_revision:1347; }","duration":"199.933139ms","start":"2026-04-13T20:56:27.54815Z","end":"2026-04-13T20:56:27.748083Z","steps":["trace[1455457884] 'agreement among raft nodes before linearized reading' (duration: 199.842787ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:56:27.747971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:56:24.757871Z","time spent":"2.990090833s","remote":"127.0.0.1:39508","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-04-13T20:56:27.748324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.794846592s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T20:56:27.748361Z","caller":"traceutil/trace.go:171","msg":"trace[966362096] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1347; }","duration":"1.794934415s","start":"2026-04-13T20:56:25.953417Z","end":"2026-04-13T20:56:27.748352Z","steps":["trace[966362096] 'agreement among raft nodes before linearized reading' (duration: 1.794881963s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:56:27.748391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:56:25.953387Z","time spent":"1.794998276s","remote":"127.0.0.1:39234","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-13T20:56:27.748583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999222366s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-04-13T20:56:27.748625Z","caller":"traceutil/trace.go:171","msg":"trace[897791737] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1347; }","duration":"1.999299826s","start":"2026-04-13T20:56:25.749311Z","end":"2026-04-13T20:56:27.748611Z","steps":["trace[897791737] 'agreement among raft nodes before linearized reading' (duration: 1.999200464s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:56:27.748662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:56:25.749288Z","time spent":"1.999364069s","remote":"127.0.0.1:39508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":550,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-04-13T20:56:27.748833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.089294888s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:60877"} {"level":"info","ts":"2026-04-13T20:56:27.748879Z","caller":"traceutil/trace.go:171","msg":"trace[1354725416] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:1347; }","duration":"2.089379081s","start":"2026-04-13T20:56:25.659483Z","end":"2026-04-13T20:56:27.748862Z","steps":["trace[1354725416] 'agreement among raft nodes before linearized reading' (duration: 2.089255488s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:56:27.748932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:56:25.659458Z","time spent":"2.089459702s","remote":"127.0.0.1:39282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":60901,"request content":"key:\"/registry/ranges/serviceips\" "} {"level":"warn","ts":"2026-04-13T20:56:27.749179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.092862919s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"warn","ts":"2026-04-13T20:56:27.749224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.842895567s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-04-13T20:56:27.749557Z","caller":"traceutil/trace.go:171","msg":"trace[868400847] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1347; }","duration":"2.84302456s","start":"2026-04-13T20:56:24.906225Z","end":"2026-04-13T20:56:27.74925Z","steps":["trace[868400847] 'agreement among raft nodes before linearized reading' (duration: 2.842924707s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:56:27.7496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:56:24.906205Z","time spent":"2.84338424s","remote":"127.0.0.1:39508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":556,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-04-13T20:56:27.749836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.866666549s","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":"warn","ts":"2026-04-13T20:56:27.749957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.795027051s","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-04-13T20:56:27.749952Z","caller":"traceutil/trace.go:171","msg":"trace[305763988] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1347; }","duration":"2.866847096s","start":"2026-04-13T20:56:24.883077Z","end":"2026-04-13T20:56:27.749925Z","steps":["trace[305763988] 'agreement among raft nodes before linearized reading' (duration: 2.866525857s)"],"step_count":1} {"level":"info","ts":"2026-04-13T20:56:27.749992Z","caller":"traceutil/trace.go:171","msg":"trace[1507258102] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1347; }","duration":"2.795111023s","start":"2026-04-13T20:56:24.954872Z","end":"2026-04-13T20:56:27.749983Z","steps":["trace[1507258102] 'agreement among raft nodes before linearized reading' (duration: 2.794959439s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:56:27.750024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:56:24.883049Z","time spent":"2.866961667s","remote":"127.0.0.1:39508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":559,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-04-13T20:56:27.750148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.795306119s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-13T20:56:27.750177Z","caller":"traceutil/trace.go:171","msg":"trace[1984918478] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1347; }","duration":"2.795386081s","start":"2026-04-13T20:56:24.954784Z","end":"2026-04-13T20:56:27.75017Z","steps":["trace[1984918478] 'agreement among raft nodes before linearized reading' (duration: 2.795279368s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:56:27.750225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:56:24.954756Z","time spent":"2.795440712s","remote":"127.0.0.1:39508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-13T20:56:27.750046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:56:24.954865Z","time spent":"2.795173765s","remote":"127.0.0.1:39508","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-04-13T20:56:27.749226Z","caller":"traceutil/trace.go:171","msg":"trace[828778140] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:1347; }","duration":"2.092953921s","start":"2026-04-13T20:56:25.656261Z","end":"2026-04-13T20:56:27.749215Z","steps":["trace[828778140] 'agreement among raft nodes before linearized reading' (duration: 2.092871168s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:56:27.752409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:56:25.656237Z","time spent":"2.096087108s","remote":"127.0.0.1:39284","response type":"/etcdserverpb.KV/Range","request count":0,"request size":35,"response count":1,"response size":142,"request content":"key:\"/registry/ranges/servicenodeports\" "} {"level":"warn","ts":"2026-04-13T20:56:27.754039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.798979711s","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-04-13T20:56:27.754096Z","caller":"traceutil/trace.go:171","msg":"trace[180605820] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1347; }","duration":"2.800215136s","start":"2026-04-13T20:56:24.953861Z","end":"2026-04-13T20:56:27.754076Z","steps":["trace[180605820] 'agreement among raft nodes before linearized reading' (duration: 2.798876088s)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:56:27.754134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:56:24.953835Z","time spent":"2.800287928s","remote":"127.0.0.1:39508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-13T20:57:03.169235Z","caller":"traceutil/trace.go:171","msg":"trace[2102583670] linearizableReadLoop","detail":"{readStateIndex:1580; appliedIndex:1579; }","duration":"124.381671ms","start":"2026-04-13T20:57:03.04483Z","end":"2026-04-13T20:57:03.169211Z","steps":["trace[2102583670] 'read index received' (duration: 47.376143ms)","trace[2102583670] 'applied index is now lower than readState.Index' (duration: 77.004018ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T20:57:03.169374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.518495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T20:57:03.169504Z","caller":"traceutil/trace.go:171","msg":"trace[480405590] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:1514; }","duration":"124.610137ms","start":"2026-04-13T20:57:03.044796Z","end":"2026-04-13T20:57:03.169406Z","steps":["trace[480405590] 'agreement among raft nodes before linearized reading' (duration: 124.517694ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T20:57:30.602089Z","caller":"traceutil/trace.go:171","msg":"trace[1916615912] transaction","detail":"{read_only:false; response_revision:1638; number_of_response:1; }","duration":"123.949479ms","start":"2026-04-13T20:57:30.478109Z","end":"2026-04-13T20:57:30.602058Z","steps":["trace[1916615912] 'process raft request' (duration: 114.813783ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T20:57:30.602425Z","caller":"traceutil/trace.go:171","msg":"trace[422007551] transaction","detail":"{read_only:false; response_revision:1639; number_of_response:1; }","duration":"123.772724ms","start":"2026-04-13T20:57:30.478637Z","end":"2026-04-13T20:57:30.60241Z","steps":["trace[422007551] 'process raft request' (duration: 123.30553ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T20:57:30.602581Z","caller":"traceutil/trace.go:171","msg":"trace[320326403] transaction","detail":"{read_only:false; response_revision:1640; number_of_response:1; }","duration":"123.500496ms","start":"2026-04-13T20:57:30.479071Z","end":"2026-04-13T20:57:30.602572Z","steps":["trace[320326403] 'process raft request' (duration: 123.301ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T20:57:30.602601Z","caller":"traceutil/trace.go:171","msg":"trace[1097997323] transaction","detail":"{read_only:false; response_revision:1641; number_of_response:1; }","duration":"123.012293ms","start":"2026-04-13T20:57:30.479564Z","end":"2026-04-13T20:57:30.602576Z","steps":["trace[1097997323] 'process raft request' (duration: 122.89398ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T20:57:42.317395Z","caller":"traceutil/trace.go:171","msg":"trace[570865579] linearizableReadLoop","detail":"{readStateIndex:1762; appliedIndex:1761; }","duration":"157.612962ms","start":"2026-04-13T20:57:42.159754Z","end":"2026-04-13T20:57:42.317367Z","steps":["trace[570865579] 'read index received' (duration: 157.17303ms)","trace[570865579] 'applied index is now lower than readState.Index' (duration: 438.172µs)"],"step_count":2} {"level":"info","ts":"2026-04-13T20:57:42.317481Z","caller":"traceutil/trace.go:171","msg":"trace[727873550] transaction","detail":"{read_only:false; response_revision:1689; number_of_response:1; }","duration":"190.284395ms","start":"2026-04-13T20:57:42.127141Z","end":"2026-04-13T20:57:42.317425Z","steps":["trace[727873550] 'process raft request' (duration: 190.056278ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:57:42.317559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.761876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T20:57:42.317596Z","caller":"traceutil/trace.go:171","msg":"trace[1962133115] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:1689; }","duration":"157.868228ms","start":"2026-04-13T20:57:42.159716Z","end":"2026-04-13T20:57:42.317584Z","steps":["trace[1962133115] 'agreement among raft nodes before linearized reading' (duration: 157.766526ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T20:58:17.68889Z","caller":"traceutil/trace.go:171","msg":"trace[879995668] transaction","detail":"{read_only:false; response_revision:1853; number_of_response:1; }","duration":"111.369388ms","start":"2026-04-13T20:58:17.577482Z","end":"2026-04-13T20:58:17.688852Z","steps":["trace[879995668] 'process raft request' (duration: 100.657167ms)","trace[879995668] 'compare' (duration: 10.541346ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T20:58:24.669784Z","caller":"traceutil/trace.go:171","msg":"trace[827167882] transaction","detail":"{read_only:false; response_revision:1885; number_of_response:1; }","duration":"106.10956ms","start":"2026-04-13T20:58:24.563617Z","end":"2026-04-13T20:58:24.669727Z","steps":["trace[827167882] 'process raft request' (duration: 55.575486ms)","trace[827167882] 'compare' (duration: 50.236716ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T20:58:55.52445Z","caller":"traceutil/trace.go:171","msg":"trace[59255150] transaction","detail":"{read_only:false; response_revision:2028; number_of_response:1; }","duration":"181.000165ms","start":"2026-04-13T20:58:55.34341Z","end":"2026-04-13T20:58:55.524411Z","steps":["trace[59255150] 'process raft request' (duration: 147.023096ms)","trace[59255150] 'compare' (duration: 33.713852ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T20:58:55.52459Z","caller":"traceutil/trace.go:171","msg":"trace[1392687968] transaction","detail":"{read_only:false; response_revision:2029; number_of_response:1; }","duration":"178.860746ms","start":"2026-04-13T20:58:55.345704Z","end":"2026-04-13T20:58:55.524565Z","steps":["trace[1392687968] 'process raft request' (duration: 178.64664ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T20:58:56.888574Z","caller":"traceutil/trace.go:171","msg":"trace[1571884924] transaction","detail":"{read_only:false; response_revision:2031; number_of_response:1; }","duration":"105.668811ms","start":"2026-04-13T20:58:56.782873Z","end":"2026-04-13T20:58:56.888542Z","steps":["trace[1571884924] 'process raft request' (duration: 105.500256ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T20:59:31.408496Z","caller":"traceutil/trace.go:171","msg":"trace[211858946] transaction","detail":"{read_only:false; response_revision:2189; number_of_response:1; }","duration":"161.747358ms","start":"2026-04-13T20:59:31.246695Z","end":"2026-04-13T20:59:31.408443Z","steps":["trace[211858946] 'process raft request' (duration: 161.512921ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:59:31.408785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.461367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-13T20:59:31.408867Z","caller":"traceutil/trace.go:171","msg":"trace[1424649471] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2189; }","duration":"138.557139ms","start":"2026-04-13T20:59:31.270296Z","end":"2026-04-13T20:59:31.408853Z","steps":["trace[1424649471] 'agreement among raft nodes before linearized reading' (duration: 138.370524ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T20:59:31.411055Z","caller":"traceutil/trace.go:171","msg":"trace[1133056441] linearizableReadLoop","detail":"{readStateIndex:2284; appliedIndex:2283; }","duration":"138.076577ms","start":"2026-04-13T20:59:31.270313Z","end":"2026-04-13T20:59:31.408389Z","steps":["trace[1133056441] 'read index received' (duration: 137.848161ms)","trace[1133056441] 'applied index is now lower than readState.Index' (duration: 227.566µs)"],"step_count":2} {"level":"warn","ts":"2026-04-13T20:59:31.770407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.712665ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-13T20:59:31.77057Z","caller":"traceutil/trace.go:171","msg":"trace[2074304251] transaction","detail":"{read_only:false; response_revision:2192; number_of_response:1; }","duration":"186.849349ms","start":"2026-04-13T20:59:31.583688Z","end":"2026-04-13T20:59:31.770537Z","steps":["trace[2074304251] 'process raft request' (duration: 48.906436ms)","trace[2074304251] 'compare' (duration: 137.573442ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T20:59:32.303132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.661852ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-13T20:59:32.303336Z","caller":"traceutil/trace.go:171","msg":"trace[145069238] linearizableReadLoop","detail":"{readStateIndex:2289; appliedIndex:2287; }","duration":"350.658824ms","start":"2026-04-13T20:59:31.952661Z","end":"2026-04-13T20:59:32.30332Z","steps":["trace[145069238] 'read index received' (duration: 37.808206ms)","trace[145069238] 'applied index is now lower than readState.Index' (duration: 312.849858ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T20:59:32.303406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.749425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-13T20:59:32.303435Z","caller":"traceutil/trace.go:171","msg":"trace[2083496163] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2195; }","duration":"350.828289ms","start":"2026-04-13T20:59:31.952598Z","end":"2026-04-13T20:59:32.303426Z","steps":["trace[2083496163] 'agreement among raft nodes before linearized reading' (duration: 350.781907ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:59:32.303461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:59:31.952571Z","time spent":"350.884789ms","remote":"127.0.0.1:39222","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-13T20:59:32.303489Z","caller":"traceutil/trace.go:171","msg":"trace[336296911] transaction","detail":"{read_only:false; response_revision:2193; number_of_response:1; }","duration":"528.169361ms","start":"2026-04-13T20:59:31.775287Z","end":"2026-04-13T20:59:32.303456Z","steps":["trace[336296911] 'process raft request' (duration: 215.097298ms)","trace[336296911] 'compare' (duration: 312.553259ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T20:59:32.30365Z","caller":"traceutil/trace.go:171","msg":"trace[171795911] transaction","detail":"{read_only:false; response_revision:2194; number_of_response:1; }","duration":"528.10799ms","start":"2026-04-13T20:59:31.775534Z","end":"2026-04-13T20:59:32.303642Z","steps":["trace[171795911] 'process raft request' (duration: 527.709868ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:59:32.30365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:59:31.775245Z","time spent":"528.325447ms","remote":"127.0.0.1:39508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T20:59:32.303708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:59:31.775509Z","time spent":"528.171862ms","remote":"127.0.0.1:39508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-13T20:59:32.303798Z","caller":"traceutil/trace.go:171","msg":"trace[164137052] transaction","detail":"{read_only:false; response_revision:2195; number_of_response:1; }","duration":"349.468221ms","start":"2026-04-13T20:59:31.954319Z","end":"2026-04-13T20:59:32.303788Z","steps":["trace[164137052] 'process raft request' (duration: 348.971166ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:59:32.303854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-13T20:59:31.954274Z","time spent":"349.553791ms","remote":"127.0.0.1:39508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-13T20:59:33.750511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.953891ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-13T20:59:33.750683Z","caller":"traceutil/trace.go:171","msg":"trace[15472767] linearizableReadLoop","detail":"{readStateIndex:2301; appliedIndex:2300; }","duration":"165.811599ms","start":"2026-04-13T20:59:33.584845Z","end":"2026-04-13T20:59:33.750656Z","steps":["trace[15472767] 'read index received' (duration: 27.486057ms)","trace[15472767] 'applied index is now lower than readState.Index' (duration: 138.323322ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T20:59:33.750756Z","caller":"traceutil/trace.go:171","msg":"trace[203604567] transaction","detail":"{read_only:false; response_revision:2204; number_of_response:1; }","duration":"229.334133ms","start":"2026-04-13T20:59:33.521396Z","end":"2026-04-13T20:59:33.75073Z","steps":["trace[203604567] 'process raft request' (duration: 91.017681ms)","trace[203604567] 'compare' (duration: 137.736386ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T20:59:33.751142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.294973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-13T20:59:33.751253Z","caller":"traceutil/trace.go:171","msg":"trace[1261642163] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2204; }","duration":"166.443336ms","start":"2026-04-13T20:59:33.584787Z","end":"2026-04-13T20:59:33.75123Z","steps":["trace[1261642163] 'agreement among raft nodes before linearized reading' (duration: 165.952122ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T20:59:33.87618Z","caller":"traceutil/trace.go:171","msg":"trace[709358145] linearizableReadLoop","detail":"{readStateIndex:2302; appliedIndex:2301; }","duration":"100.516816ms","start":"2026-04-13T20:59:33.775622Z","end":"2026-04-13T20:59:33.876138Z","steps":["trace[709358145] 'read index received' (duration: 79.687744ms)","trace[709358145] 'applied index is now lower than readState.Index' (duration: 20.827692ms)"],"step_count":2} {"level":"warn","ts":"2026-04-13T20:59:33.876413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.761862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-04-13T20:59:33.876468Z","caller":"traceutil/trace.go:171","msg":"trace[116662419] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2205; }","duration":"100.878227ms","start":"2026-04-13T20:59:33.775576Z","end":"2026-04-13T20:59:33.876454Z","steps":["trace[116662419] 'agreement among raft nodes before linearized reading' (duration: 100.693091ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T20:59:33.876495Z","caller":"traceutil/trace.go:171","msg":"trace[32837276] transaction","detail":"{read_only:false; response_revision:2205; number_of_response:1; }","duration":"119.070825ms","start":"2026-04-13T20:59:33.757332Z","end":"2026-04-13T20:59:33.876403Z","steps":["trace[32837276] 'process raft request' (duration: 97.998016ms)","trace[32837276] 'compare' (duration: 20.627965ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T20:59:56.548305Z","caller":"traceutil/trace.go:171","msg":"trace[104537335] transaction","detail":"{read_only:false; response_revision:2336; number_of_response:1; }","duration":"110.459674ms","start":"2026-04-13T20:59:56.437808Z","end":"2026-04-13T20:59:56.548267Z","steps":["trace[104537335] 'process raft request' (duration: 81.739642ms)","trace[104537335] 'compare' (duration: 28.546817ms)"],"step_count":2} {"level":"info","ts":"2026-04-13T20:59:57.271106Z","caller":"traceutil/trace.go:171","msg":"trace[1597147298] transaction","detail":"{read_only:false; response_revision:2337; number_of_response:1; }","duration":"114.981479ms","start":"2026-04-13T20:59:57.15608Z","end":"2026-04-13T20:59:57.271061Z","steps":["trace[1597147298] 'process raft request' (duration: 114.784924ms)"],"step_count":1} {"level":"info","ts":"2026-04-13T20:59:57.741665Z","caller":"traceutil/trace.go:171","msg":"trace[1555982841] linearizableReadLoop","detail":"{readStateIndex:2441; appliedIndex:2440; }","duration":"137.23104ms","start":"2026-04-13T20:59:57.604413Z","end":"2026-04-13T20:59:57.741644Z","steps":["trace[1555982841] 'read index received' (duration: 137.084477ms)","trace[1555982841] 'applied index is now lower than readState.Index' (duration: 145.453µs)"],"step_count":2} {"level":"info","ts":"2026-04-13T20:59:57.742355Z","caller":"traceutil/trace.go:171","msg":"trace[161411232] transaction","detail":"{read_only:false; response_revision:2339; number_of_response:1; }","duration":"156.118188ms","start":"2026-04-13T20:59:57.586187Z","end":"2026-04-13T20:59:57.742305Z","steps":["trace[161411232] 'process raft request' (duration: 155.328426ms)"],"step_count":1} {"level":"warn","ts":"2026-04-13T20:59:57.742131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.685154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-04-13T20:59:57.742687Z","caller":"traceutil/trace.go:171","msg":"trace[498898034] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:2339; }","duration":"138.219248ms","start":"2026-04-13T20:59:57.604386Z","end":"2026-04-13T20:59:57.742605Z","steps":["trace[498898034] 'agreement among raft nodes before linearized reading' (duration: 137.54638ms)"],"step_count":1}