{"level":"warn","ts":"2026-05-07T03:48:20.454063Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-07T03:48:20.454177Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.57:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.57:2380","--initial-cluster=instance=https://199.204.45.57:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.57:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.57:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-07T03:48:20.454273Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-07T03:48:20.454289Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.57:2380"]} {"level":"info","ts":"2026-05-07T03:48:20.454313Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-07T03:48:20.455187Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.57:2379"]} {"level":"info","ts":"2026-05-07T03:48:20.455287Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.57:2380"],"listen-peer-urls":["https://199.204.45.57:2380"],"advertise-client-urls":["https://199.204.45.57:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.57:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.57:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-07T03:48:20.461725Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.176832ms"} {"level":"info","ts":"2026-05-07T03:48:20.467661Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b6478f0ac5aeb8e1","cluster-id":"6c58cff80d4a5b62"} {"level":"info","ts":"2026-05-07T03:48:20.467711Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 switched to configuration voters=()"} {"level":"info","ts":"2026-05-07T03:48:20.467729Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 became follower at term 0"} {"level":"info","ts":"2026-05-07T03:48:20.467736Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b6478f0ac5aeb8e1 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-07T03:48:20.467746Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 became follower at term 1"} {"level":"info","ts":"2026-05-07T03:48:20.467777Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 switched to configuration voters=(13134624114678348001)"} {"level":"warn","ts":"2026-05-07T03:48:20.475661Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-07T03:48:20.476766Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-07T03:48:20.477606Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-07T03:48:20.479172Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b6478f0ac5aeb8e1","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-07T03:48:20.479306Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b6478f0ac5aeb8e1","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-07T03:48:20.481918Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 switched to configuration voters=(13134624114678348001)"} {"level":"info","ts":"2026-05-07T03:48:20.482049Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"6c58cff80d4a5b62","local-member-id":"b6478f0ac5aeb8e1","added-peer-id":"b6478f0ac5aeb8e1","added-peer-peer-urls":["https://199.204.45.57:2380"]} {"level":"info","ts":"2026-05-07T03:48:20.484165Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-07T03:48:20.48447Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-07T03:48:20.4845Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-07T03:48:20.485784Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-07T03:48:20.485902Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.57:2380"} {"level":"info","ts":"2026-05-07T03:48:20.485989Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.57:2380"} {"level":"info","ts":"2026-05-07T03:48:20.486068Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b6478f0ac5aeb8e1","initial-advertise-peer-urls":["https://199.204.45.57:2380"],"listen-peer-urls":["https://199.204.45.57:2380"],"advertise-client-urls":["https://199.204.45.57:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.57:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-07T03:48:20.486138Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-07T03:48:20.768084Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 is starting a new election at term 1"} {"level":"info","ts":"2026-05-07T03:48:20.768164Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-07T03:48:20.768185Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 received MsgPreVoteResp from b6478f0ac5aeb8e1 at term 1"} {"level":"info","ts":"2026-05-07T03:48:20.7682Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 became candidate at term 2"} {"level":"info","ts":"2026-05-07T03:48:20.768207Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 received MsgVoteResp from b6478f0ac5aeb8e1 at term 2"} {"level":"info","ts":"2026-05-07T03:48:20.768218Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b6478f0ac5aeb8e1 became leader at term 2"} {"level":"info","ts":"2026-05-07T03:48:20.768232Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b6478f0ac5aeb8e1 elected leader b6478f0ac5aeb8e1 at term 2"} {"level":"info","ts":"2026-05-07T03:48:20.770598Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-07T03:48:20.771461Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b6478f0ac5aeb8e1","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.57:2379]}","request-path":"/0/members/b6478f0ac5aeb8e1/attributes","cluster-id":"6c58cff80d4a5b62","publish-timeout":"7s"} {"level":"info","ts":"2026-05-07T03:48:20.771592Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-07T03:48:20.771643Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-07T03:48:20.771889Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-07T03:48:20.771958Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-07T03:48:20.772031Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"6c58cff80d4a5b62","local-member-id":"b6478f0ac5aeb8e1","cluster-version":"3.5"} {"level":"info","ts":"2026-05-07T03:48:20.772455Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-07T03:48:20.772489Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-07T03:48:20.77487Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-07T03:48:20.779382Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.57:2379"} {"level":"info","ts":"2026-05-07T03:48:27.175217Z","caller":"traceutil/trace.go:171","msg":"trace[1332374988] transaction","detail":"{read_only:false; response_revision:246; number_of_response:1; }","duration":"223.983239ms","start":"2026-05-07T03:48:26.9512Z","end":"2026-05-07T03:48:27.175183Z","steps":["trace[1332374988] 'process raft request' (duration: 166.425323ms)","trace[1332374988] 'compare' (duration: 57.375572ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:48:27.175374Z","caller":"traceutil/trace.go:171","msg":"trace[305794278] transaction","detail":"{read_only:false; response_revision:248; number_of_response:1; }","duration":"151.467035ms","start":"2026-05-07T03:48:27.023897Z","end":"2026-05-07T03:48:27.175364Z","steps":["trace[305794278] 'process raft request' (duration: 151.273241ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:48:27.175368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.71263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T03:48:27.175224Z","caller":"traceutil/trace.go:171","msg":"trace[1622065222] linearizableReadLoop","detail":"{readStateIndex:253; appliedIndex:251; }","duration":"151.568487ms","start":"2026-05-07T03:48:27.023635Z","end":"2026-05-07T03:48:27.175203Z","steps":["trace[1622065222] 'read index received' (duration: 93.976831ms)","trace[1622065222] 'applied index is now lower than readState.Index' (duration: 57.590576ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:48:27.175439Z","caller":"traceutil/trace.go:171","msg":"trace[1524683414] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:248; }","duration":"151.831081ms","start":"2026-05-07T03:48:27.023596Z","end":"2026-05-07T03:48:27.175427Z","steps":["trace[1524683414] 'agreement among raft nodes before linearized reading' (duration: 151.687378ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:48:27.175251Z","caller":"traceutil/trace.go:171","msg":"trace[1226421318] transaction","detail":"{read_only:false; response_revision:247; number_of_response:1; }","duration":"193.426612ms","start":"2026-05-07T03:48:26.981801Z","end":"2026-05-07T03:48:27.175228Z","steps":["trace[1226421318] 'process raft request' (duration: 193.326421ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:48:27.212509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.783087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T03:48:27.21259Z","caller":"traceutil/trace.go:171","msg":"trace[837106148] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:0; response_revision:248; }","duration":"188.895128ms","start":"2026-05-07T03:48:27.023676Z","end":"2026-05-07T03:48:27.212571Z","steps":["trace[837106148] 'agreement among raft nodes before linearized reading' (duration: 188.734695ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:48:27.311378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.32575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-07T03:48:27.31137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.048454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T03:48:27.311456Z","caller":"traceutil/trace.go:171","msg":"trace[1791005759] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:0; response_revision:250; }","duration":"132.450864ms","start":"2026-05-07T03:48:27.178985Z","end":"2026-05-07T03:48:27.311436Z","steps":["trace[1791005759] 'agreement among raft nodes before linearized reading' (duration: 96.279284ms)","trace[1791005759] 'range keys from in-memory index tree' (duration: 36.063037ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:48:27.311467Z","caller":"traceutil/trace.go:171","msg":"trace[704786329] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:250; }","duration":"133.198248ms","start":"2026-05-07T03:48:27.178249Z","end":"2026-05-07T03:48:27.311447Z","steps":["trace[704786329] 'agreement among raft nodes before linearized reading' (duration: 97.032288ms)","trace[704786329] 'range keys from in-memory index tree' (duration: 36.038567ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:48:27.797733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.755725ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-07T03:48:27.798067Z","caller":"traceutil/trace.go:171","msg":"trace[573099487] transaction","detail":"{read_only:false; response_revision:265; number_of_response:1; }","duration":"420.542888ms","start":"2026-05-07T03:48:27.377507Z","end":"2026-05-07T03:48:27.79805Z","steps":["trace[573099487] 'process raft request' (duration: 420.496257ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:48:27.79814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:48:27.377497Z","time spent":"420.617769ms","remote":"127.0.0.1:54132","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3200,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-07T03:48:27.798193Z","caller":"traceutil/trace.go:171","msg":"trace[414221047] transaction","detail":"{read_only:false; response_revision:263; number_of_response:1; }","duration":"444.364339ms","start":"2026-05-07T03:48:27.3538Z","end":"2026-05-07T03:48:27.798164Z","steps":["trace[414221047] 'process raft request' (duration: 444.096185ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:48:27.798276Z","caller":"traceutil/trace.go:171","msg":"trace[812104507] transaction","detail":"{read_only:false; response_revision:264; number_of_response:1; }","duration":"423.508183ms","start":"2026-05-07T03:48:27.374728Z","end":"2026-05-07T03:48:27.798236Z","steps":["trace[812104507] 'process raft request' (duration: 423.240198ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:48:27.798291Z","caller":"traceutil/trace.go:171","msg":"trace[582444485] transaction","detail":"{read_only:false; response_revision:262; number_of_response:1; }","duration":"449.097737ms","start":"2026-05-07T03:48:27.349133Z","end":"2026-05-07T03:48:27.798231Z","steps":["trace[582444485] 'process raft request' (duration: 63.324214ms)","trace[582444485] 'compare' (duration: 384.53304ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:48:27.798442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:48:27.374712Z","time spent":"423.647005ms","remote":"127.0.0.1:53758","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":636,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-07T03:48:27.79846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:48:27.349118Z","time spent":"449.26965ms","remote":"127.0.0.1:53868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":192,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-07T03:48:27.798607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:48:27.353784Z","time spent":"444.540832ms","remote":"127.0.0.1:53842","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4216,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T03:48:28.26166Z","caller":"traceutil/trace.go:171","msg":"trace[1041167195] linearizableReadLoop","detail":"{readStateIndex:275; appliedIndex:274; }","duration":"423.142377ms","start":"2026-05-07T03:48:27.838479Z","end":"2026-05-07T03:48:28.261622Z","steps":["trace[1041167195] 'read index received' (duration: 404.149226ms)","trace[1041167195] 'applied index is now lower than readState.Index' (duration: 18.992121ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:48:28.26174Z","caller":"traceutil/trace.go:171","msg":"trace[2066564551] transaction","detail":"{read_only:false; response_revision:268; number_of_response:1; }","duration":"437.014554ms","start":"2026-05-07T03:48:27.824687Z","end":"2026-05-07T03:48:28.261702Z","steps":["trace[2066564551] 'process raft request' (duration: 418.011463ms)","trace[2066564551] 'compare' (duration: 18.772367ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:48:28.261919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:48:27.824674Z","time spent":"437.165776ms","remote":"127.0.0.1:53866","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1196,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-07T03:48:28.261944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.456682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-05-07T03:48:28.262044Z","caller":"traceutil/trace.go:171","msg":"trace[509686756] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:268; }","duration":"423.595855ms","start":"2026-05-07T03:48:27.838433Z","end":"2026-05-07T03:48:28.262029Z","steps":["trace[509686756] 'agreement among raft nodes before linearized reading' (duration: 423.405271ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:48:28.262101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:48:27.838343Z","time spent":"423.747608ms","remote":"127.0.0.1:53868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":232,"request content":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" "} {"level":"info","ts":"2026-05-07T03:48:28.286461Z","caller":"traceutil/trace.go:171","msg":"trace[1211209329] transaction","detail":"{read_only:false; number_of_response:0; response_revision:268; }","duration":"234.000944ms","start":"2026-05-07T03:48:28.052437Z","end":"2026-05-07T03:48:28.286438Z","steps":["trace[1211209329] 'process raft request' (duration: 233.888372ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:48:28.286635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.171323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:3468"} {"level":"info","ts":"2026-05-07T03:48:28.286699Z","caller":"traceutil/trace.go:171","msg":"trace[105337209] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:268; }","duration":"237.283634ms","start":"2026-05-07T03:48:28.049402Z","end":"2026-05-07T03:48:28.286686Z","steps":["trace[105337209] 'agreement among raft nodes before linearized reading' (duration: 237.095851ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:48:28.286794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.446874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-07T03:48:28.286886Z","caller":"traceutil/trace.go:171","msg":"trace[1822664195] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:268; }","duration":"381.591136ms","start":"2026-05-07T03:48:27.905276Z","end":"2026-05-07T03:48:28.286867Z","steps":["trace[1822664195] 'agreement among raft nodes before linearized reading' (duration: 381.281911ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:48:28.286936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:48:27.905258Z","time spent":"381.665618ms","remote":"127.0.0.1:53924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-07T03:48:28.59238Z","caller":"traceutil/trace.go:171","msg":"trace[1361804482] linearizableReadLoop","detail":"{readStateIndex:277; appliedIndex:276; }","duration":"305.833354ms","start":"2026-05-07T03:48:28.28652Z","end":"2026-05-07T03:48:28.592354Z","steps":["trace[1361804482] 'read index received' (duration: 296.265187ms)","trace[1361804482] 'applied index is now lower than readState.Index' (duration: 9.567097ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:48:28.592433Z","caller":"traceutil/trace.go:171","msg":"trace[1858504623] transaction","detail":"{read_only:false; response_revision:269; number_of_response:1; }","duration":"323.645504ms","start":"2026-05-07T03:48:28.268759Z","end":"2026-05-07T03:48:28.592404Z","steps":["trace[1858504623] 'process raft request' (duration: 314.004666ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:48:28.592559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.547541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-05-07T03:48:28.592606Z","caller":"traceutil/trace.go:171","msg":"trace[2123851066] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:269; }","duration":"324.650333ms","start":"2026-05-07T03:48:28.267944Z","end":"2026-05-07T03:48:28.592594Z","steps":["trace[2123851066] 'agreement among raft nodes before linearized reading' (duration: 324.533111ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:48:28.59265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:48:28.267917Z","time spent":"324.724654ms","remote":"127.0.0.1:53868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":232,"request content":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" "} {"level":"warn","ts":"2026-05-07T03:48:28.592709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:48:28.268741Z","time spent":"323.762087ms","remote":"127.0.0.1:53784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1933,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-07T03:48:28.66702Z","caller":"traceutil/trace.go:171","msg":"trace[1445134898] transaction","detail":"{read_only:false; response_revision:270; number_of_response:1; }","duration":"375.842691ms","start":"2026-05-07T03:48:28.291153Z","end":"2026-05-07T03:48:28.666996Z","steps":["trace[1445134898] 'process raft request' (duration: 375.654058ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:48:28.667216Z","caller":"traceutil/trace.go:171","msg":"trace[837459781] transaction","detail":"{read_only:false; response_revision:271; number_of_response:1; }","duration":"363.539403ms","start":"2026-05-07T03:48:28.303654Z","end":"2026-05-07T03:48:28.667193Z","steps":["trace[837459781] 'process raft request' (duration: 363.288179ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:48:28.667278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.724822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:2920"} {"level":"warn","ts":"2026-05-07T03:48:28.667288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:48:28.291138Z","time spent":"376.068436ms","remote":"127.0.0.1:53924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T03:48:28.667397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:48:28.303633Z","time spent":"363.678285ms","remote":"127.0.0.1:53852","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4645,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T03:48:28.667376Z","caller":"traceutil/trace.go:171","msg":"trace[34552911] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:271; }","duration":"379.832664ms","start":"2026-05-07T03:48:28.287506Z","end":"2026-05-07T03:48:28.667339Z","steps":["trace[34552911] 'agreement among raft nodes before linearized reading' (duration: 379.692131ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:48:28.667585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:48:28.287494Z","time spent":"380.073869ms","remote":"127.0.0.1:53852","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":2943,"request content":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" "} {"level":"warn","ts":"2026-05-07T03:48:28.926568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.74585ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T03:48:28.927112Z","caller":"traceutil/trace.go:171","msg":"trace[1966985338] transaction","detail":"{read_only:false; response_revision:273; number_of_response:1; }","duration":"251.425556ms","start":"2026-05-07T03:48:28.675296Z","end":"2026-05-07T03:48:28.926721Z","steps":["trace[1966985338] 'process raft request' (duration: 38.441911ms)","trace[1966985338] 'compare' (duration: 212.637049ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:48:28.96881Z","caller":"traceutil/trace.go:171","msg":"trace[735017752] linearizableReadLoop","detail":"{readStateIndex:282; appliedIndex:280; }","duration":"291.57244ms","start":"2026-05-07T03:48:28.677219Z","end":"2026-05-07T03:48:28.968792Z","steps":["trace[735017752] 'read index received' (duration: 36.529056ms)","trace[735017752] 'applied index is now lower than readState.Index' (duration: 255.042104ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:48:28.968837Z","caller":"traceutil/trace.go:171","msg":"trace[1421259827] transaction","detail":"{read_only:false; response_revision:274; number_of_response:1; }","duration":"293.116148ms","start":"2026-05-07T03:48:28.675701Z","end":"2026-05-07T03:48:28.968817Z","steps":["trace[1421259827] 'process raft request' (duration: 292.946115ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:48:28.968931Z","caller":"traceutil/trace.go:171","msg":"trace[1851869734] transaction","detail":"{read_only:false; response_revision:275; number_of_response:1; }","duration":"288.946611ms","start":"2026-05-07T03:48:28.679975Z","end":"2026-05-07T03:48:28.968921Z","steps":["trace[1851869734] 'process raft request' (duration: 288.769398ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:48:28.969116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.885175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-05-07T03:48:28.969158Z","caller":"traceutil/trace.go:171","msg":"trace[1391885796] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:275; }","duration":"291.958458ms","start":"2026-05-07T03:48:28.677188Z","end":"2026-05-07T03:48:28.969146Z","steps":["trace[1391885796] 'agreement among raft nodes before linearized reading' (duration: 291.878836ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:48:28.969334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.972524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-07T03:48:28.970287Z","caller":"traceutil/trace.go:171","msg":"trace[1687216013] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:275; }","duration":"213.951072ms","start":"2026-05-07T03:48:28.756306Z","end":"2026-05-07T03:48:28.970257Z","steps":["trace[1687216013] 'agreement among raft nodes before linearized reading' (duration: 212.697258ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:48:29.233167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.53879ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-07T03:48:29.233365Z","caller":"traceutil/trace.go:171","msg":"trace[905978463] transaction","detail":"{read_only:false; response_revision:277; number_of_response:1; }","duration":"260.309341ms","start":"2026-05-07T03:48:28.973027Z","end":"2026-05-07T03:48:29.233336Z","steps":["trace[905978463] 'process raft request' (duration: 83.532837ms)","trace[905978463] 'compare' (duration: 176.376827ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:48:29.233441Z","caller":"traceutil/trace.go:171","msg":"trace[1104621424] transaction","detail":"{read_only:false; response_revision:278; number_of_response:1; }","duration":"260.130197ms","start":"2026-05-07T03:48:28.97327Z","end":"2026-05-07T03:48:29.2334Z","steps":["trace[1104621424] 'process raft request' (duration: 259.991405ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:48:29.233644Z","caller":"traceutil/trace.go:171","msg":"trace[668200183] transaction","detail":"{read_only:false; response_revision:280; number_of_response:1; }","duration":"254.708616ms","start":"2026-05-07T03:48:28.978917Z","end":"2026-05-07T03:48:29.233626Z","steps":["trace[668200183] 'process raft request' (duration: 254.440092ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:48:29.233643Z","caller":"traceutil/trace.go:171","msg":"trace[91437232] transaction","detail":"{read_only:false; response_revision:279; number_of_response:1; }","duration":"259.345353ms","start":"2026-05-07T03:48:28.974268Z","end":"2026-05-07T03:48:29.233613Z","steps":["trace[91437232] 'process raft request' (duration: 259.056738ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:48:29.23421Z","caller":"traceutil/trace.go:171","msg":"trace[1870655735] transaction","detail":"{read_only:false; response_revision:281; number_of_response:1; }","duration":"208.463161ms","start":"2026-05-07T03:48:29.025729Z","end":"2026-05-07T03:48:29.234192Z","steps":["trace[1870655735] 'process raft request' (duration: 207.707906ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:48:29.593785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.227392ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T03:48:29.593924Z","caller":"traceutil/trace.go:171","msg":"trace[1274935152] linearizableReadLoop","detail":"{readStateIndex:291; appliedIndex:290; }","duration":"321.061836ms","start":"2026-05-07T03:48:29.272819Z","end":"2026-05-07T03:48:29.593881Z","steps":["trace[1274935152] 'read index received' (duration: 129.546239ms)","trace[1274935152] 'applied index is now lower than readState.Index' (duration: 191.514237ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:48:29.593956Z","caller":"traceutil/trace.go:171","msg":"trace[593641236] transaction","detail":"{read_only:false; response_revision:283; number_of_response:1; }","duration":"348.881261ms","start":"2026-05-07T03:48:29.245043Z","end":"2026-05-07T03:48:29.593924Z","steps":["trace[593641236] 'process raft request' (duration: 157.433985ms)","trace[593641236] 'compare' (duration: 191.10615ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:48:29.594073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.22668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-05-07T03:48:29.594205Z","caller":"traceutil/trace.go:171","msg":"trace[911809758] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:283; }","duration":"321.400633ms","start":"2026-05-07T03:48:29.272774Z","end":"2026-05-07T03:48:29.594175Z","steps":["trace[911809758] 'agreement among raft nodes before linearized reading' (duration: 321.194069ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:48:29.594112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:48:29.24503Z","time spent":"349.010263ms","remote":"127.0.0.1:53852","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6946,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T03:48:29.594353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:48:29.272754Z","time spent":"321.563566ms","remote":"127.0.0.1:53790","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":374,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-05-07T03:48:29.797041Z","caller":"traceutil/trace.go:171","msg":"trace[1271243675] linearizableReadLoop","detail":"{readStateIndex:293; appliedIndex:292; }","duration":"185.393585ms","start":"2026-05-07T03:48:29.611628Z","end":"2026-05-07T03:48:29.797022Z","steps":["trace[1271243675] 'read index received' (duration: 99.354871ms)","trace[1271243675] 'applied index is now lower than readState.Index' (duration: 86.038004ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:48:29.797105Z","caller":"traceutil/trace.go:171","msg":"trace[1861106521] transaction","detail":"{read_only:false; response_revision:285; number_of_response:1; }","duration":"194.456542ms","start":"2026-05-07T03:48:29.602624Z","end":"2026-05-07T03:48:29.79708Z","steps":["trace[1861106521] 'process raft request' (duration: 108.304046ms)","trace[1861106521] 'compare' (duration: 85.994983ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:48:29.797138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.490906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/namespace-controller\" ","response":"range_response_count:1 size:205"} {"level":"info","ts":"2026-05-07T03:48:29.79716Z","caller":"traceutil/trace.go:171","msg":"trace[1112682801] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/namespace-controller; range_end:; response_count:1; response_revision:285; }","duration":"185.558857ms","start":"2026-05-07T03:48:29.611595Z","end":"2026-05-07T03:48:29.797154Z","steps":["trace[1112682801] 'agreement among raft nodes before linearized reading' (duration: 185.496246ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:48:30.047251Z","caller":"traceutil/trace.go:171","msg":"trace[1707706868] linearizableReadLoop","detail":"{readStateIndex:296; appliedIndex:295; }","duration":"150.714032ms","start":"2026-05-07T03:48:29.896511Z","end":"2026-05-07T03:48:30.047225Z","steps":["trace[1707706868] 'read index received' (duration: 82.838154ms)","trace[1707706868] 'applied index is now lower than readState.Index' (duration: 67.874728ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:48:30.047314Z","caller":"traceutil/trace.go:171","msg":"trace[493541290] transaction","detail":"{read_only:false; response_revision:288; number_of_response:1; }","duration":"160.373161ms","start":"2026-05-07T03:48:29.88691Z","end":"2026-05-07T03:48:30.047283Z","steps":["trace[493541290] 'process raft request' (duration: 92.493413ms)","trace[493541290] 'compare' (duration: 67.683333ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:48:30.047418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.885625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T03:48:30.047635Z","caller":"traceutil/trace.go:171","msg":"trace[1665309414] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:0; response_revision:288; }","duration":"151.134489ms","start":"2026-05-07T03:48:29.896485Z","end":"2026-05-07T03:48:30.04762Z","steps":["trace[1665309414] 'agreement among raft nodes before linearized reading' (duration: 150.877814ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:48:30.169536Z","caller":"traceutil/trace.go:171","msg":"trace[1946473406] linearizableReadLoop","detail":"{readStateIndex:298; appliedIndex:297; }","duration":"106.515832ms","start":"2026-05-07T03:48:30.062995Z","end":"2026-05-07T03:48:30.169511Z","steps":["trace[1946473406] 'read index received' (duration: 88.965157ms)","trace[1946473406] 'applied index is now lower than readState.Index' (duration: 17.549735ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:48:30.169637Z","caller":"traceutil/trace.go:171","msg":"trace[333693252] transaction","detail":"{read_only:false; response_revision:290; number_of_response:1; }","duration":"110.510997ms","start":"2026-05-07T03:48:30.059103Z","end":"2026-05-07T03:48:30.169614Z","steps":["trace[333693252] 'process raft request' (duration: 92.86998ms)","trace[333693252] 'compare' (duration: 17.414373ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:48:30.169683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.659885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T03:48:30.169838Z","caller":"traceutil/trace.go:171","msg":"trace[1319806974] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:0; response_revision:290; }","duration":"106.726147ms","start":"2026-05-07T03:48:30.06298Z","end":"2026-05-07T03:48:30.169707Z","steps":["trace[1319806974] 'agreement among raft nodes before linearized reading' (duration: 106.625135ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:48:30.592657Z","caller":"traceutil/trace.go:171","msg":"trace[1679822025] transaction","detail":"{read_only:false; response_revision:298; number_of_response:1; }","duration":"101.907048ms","start":"2026-05-07T03:48:30.490732Z","end":"2026-05-07T03:48:30.592639Z","steps":["trace[1679822025] 'process raft request' (duration: 101.640213ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:48:30.593166Z","caller":"traceutil/trace.go:171","msg":"trace[284454570] transaction","detail":"{read_only:false; response_revision:299; number_of_response:1; }","duration":"102.312725ms","start":"2026-05-07T03:48:30.490838Z","end":"2026-05-07T03:48:30.59315Z","steps":["trace[284454570] 'process raft request' (duration: 101.622302ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:48:30.593271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.756902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-05-07T03:48:30.593695Z","caller":"traceutil/trace.go:171","msg":"trace[2017222888] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:299; }","duration":"104.21057ms","start":"2026-05-07T03:48:30.489471Z","end":"2026-05-07T03:48:30.593681Z","steps":["trace[2017222888] 'agreement among raft nodes before linearized reading' (duration: 103.767801ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:48:30.837462Z","caller":"traceutil/trace.go:171","msg":"trace[1263048403] transaction","detail":"{read_only:false; response_revision:301; number_of_response:1; }","duration":"235.384009ms","start":"2026-05-07T03:48:30.602037Z","end":"2026-05-07T03:48:30.837421Z","steps":["trace[1263048403] 'process raft request' (duration: 136.142591ms)","trace[1263048403] 'compare' (duration: 99.084266ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:48:30.837809Z","caller":"traceutil/trace.go:171","msg":"trace[551792099] transaction","detail":"{read_only:false; response_revision:302; number_of_response:1; }","duration":"234.446542ms","start":"2026-05-07T03:48:30.603331Z","end":"2026-05-07T03:48:30.837777Z","steps":["trace[551792099] 'process raft request' (duration: 234.031274ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:48:30.83787Z","caller":"traceutil/trace.go:171","msg":"trace[914692905] transaction","detail":"{read_only:false; response_revision:303; number_of_response:1; }","duration":"231.144611ms","start":"2026-05-07T03:48:30.606714Z","end":"2026-05-07T03:48:30.837859Z","steps":["trace[914692905] 'process raft request' (duration: 230.685573ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:48:30.837954Z","caller":"traceutil/trace.go:171","msg":"trace[542442502] transaction","detail":"{read_only:false; response_revision:304; number_of_response:1; }","duration":"231.06822ms","start":"2026-05-07T03:48:30.606866Z","end":"2026-05-07T03:48:30.837934Z","steps":["trace[542442502] 'process raft request' (duration: 230.581281ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:48:30.838151Z","caller":"traceutil/trace.go:171","msg":"trace[210527246] transaction","detail":"{read_only:false; response_revision:305; number_of_response:1; }","duration":"229.99407ms","start":"2026-05-07T03:48:30.608131Z","end":"2026-05-07T03:48:30.838125Z","steps":["trace[210527246] 'process raft request' (duration: 229.670624ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:48:30.838785Z","caller":"traceutil/trace.go:171","msg":"trace[2016020186] transaction","detail":"{read_only:false; response_revision:306; number_of_response:1; }","duration":"230.53424ms","start":"2026-05-07T03:48:30.608238Z","end":"2026-05-07T03:48:30.838772Z","steps":["trace[2016020186] 'process raft request' (duration: 229.809887ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:48:30.83939Z","caller":"traceutil/trace.go:171","msg":"trace[508099044] transaction","detail":"{read_only:false; response_revision:308; number_of_response:1; }","duration":"230.274784ms","start":"2026-05-07T03:48:30.609099Z","end":"2026-05-07T03:48:30.839373Z","steps":["trace[508099044] 'process raft request' (duration: 230.162652ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:48:30.839445Z","caller":"traceutil/trace.go:171","msg":"trace[352206330] linearizableReadLoop","detail":"{readStateIndex:310; appliedIndex:307; }","duration":"233.891912ms","start":"2026-05-07T03:48:30.605542Z","end":"2026-05-07T03:48:30.839434Z","steps":["trace[352206330] 'read index received' (duration: 44.053957ms)","trace[352206330] 'applied index is now lower than readState.Index' (duration: 189.836865ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:48:30.83962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.053455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-05-07T03:48:30.83966Z","caller":"traceutil/trace.go:171","msg":"trace[319038491] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:310; }","duration":"234.120906ms","start":"2026-05-07T03:48:30.605529Z","end":"2026-05-07T03:48:30.83965Z","steps":["trace[319038491] 'agreement among raft nodes before linearized reading' (duration: 234.017794ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:48:30.839401Z","caller":"traceutil/trace.go:171","msg":"trace[355726204] transaction","detail":"{read_only:false; response_revision:310; number_of_response:1; }","duration":"186.86856ms","start":"2026-05-07T03:48:30.652518Z","end":"2026-05-07T03:48:30.839386Z","steps":["trace[355726204] 'process raft request' (duration: 186.83171ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:48:30.839994Z","caller":"traceutil/trace.go:171","msg":"trace[1665749250] transaction","detail":"{read_only:false; response_revision:307; number_of_response:1; }","duration":"231.234583ms","start":"2026-05-07T03:48:30.60875Z","end":"2026-05-07T03:48:30.839984Z","steps":["trace[1665749250] 'process raft request' (duration: 230.426248ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:48:30.840147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.060052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-07T03:48:30.840192Z","caller":"traceutil/trace.go:171","msg":"trace[708108238] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:310; }","duration":"169.169423ms","start":"2026-05-07T03:48:30.671012Z","end":"2026-05-07T03:48:30.840181Z","steps":["trace[708108238] 'agreement among raft nodes before linearized reading' (duration: 168.720695ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:48:30.840218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.495279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-05-07T03:48:30.840256Z","caller":"traceutil/trace.go:171","msg":"trace[1314413662] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:310; }","duration":"142.552811ms","start":"2026-05-07T03:48:30.697694Z","end":"2026-05-07T03:48:30.840246Z","steps":["trace[1314413662] 'agreement among raft nodes before linearized reading' (duration: 142.49111ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:48:30.839434Z","caller":"traceutil/trace.go:171","msg":"trace[1275796282] transaction","detail":"{read_only:false; response_revision:309; number_of_response:1; }","duration":"229.014521ms","start":"2026-05-07T03:48:30.610407Z","end":"2026-05-07T03:48:30.839421Z","steps":["trace[1275796282] 'process raft request' (duration: 228.899049ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:48:31.348268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.972156ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-07T03:48:31.348424Z","caller":"traceutil/trace.go:171","msg":"trace[1649295228] transaction","detail":"{read_only:false; response_revision:317; number_of_response:1; }","duration":"476.973154ms","start":"2026-05-07T03:48:30.871435Z","end":"2026-05-07T03:48:31.348408Z","steps":["trace[1649295228] 'process raft request' (duration: 476.920743ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:48:31.348484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:48:30.871414Z","time spent":"477.047925ms","remote":"127.0.0.1:54162","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2150,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-07T03:48:31.348485Z","caller":"traceutil/trace.go:171","msg":"trace[617790652] transaction","detail":"{read_only:false; response_revision:316; number_of_response:1; }","duration":"482.68721ms","start":"2026-05-07T03:48:30.865766Z","end":"2026-05-07T03:48:31.348454Z","steps":["trace[617790652] 'process raft request' (duration: 364.46997ms)","trace[617790652] 'compare' (duration: 117.799283ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:48:31.348729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:48:30.865748Z","time spent":"482.955485ms","remote":"127.0.0.1:53784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1731,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-07T03:48:31.581839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.856557ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-07T03:48:31.582027Z","caller":"traceutil/trace.go:171","msg":"trace[1864150315] transaction","detail":"{read_only:false; response_revision:318; number_of_response:1; }","duration":"689.547522ms","start":"2026-05-07T03:48:30.892455Z","end":"2026-05-07T03:48:31.582003Z","steps":["trace[1864150315] 'process raft request' (duration: 587.463001ms)","trace[1864150315] 'compare' (duration: 101.708993ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:48:31.582147Z","caller":"traceutil/trace.go:171","msg":"trace[198552920] linearizableReadLoop","detail":"{readStateIndex:329; appliedIndex:323; }","duration":"651.113499ms","start":"2026-05-07T03:48:30.931005Z","end":"2026-05-07T03:48:31.582119Z","steps":["trace[198552920] 'read index received' (duration: 299.241891ms)","trace[198552920] 'applied index is now lower than readState.Index' (duration: 351.870348ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:48:31.582132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:48:30.892438Z","time spent":"689.658303ms","remote":"127.0.0.1:53868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":164,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-07T03:48:31.582333Z","caller":"traceutil/trace.go:171","msg":"trace[1594031150] transaction","detail":"{read_only:false; response_revision:321; number_of_response:1; }","duration":"683.945758ms","start":"2026-05-07T03:48:30.898376Z","end":"2026-05-07T03:48:31.582322Z","steps":["trace[1594031150] 'process raft request' (duration: 683.674353ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:48:31.582407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:48:30.898364Z","time spent":"683.996848ms","remote":"127.0.0.1:54132","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4002,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T03:48:31.582452Z","caller":"traceutil/trace.go:171","msg":"trace[321889336] transaction","detail":"{read_only:false; response_revision:319; number_of_response:1; }","duration":"686.710028ms","start":"2026-05-07T03:48:30.895719Z","end":"2026-05-07T03:48:31.582429Z","steps":["trace[321889336] 'process raft request' (duration: 686.23306ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:48:31.582592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:48:30.895698Z","time spent":"686.82777ms","remote":"127.0.0.1:53852","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3527,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-07T03:48:31.582468Z","caller":"traceutil/trace.go:171","msg":"trace[1562004121] transaction","detail":"{read_only:false; response_revision:320; number_of_response:1; }","duration":"685.64395ms","start":"2026-05-07T03:48:30.896795Z","end":"2026-05-07T03:48:31.582439Z","steps":["trace[1562004121] 'process raft request' (duration: 685.205992ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:48:31.582666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:48:30.896779Z","time spent":"685.853413ms","remote":"127.0.0.1:54008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T03:48:31.582811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"651.453975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-07T03:48:31.58298Z","caller":"traceutil/trace.go:171","msg":"trace[1330054040] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:321; }","duration":"651.883223ms","start":"2026-05-07T03:48:30.930964Z","end":"2026-05-07T03:48:31.582847Z","steps":["trace[1330054040] 'agreement among raft nodes before linearized reading' (duration: 651.434245ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:48:31.58304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:48:30.930952Z","time spent":"652.064436ms","remote":"127.0.0.1:53924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-07T03:48:31.770984Z","caller":"traceutil/trace.go:171","msg":"trace[984167657] transaction","detail":"{read_only:false; response_revision:322; number_of_response:1; }","duration":"416.635057ms","start":"2026-05-07T03:48:31.354324Z","end":"2026-05-07T03:48:31.770959Z","steps":["trace[984167657] 'process raft request' (duration: 394.486357ms)","trace[984167657] 'compare' (duration: 21.858614ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:48:31.7713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:48:31.354302Z","time spent":"416.950533ms","remote":"127.0.0.1:53852","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3378,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-07T03:48:31.771289Z","caller":"traceutil/trace.go:171","msg":"trace[701880800] transaction","detail":"{read_only:false; response_revision:328; number_of_response:1; }","duration":"176.728863ms","start":"2026-05-07T03:48:31.59454Z","end":"2026-05-07T03:48:31.771269Z","steps":["trace[701880800] 'process raft request' (duration: 176.671482ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:48:31.771525Z","caller":"traceutil/trace.go:171","msg":"trace[1794423653] transaction","detail":"{read_only:false; response_revision:324; number_of_response:1; }","duration":"184.737841ms","start":"2026-05-07T03:48:31.586775Z","end":"2026-05-07T03:48:31.771512Z","steps":["trace[1794423653] 'process raft request' (duration: 184.198462ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:48:31.771561Z","caller":"traceutil/trace.go:171","msg":"trace[1609132779] transaction","detail":"{read_only:false; response_revision:323; number_of_response:1; }","duration":"417.034554ms","start":"2026-05-07T03:48:31.354515Z","end":"2026-05-07T03:48:31.77155Z","steps":["trace[1609132779] 'process raft request' (duration: 416.382562ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:48:31.772503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:48:31.354498Z","time spent":"417.943301ms","remote":"127.0.0.1:53784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1731,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-07T03:48:31.771592Z","caller":"traceutil/trace.go:171","msg":"trace[501409710] linearizableReadLoop","detail":"{readStateIndex:331; appliedIndex:329; }","duration":"189.394668ms","start":"2026-05-07T03:48:31.582189Z","end":"2026-05-07T03:48:31.771584Z","steps":["trace[501409710] 'read index received' (duration: 166.712578ms)","trace[501409710] 'applied index is now lower than readState.Index' (duration: 22.68098ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:48:31.771773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"533.765706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-07T03:48:31.772629Z","caller":"traceutil/trace.go:171","msg":"trace[727773859] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:328; }","duration":"534.659383ms","start":"2026-05-07T03:48:31.237949Z","end":"2026-05-07T03:48:31.772608Z","steps":["trace[727773859] 'agreement among raft nodes before linearized reading' (duration: 533.662114ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:48:31.772686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.852695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4017"} {"level":"warn","ts":"2026-05-07T03:48:31.772723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:48:31.237925Z","time spent":"534.756214ms","remote":"127.0.0.1:53924","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-05-07T03:48:31.772725Z","caller":"traceutil/trace.go:171","msg":"trace[359268830] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:328; }","duration":"183.911946ms","start":"2026-05-07T03:48:31.588801Z","end":"2026-05-07T03:48:31.772713Z","steps":["trace[359268830] 'agreement among raft nodes before linearized reading' (duration: 183.823584ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:48:31.771818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"533.778706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-07T03:48:31.772885Z","caller":"traceutil/trace.go:171","msg":"trace[1151331933] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:328; }","duration":"534.878536ms","start":"2026-05-07T03:48:31.237989Z","end":"2026-05-07T03:48:31.772868Z","steps":["trace[1151331933] 'agreement among raft nodes before linearized reading' (duration: 533.804586ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:48:31.772954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:48:31.237965Z","time spent":"534.972679ms","remote":"127.0.0.1:53924","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-05-07T03:48:31.771825Z","caller":"traceutil/trace.go:171","msg":"trace[422671297] transaction","detail":"{read_only:false; response_revision:326; number_of_response:1; }","duration":"183.853554ms","start":"2026-05-07T03:48:31.587952Z","end":"2026-05-07T03:48:31.771806Z","steps":["trace[422671297] 'process raft request' (duration: 183.162542ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:48:31.771891Z","caller":"traceutil/trace.go:171","msg":"trace[43320159] transaction","detail":"{read_only:false; response_revision:327; number_of_response:1; }","duration":"183.206323ms","start":"2026-05-07T03:48:31.588673Z","end":"2026-05-07T03:48:31.771879Z","steps":["trace[43320159] 'process raft request' (duration: 182.47864ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:48:31.771869Z","caller":"traceutil/trace.go:171","msg":"trace[1001401002] transaction","detail":"{read_only:false; response_revision:325; number_of_response:1; }","duration":"183.901935ms","start":"2026-05-07T03:48:31.58794Z","end":"2026-05-07T03:48:31.771842Z","steps":["trace[1001401002] 'process raft request' (duration: 183.139982ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:48:32.00879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.254865ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T03:48:32.008904Z","caller":"traceutil/trace.go:171","msg":"trace[1772670241] transaction","detail":"{read_only:false; response_revision:330; number_of_response:1; }","duration":"231.429407ms","start":"2026-05-07T03:48:31.77746Z","end":"2026-05-07T03:48:32.00889Z","steps":["trace[1772670241] 'process raft request' (duration: 130.012038ms)","trace[1772670241] 'compare' (duration: 101.161783ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:48:32.009356Z","caller":"traceutil/trace.go:171","msg":"trace[1034316306] transaction","detail":"{read_only:false; response_revision:331; number_of_response:1; }","duration":"231.361404ms","start":"2026-05-07T03:48:31.777986Z","end":"2026-05-07T03:48:32.009348Z","steps":["trace[1034316306] 'process raft request' (duration: 230.873025ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:48:32.010894Z","caller":"traceutil/trace.go:171","msg":"trace[1411581013] transaction","detail":"{read_only:false; response_revision:332; number_of_response:1; }","duration":"232.823911ms","start":"2026-05-07T03:48:31.778041Z","end":"2026-05-07T03:48:32.010865Z","steps":["trace[1411581013] 'process raft request' (duration: 231.270852ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:48:32.011682Z","caller":"traceutil/trace.go:171","msg":"trace[2066751816] transaction","detail":"{read_only:false; response_revision:334; number_of_response:1; }","duration":"233.108968ms","start":"2026-05-07T03:48:31.77856Z","end":"2026-05-07T03:48:32.011669Z","steps":["trace[2066751816] 'process raft request' (duration: 232.388924ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:48:32.012103Z","caller":"traceutil/trace.go:171","msg":"trace[1608139478] transaction","detail":"{read_only:false; response_revision:333; number_of_response:1; }","duration":"233.759119ms","start":"2026-05-07T03:48:31.778153Z","end":"2026-05-07T03:48:32.011913Z","steps":["trace[1608139478] 'process raft request' (duration: 232.639648ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:48:32.012303Z","caller":"traceutil/trace.go:171","msg":"trace[1131014999] transaction","detail":"{read_only:false; response_revision:337; number_of_response:1; }","duration":"231.223722ms","start":"2026-05-07T03:48:31.781043Z","end":"2026-05-07T03:48:32.012267Z","steps":["trace[1131014999] 'process raft request' (duration: 230.580181ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:48:32.013528Z","caller":"traceutil/trace.go:171","msg":"trace[2051626172] transaction","detail":"{read_only:false; response_revision:336; number_of_response:1; }","duration":"233.445573ms","start":"2026-05-07T03:48:31.780061Z","end":"2026-05-07T03:48:32.013506Z","steps":["trace[2051626172] 'process raft request' (duration: 231.354314ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:48:32.013991Z","caller":"traceutil/trace.go:171","msg":"trace[107189744] transaction","detail":"{read_only:false; response_revision:335; number_of_response:1; }","duration":"233.871281ms","start":"2026-05-07T03:48:31.780019Z","end":"2026-05-07T03:48:32.013891Z","steps":["trace[107189744] 'process raft request' (duration: 231.230852ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:48:32.501245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.979895ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T03:48:32.501776Z","caller":"traceutil/trace.go:171","msg":"trace[10096442] linearizableReadLoop","detail":"{readStateIndex:348; appliedIndex:346; }","duration":"388.015387ms","start":"2026-05-07T03:48:32.113737Z","end":"2026-05-07T03:48:32.501752Z","steps":["trace[10096442] 'read index received' (duration: 161.482141ms)","trace[10096442] 'applied index is now lower than readState.Index' (duration: 226.531976ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:48:32.501782Z","caller":"traceutil/trace.go:171","msg":"trace[632024291] transaction","detail":"{read_only:false; response_revision:339; number_of_response:1; }","duration":"480.156233ms","start":"2026-05-07T03:48:32.021597Z","end":"2026-05-07T03:48:32.501753Z","steps":["trace[632024291] 'process raft request' (duration: 253.544076ms)","trace[632024291] 'compare' (duration: 225.797072ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:48:32.501816Z","caller":"traceutil/trace.go:171","msg":"trace[193315110] transaction","detail":"{read_only:false; response_revision:340; number_of_response:1; }","duration":"441.333465ms","start":"2026-05-07T03:48:32.060454Z","end":"2026-05-07T03:48:32.501788Z","steps":["trace[193315110] 'process raft request' (duration: 441.214373ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:48:32.501914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.133069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-07T03:48:32.501943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.957496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/kube-proxy\" ","response":"range_response_count:1 size:185"} {"level":"warn","ts":"2026-05-07T03:48:32.50194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:48:32.021578Z","time spent":"480.267475ms","remote":"127.0.0.1:54156","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3804,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T03:48:32.501988Z","caller":"traceutil/trace.go:171","msg":"trace[1038131160] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/kube-proxy; range_end:; response_count:1; response_revision:340; }","duration":"340.038738ms","start":"2026-05-07T03:48:32.161933Z","end":"2026-05-07T03:48:32.501972Z","steps":["trace[1038131160] 'agreement among raft nodes before linearized reading' (duration: 339.962407ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:48:32.502008Z","caller":"traceutil/trace.go:171","msg":"trace[638559244] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:340; }","duration":"388.295932ms","start":"2026-05-07T03:48:32.113693Z","end":"2026-05-07T03:48:32.501989Z","steps":["trace[638559244] 'agreement among raft nodes before linearized reading' (duration: 388.126139ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:48:32.501998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:48:32.060434Z","time spent":"441.452017ms","remote":"127.0.0.1:53852","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4447,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T03:48:32.50207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:48:32.161912Z","time spent":"340.14432ms","remote":"127.0.0.1:53868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":1,"response size":208,"request content":"key:\"/registry/serviceaccounts/kube-system/kube-proxy\" "} {"level":"warn","ts":"2026-05-07T03:48:32.502097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:48:32.113676Z","time spent":"388.369263ms","remote":"127.0.0.1:53676","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-07T03:48:33.068442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.77117ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T03:48:33.068545Z","caller":"traceutil/trace.go:171","msg":"trace[1959269410] transaction","detail":"{read_only:false; response_revision:341; number_of_response:1; }","duration":"557.081187ms","start":"2026-05-07T03:48:32.511445Z","end":"2026-05-07T03:48:33.068526Z","steps":["trace[1959269410] 'process raft request' (duration: 242.140754ms)","trace[1959269410] 'compare' (duration: 314.613367ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:48:33.068603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:48:32.511379Z","time spent":"557.19875ms","remote":"127.0.0.1:54132","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4041,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T03:48:33.087953Z","caller":"traceutil/trace.go:171","msg":"trace[2018529400] transaction","detail":"{read_only:false; response_revision:342; number_of_response:1; }","duration":"573.122694ms","start":"2026-05-07T03:48:32.514812Z","end":"2026-05-07T03:48:33.087935Z","steps":["trace[2018529400] 'process raft request' (duration: 572.93355ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:48:33.088045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:48:32.514785Z","time spent":"573.218336ms","remote":"127.0.0.1:53852","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7279,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T03:48:33.088495Z","caller":"traceutil/trace.go:171","msg":"trace[1159149897] transaction","detail":"{read_only:false; response_revision:343; number_of_response:1; }","duration":"419.827195ms","start":"2026-05-07T03:48:32.668655Z","end":"2026-05-07T03:48:33.088482Z","steps":["trace[1159149897] 'process raft request' (duration: 419.237684ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:48:33.088482Z","caller":"traceutil/trace.go:171","msg":"trace[1898118232] linearizableReadLoop","detail":"{readStateIndex:350; appliedIndex:348; }","duration":"476.868632ms","start":"2026-05-07T03:48:32.611588Z","end":"2026-05-07T03:48:33.088456Z","steps":["trace[1898118232] 'read index received' (duration: 142.055541ms)","trace[1898118232] 'applied index is now lower than readState.Index' (duration: 334.811501ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:48:33.088566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:48:32.668638Z","time spent":"419.893716ms","remote":"127.0.0.1:53924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T03:48:33.088618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.032805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T03:48:33.088681Z","caller":"traceutil/trace.go:171","msg":"trace[1069502756] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:343; }","duration":"477.121457ms","start":"2026-05-07T03:48:32.611543Z","end":"2026-05-07T03:48:33.088664Z","steps":["trace[1069502756] 'agreement among raft nodes before linearized reading' (duration: 477.030795ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:48:33.088697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.391246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-07T03:48:33.088714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:48:32.611527Z","time spent":"477.179648ms","remote":"127.0.0.1:53668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-07T03:48:33.088734Z","caller":"traceutil/trace.go:171","msg":"trace[1645394780] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:343; }","duration":"190.474268ms","start":"2026-05-07T03:48:32.898248Z","end":"2026-05-07T03:48:33.088722Z","steps":["trace[1645394780] 'agreement among raft nodes before linearized reading' (duration: 190.384206ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:48:33.533474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.268956ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T03:48:33.533573Z","caller":"traceutil/trace.go:171","msg":"trace[1126242009] transaction","detail":"{read_only:false; response_revision:345; number_of_response:1; }","duration":"433.5415ms","start":"2026-05-07T03:48:33.100018Z","end":"2026-05-07T03:48:33.533559Z","steps":["trace[1126242009] 'process raft request' (duration: 251.08105ms)","trace[1126242009] 'compare' (duration: 182.157404ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:48:33.533641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:48:33.100001Z","time spent":"433.597491ms","remote":"127.0.0.1:53852","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7087,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T03:48:34.047509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.168372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.57\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-05-07T03:48:34.047555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.349599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-07T03:48:34.047583Z","caller":"traceutil/trace.go:171","msg":"trace[267633527] range","detail":"{range_begin:/registry/masterleases/199.204.45.57; range_end:; response_count:1; response_revision:345; }","duration":"177.295594ms","start":"2026-05-07T03:48:33.870271Z","end":"2026-05-07T03:48:34.047567Z","steps":["trace[267633527] 'range keys from in-memory index tree' (duration: 177.043079ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:48:34.047608Z","caller":"traceutil/trace.go:171","msg":"trace[1267555887] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:345; }","duration":"269.448601ms","start":"2026-05-07T03:48:33.778145Z","end":"2026-05-07T03:48:34.047594Z","steps":["trace[1267555887] 'range keys from in-memory index tree' (duration: 269.256717ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:48:34.04763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.867562ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6961"} {"level":"info","ts":"2026-05-07T03:48:34.047707Z","caller":"traceutil/trace.go:171","msg":"trace[818070677] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:345; }","duration":"172.999145ms","start":"2026-05-07T03:48:33.87469Z","end":"2026-05-07T03:48:34.04769Z","steps":["trace[818070677] 'range keys from in-memory index tree' (duration: 172.75922ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:48:34.466016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:48:34.049044Z","time spent":"416.965992ms","remote":"127.0.0.1:53698","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-05-07T03:48:34.684415Z","caller":"traceutil/trace.go:171","msg":"trace[1793500559] transaction","detail":"{read_only:false; response_revision:346; number_of_response:1; }","duration":"632.430112ms","start":"2026-05-07T03:48:34.051923Z","end":"2026-05-07T03:48:34.684353Z","steps":["trace[1793500559] 'process raft request' (duration: 632.088566ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:48:34.684619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:48:34.051903Z","time spent":"632.594976ms","remote":"127.0.0.1:53924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T03:48:34.684795Z","caller":"traceutil/trace.go:171","msg":"trace[816216527] transaction","detail":"{read_only:false; response_revision:349; number_of_response:1; }","duration":"629.158463ms","start":"2026-05-07T03:48:34.055611Z","end":"2026-05-07T03:48:34.684769Z","steps":["trace[816216527] 'process raft request' (duration: 629.092972ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:48:34.684946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:48:34.055602Z","time spent":"629.261554ms","remote":"127.0.0.1:53852","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6946,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T03:48:34.684966Z","caller":"traceutil/trace.go:171","msg":"trace[878752171] transaction","detail":"{read_only:false; response_revision:348; number_of_response:1; }","duration":"632.633857ms","start":"2026-05-07T03:48:34.052318Z","end":"2026-05-07T03:48:34.684952Z","steps":["trace[878752171] 'process raft request' (duration: 632.26016ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:48:34.685051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:48:34.052301Z","time spent":"632.702218ms","remote":"127.0.0.1:53924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T03:48:34.685165Z","caller":"traceutil/trace.go:171","msg":"trace[1105089252] transaction","detail":"{read_only:false; response_revision:347; number_of_response:1; }","duration":"632.911702ms","start":"2026-05-07T03:48:34.052245Z","end":"2026-05-07T03:48:34.685156Z","steps":["trace[1105089252] 'process raft request' (duration: 632.023475ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:48:34.685234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:48:34.052217Z","time spent":"632.984394ms","remote":"127.0.0.1:53924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T03:48:34.703591Z","caller":"traceutil/trace.go:171","msg":"trace[126793144] transaction","detail":"{read_only:false; response_revision:350; number_of_response:1; }","duration":"235.220457ms","start":"2026-05-07T03:48:34.468361Z","end":"2026-05-07T03:48:34.703581Z","steps":["trace[126793144] 'process raft request' (duration: 235.041204ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:48:34.703668Z","caller":"traceutil/trace.go:171","msg":"trace[1361315513] transaction","detail":"{read_only:false; response_revision:351; number_of_response:1; }","duration":"184.069559ms","start":"2026-05-07T03:48:34.519553Z","end":"2026-05-07T03:48:34.703622Z","steps":["trace[1361315513] 'process raft request' (duration: 183.950816ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:48:35.030646Z","caller":"traceutil/trace.go:171","msg":"trace[411625593] transaction","detail":"{read_only:false; response_revision:352; number_of_response:1; }","duration":"318.662772ms","start":"2026-05-07T03:48:34.711953Z","end":"2026-05-07T03:48:35.030616Z","steps":["trace[411625593] 'process raft request' (duration: 241.320749ms)","trace[411625593] 'compare' (duration: 77.220391ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:48:35.030802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:48:34.711935Z","time spent":"318.786194ms","remote":"127.0.0.1:53852","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6736,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T03:48:35.325337Z","caller":"traceutil/trace.go:171","msg":"trace[573176083] linearizableReadLoop","detail":"{readStateIndex:362; appliedIndex:361; }","duration":"167.293328ms","start":"2026-05-07T03:48:35.15802Z","end":"2026-05-07T03:48:35.325313Z","steps":["trace[573176083] 'read index received' (duration: 167.157265ms)","trace[573176083] 'applied index is now lower than readState.Index' (duration: 135.013µs)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:48:35.325436Z","caller":"traceutil/trace.go:171","msg":"trace[1703850434] transaction","detail":"{read_only:false; response_revision:353; number_of_response:1; }","duration":"225.339643ms","start":"2026-05-07T03:48:35.100061Z","end":"2026-05-07T03:48:35.3254Z","steps":["trace[1703850434] 'process raft request' (duration: 225.115979ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:48:35.325465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.42688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-07T03:48:35.325513Z","caller":"traceutil/trace.go:171","msg":"trace[347779846] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:353; }","duration":"167.549782ms","start":"2026-05-07T03:48:35.157949Z","end":"2026-05-07T03:48:35.325499Z","steps":["trace[347779846] 'agreement among raft nodes before linearized reading' (duration: 167.45434ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:48:35.563221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.986851ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-07T03:48:35.563422Z","caller":"traceutil/trace.go:171","msg":"trace[2025793557] linearizableReadLoop","detail":"{readStateIndex:364; appliedIndex:362; }","duration":"217.05989ms","start":"2026-05-07T03:48:35.346339Z","end":"2026-05-07T03:48:35.563399Z","steps":["trace[2025793557] 'read index received' (duration: 41.820805ms)","trace[2025793557] 'applied index is now lower than readState.Index' (duration: 175.234165ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:48:35.56345Z","caller":"traceutil/trace.go:171","msg":"trace[1887335643] transaction","detail":"{read_only:false; response_revision:354; number_of_response:1; }","duration":"320.898183ms","start":"2026-05-07T03:48:35.24253Z","end":"2026-05-07T03:48:35.563428Z","steps":["trace[1887335643] 'process raft request' (duration: 145.640227ms)","trace[1887335643] 'compare' (duration: 174.857839ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:48:35.563552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.207553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4403"} {"level":"warn","ts":"2026-05-07T03:48:35.563559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:48:35.242502Z","time spent":"321.021495ms","remote":"127.0.0.1:53758","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-07T03:48:35.563573Z","caller":"traceutil/trace.go:171","msg":"trace[981234871] transaction","detail":"{read_only:false; response_revision:355; number_of_response:1; }","duration":"232.877733ms","start":"2026-05-07T03:48:35.330667Z","end":"2026-05-07T03:48:35.563545Z","steps":["trace[981234871] 'process raft request' (duration: 232.659369ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:48:35.563682Z","caller":"traceutil/trace.go:171","msg":"trace[442785836] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:355; }","duration":"217.369066ms","start":"2026-05-07T03:48:35.346297Z","end":"2026-05-07T03:48:35.563666Z","steps":["trace[442785836] 'agreement among raft nodes before linearized reading' (duration: 217.181123ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:48:35.84874Z","caller":"traceutil/trace.go:171","msg":"trace[188728609] linearizableReadLoop","detail":"{readStateIndex:365; appliedIndex:364; }","duration":"237.001379ms","start":"2026-05-07T03:48:35.611718Z","end":"2026-05-07T03:48:35.848719Z","steps":["trace[188728609] 'read index received' (duration: 236.867427ms)","trace[188728609] 'applied index is now lower than readState.Index' (duration: 133.162µs)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:48:35.848757Z","caller":"traceutil/trace.go:171","msg":"trace[1569899845] transaction","detail":"{read_only:false; response_revision:356; number_of_response:1; }","duration":"281.186678ms","start":"2026-05-07T03:48:35.567551Z","end":"2026-05-07T03:48:35.848738Z","steps":["trace[1569899845] 'process raft request' (duration: 281.054356ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:48:35.84886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.126601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T03:48:35.848891Z","caller":"traceutil/trace.go:171","msg":"trace[241664372] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:356; }","duration":"237.204123ms","start":"2026-05-07T03:48:35.611678Z","end":"2026-05-07T03:48:35.848882Z","steps":["trace[241664372] 'agreement among raft nodes before linearized reading' (duration: 237.125181ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:48:36.227103Z","caller":"traceutil/trace.go:171","msg":"trace[543172495] transaction","detail":"{read_only:false; response_revision:358; number_of_response:1; }","duration":"150.70743ms","start":"2026-05-07T03:48:36.076373Z","end":"2026-05-07T03:48:36.22708Z","steps":["trace[543172495] 'process raft request' (duration: 150.585548ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:48:36.591827Z","caller":"traceutil/trace.go:171","msg":"trace[1241487573] transaction","detail":"{read_only:false; response_revision:360; number_of_response:1; }","duration":"247.181139ms","start":"2026-05-07T03:48:36.344618Z","end":"2026-05-07T03:48:36.591799Z","steps":["trace[1241487573] 'process raft request' (duration: 208.756356ms)","trace[1241487573] 'compare' (duration: 38.301361ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:48:39.020943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.405322077s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T03:48:39.021045Z","caller":"traceutil/trace.go:171","msg":"trace[228293336] transaction","detail":"{read_only:false; response_revision:361; number_of_response:1; }","duration":"2.326935267s","start":"2026-05-07T03:48:36.694091Z","end":"2026-05-07T03:48:39.021026Z","steps":["trace[228293336] 'process raft request' (duration: 921.452266ms)","trace[228293336] 'compare' (duration: 1.40497495s)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:48:39.021107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:48:36.694072Z","time spent":"2.327006817s","remote":"127.0.0.1:53924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T03:48:39.518718Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.837917949s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-07T03:48:39.612476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000039972s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-05-07T03:48:39.612568Z","caller":"traceutil/trace.go:171","msg":"trace[1652402674] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000209765s","start":"2026-05-07T03:48:37.612338Z","end":"2026-05-07T03:48:39.612548Z","steps":["trace[1652402674] 'agreement among raft nodes before linearized reading' (duration: 2.000091563s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:48:39.612619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:48:37.612318Z","time spent":"2.000292056s","remote":"127.0.0.1:53668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-07T03:48:39.934871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.674249ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T03:48:39.935065Z","caller":"traceutil/trace.go:171","msg":"trace[1558453903] transaction","detail":"{read_only:false; response_revision:363; number_of_response:1; }","duration":"3.240046079s","start":"2026-05-07T03:48:36.695002Z","end":"2026-05-07T03:48:39.935048Z","steps":["trace[1558453903] 'process raft request' (duration: 3.239961148s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:48:39.935143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:48:36.694985Z","time spent":"3.24012355s","remote":"127.0.0.1:53924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T03:48:39.935141Z","caller":"traceutil/trace.go:171","msg":"trace[1493367087] transaction","detail":"{read_only:false; response_revision:362; number_of_response:1; }","duration":"3.240859674s","start":"2026-05-07T03:48:36.694227Z","end":"2026-05-07T03:48:39.935087Z","steps":["trace[1493367087] 'process raft request' (duration: 2.82488832s)","trace[1493367087] 'compare' (duration: 415.528185ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:48:39.935401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:48:36.694215Z","time spent":"3.241063107s","remote":"127.0.0.1:53924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T03:48:39.935869Z","caller":"traceutil/trace.go:171","msg":"trace[1902318723] linearizableReadLoop","detail":"{readStateIndex:373; appliedIndex:370; }","duration":"2.368471645s","start":"2026-05-07T03:48:37.567379Z","end":"2026-05-07T03:48:39.93585Z","steps":["trace[1902318723] 'read index received' (duration: 48.119671ms)","trace[1902318723] 'applied index is now lower than readState.Index' (duration: 2.320350514s)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:48:39.935947Z","caller":"traceutil/trace.go:171","msg":"trace[252351360] transaction","detail":"{read_only:false; response_revision:364; number_of_response:1; }","duration":"2.32233292s","start":"2026-05-07T03:48:37.613561Z","end":"2026-05-07T03:48:39.935894Z","steps":["trace[252351360] 'process raft request' (duration: 2.321452845s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:48:39.936146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.368768761s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-07T03:48:39.936205Z","caller":"traceutil/trace.go:171","msg":"trace[1319160417] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:364; }","duration":"2.368860852s","start":"2026-05-07T03:48:37.567328Z","end":"2026-05-07T03:48:39.936189Z","steps":["trace[1319160417] 'agreement among raft nodes before linearized reading' (duration: 2.368596888s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:48:39.936241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:48:37.567309Z","time spent":"2.368924974s","remote":"127.0.0.1:53924","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-05-07T03:48:39.936243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:48:37.613536Z","time spent":"2.322511534s","remote":"127.0.0.1:53924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T03:48:39.938821Z","caller":"traceutil/trace.go:171","msg":"trace[405815447] transaction","detail":"{read_only:false; response_revision:365; number_of_response:1; }","duration":"2.039972122s","start":"2026-05-07T03:48:37.898815Z","end":"2026-05-07T03:48:39.938787Z","steps":["trace[405815447] 'process raft request' (duration: 2.039710307s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:48:39.939224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:48:37.89879Z","time spent":"2.040390151s","remote":"127.0.0.1:53842","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4354,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T03:48:39.972625Z","caller":"traceutil/trace.go:171","msg":"trace[1863019056] transaction","detail":"{read_only:false; response_revision:366; number_of_response:1; }","duration":"356.199327ms","start":"2026-05-07T03:48:39.616402Z","end":"2026-05-07T03:48:39.972601Z","steps":["trace[1863019056] 'process raft request' (duration: 356.042064ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:48:39.972727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:48:39.616378Z","time spent":"356.304468ms","remote":"127.0.0.1:53758","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-07T03:48:39.973165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.169131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T03:48:39.973211Z","caller":"traceutil/trace.go:171","msg":"trace[2124820559] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:367; }","duration":"353.213301ms","start":"2026-05-07T03:48:39.619984Z","end":"2026-05-07T03:48:39.973198Z","steps":["trace[2124820559] 'agreement among raft nodes before linearized reading' (duration: 353.14381ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:48:39.973239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:48:39.61995Z","time spent":"353.282113ms","remote":"127.0.0.1:53676","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-07T03:48:39.973293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.004454794s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4322"} {"level":"info","ts":"2026-05-07T03:48:39.973409Z","caller":"traceutil/trace.go:171","msg":"trace[524746097] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:367; }","duration":"2.004604237s","start":"2026-05-07T03:48:37.968761Z","end":"2026-05-07T03:48:39.973365Z","steps":["trace[524746097] 'agreement among raft nodes before linearized reading' (duration: 2.004368623s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:48:39.973461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:48:37.968745Z","time spent":"2.004705489s","remote":"127.0.0.1:53852","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":4345,"request content":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" "} {"level":"warn","ts":"2026-05-07T03:49:00.525799Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13322102897941247839,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-07T03:49:01.54061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"806.055529ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T03:49:01.541211Z","caller":"traceutil/trace.go:171","msg":"trace[1760298450] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"1.630203182s","start":"2026-05-07T03:48:59.910981Z","end":"2026-05-07T03:49:01.541184Z","steps":["trace[1760298450] 'process raft request' (duration: 823.440801ms)","trace[1760298450] 'compare' (duration: 805.866784ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:49:01.541377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:48:59.910953Z","time spent":"1.630377026s","remote":"127.0.0.1:53722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29452,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T03:49:01.542219Z","caller":"traceutil/trace.go:171","msg":"trace[1726950215] linearizableReadLoop","detail":"{readStateIndex:525; appliedIndex:522; }","duration":"1.517070468s","start":"2026-05-07T03:49:00.025135Z","end":"2026-05-07T03:49:01.542205Z","steps":["trace[1726950215] 'read index received' (duration: 709.376248ms)","trace[1726950215] 'applied index is now lower than readState.Index' (duration: 807.69323ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:49:01.542421Z","caller":"traceutil/trace.go:171","msg":"trace[771900319] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"1.023703189s","start":"2026-05-07T03:49:00.518705Z","end":"2026-05-07T03:49:01.542409Z","steps":["trace[771900319] 'process raft request' (duration: 1.023454805s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:49:01.542552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:49:00.518672Z","time spent":"1.023832842s","remote":"127.0.0.1:53924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T03:49:01.542697Z","caller":"traceutil/trace.go:171","msg":"trace[1879722852] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"1.549385617s","start":"2026-05-07T03:48:59.993093Z","end":"2026-05-07T03:49:01.542479Z","steps":["trace[1879722852] 'process raft request' (duration: 1.548976489s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:49:01.542888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:48:59.993057Z","time spent":"1.549753424s","remote":"127.0.0.1:53722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-07T03:49:01.542973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.517845122s","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-05-07T03:49:01.543029Z","caller":"traceutil/trace.go:171","msg":"trace[323751885] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:506; }","duration":"1.517937403s","start":"2026-05-07T03:49:00.025071Z","end":"2026-05-07T03:49:01.543009Z","steps":["trace[323751885] 'agreement among raft nodes before linearized reading' (duration: 1.517877072s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:49:01.543076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:49:00.025011Z","time spent":"1.518055405s","remote":"127.0.0.1:53924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-07T03:49:01.542921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.513533532s","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-05-07T03:49:01.543179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.681431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-07T03:49:01.543209Z","caller":"traceutil/trace.go:171","msg":"trace[409302809] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:506; }","duration":"413.740253ms","start":"2026-05-07T03:49:01.129461Z","end":"2026-05-07T03:49:01.543201Z","steps":["trace[409302809] 'agreement among raft nodes before linearized reading' (duration: 413.691642ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:49:01.543181Z","caller":"traceutil/trace.go:171","msg":"trace[760951983] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:506; }","duration":"1.513825268s","start":"2026-05-07T03:49:00.029342Z","end":"2026-05-07T03:49:01.543167Z","steps":["trace[760951983] 'agreement among raft nodes before linearized reading' (duration: 1.513505702s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:49:01.543271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.063894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T03:49:01.543303Z","caller":"traceutil/trace.go:171","msg":"trace[994783873] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:506; }","duration":"418.105644ms","start":"2026-05-07T03:49:01.12519Z","end":"2026-05-07T03:49:01.543295Z","steps":["trace[994783873] 'agreement among raft nodes before linearized reading' (duration: 418.059303ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:49:01.54334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:49:01.125184Z","time spent":"418.146055ms","remote":"127.0.0.1:52690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":109,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:500 "} {"level":"warn","ts":"2026-05-07T03:49:01.54342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:49:00.029326Z","time spent":"1.513953321s","remote":"127.0.0.1:53924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-07T03:49:01.543234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:49:01.12945Z","time spent":"413.778784ms","remote":"127.0.0.1:53924","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-05-07T03:49:01.543678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.147204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T03:49:01.543722Z","caller":"traceutil/trace.go:171","msg":"trace[1802528567] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:506; }","duration":"419.201525ms","start":"2026-05-07T03:49:01.12451Z","end":"2026-05-07T03:49:01.543712Z","steps":["trace[1802528567] 'agreement among raft nodes before linearized reading' (duration: 419.133633ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:49:01.543754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:49:01.124507Z","time spent":"419.239835ms","remote":"127.0.0.1:52698","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"warn","ts":"2026-05-07T03:49:01.544541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.492786428s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-07T03:49:01.547952Z","caller":"traceutil/trace.go:171","msg":"trace[475499184] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:506; }","duration":"1.496211182s","start":"2026-05-07T03:49:00.051718Z","end":"2026-05-07T03:49:01.54793Z","steps":["trace[475499184] 'agreement among raft nodes before linearized reading' (duration: 1.492743637s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:49:01.548025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:49:00.051673Z","time spent":"1.496329774s","remote":"127.0.0.1:53924","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-05-07T03:49:01.543797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"462.693561ms","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-05-07T03:49:01.54821Z","caller":"traceutil/trace.go:171","msg":"trace[1019288978] 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:506; }","duration":"467.155532ms","start":"2026-05-07T03:49:01.081045Z","end":"2026-05-07T03:49:01.5482Z","steps":["trace[1019288978] 'agreement among raft nodes before linearized reading' (duration: 462.67483ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:49:01.548238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:49:01.081025Z","time spent":"467.203123ms","remote":"127.0.0.1:52684","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true "} {"level":"warn","ts":"2026-05-07T03:49:01.544764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.248964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T03:49:01.548409Z","caller":"traceutil/trace.go:171","msg":"trace[968926164] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:506; }","duration":"423.865121ms","start":"2026-05-07T03:49:01.124483Z","end":"2026-05-07T03:49:01.548348Z","steps":["trace[968926164] 'agreement among raft nodes before linearized reading' (duration: 420.238465ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:49:01.548495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:49:01.124477Z","time spent":"423.968073ms","remote":"127.0.0.1:52698","response type":"/etcdserverpb.KV/Range","request count":0,"request size":109,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 "} {"level":"warn","ts":"2026-05-07T03:49:01.545022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.759407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T03:49:01.548685Z","caller":"traceutil/trace.go:171","msg":"trace[1146158825] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:506; }","duration":"442.429294ms","start":"2026-05-07T03:49:01.106244Z","end":"2026-05-07T03:49:01.548673Z","steps":["trace[1146158825] 'agreement among raft nodes before linearized reading' (duration: 438.561423ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:49:01.548906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:49:01.106195Z","time spent":"442.6992ms","remote":"127.0.0.1:52690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"warn","ts":"2026-05-07T03:49:01.54509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.828858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T03:49:01.549145Z","caller":"traceutil/trace.go:171","msg":"trace[2024279496] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:506; }","duration":"442.935984ms","start":"2026-05-07T03:49:01.106197Z","end":"2026-05-07T03:49:01.549133Z","steps":["trace[2024279496] 'agreement among raft nodes before linearized reading' (duration: 438.854808ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:49:01.549343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:49:01.106188Z","time spent":"442.979155ms","remote":"127.0.0.1:52690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":109,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 "} {"level":"warn","ts":"2026-05-07T03:49:01.545127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.763539ms","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\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T03:49:01.549461Z","caller":"traceutil/trace.go:171","msg":"trace[2133935745] 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:506; }","duration":"468.12758ms","start":"2026-05-07T03:49:01.081325Z","end":"2026-05-07T03:49:01.549453Z","steps":["trace[2133935745] 'agreement among raft nodes before linearized reading' (duration: 463.78544ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:49:01.549487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:49:01.081306Z","time spent":"468.17232ms","remote":"127.0.0.1:52684","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:500 "} {"level":"warn","ts":"2026-05-07T03:49:01.545322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"932.696534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T03:49:01.549655Z","caller":"traceutil/trace.go:171","msg":"trace[1776085577] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:506; }","duration":"937.064675ms","start":"2026-05-07T03:49:00.612581Z","end":"2026-05-07T03:49:01.549646Z","steps":["trace[1776085577] 'agreement among raft nodes before linearized reading' (duration: 932.716145ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:49:01.549689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:49:00.612563Z","time spent":"937.119266ms","remote":"127.0.0.1:53676","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-07T03:49:01.547017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.994641ms","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\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T03:49:01.549875Z","caller":"traceutil/trace.go:171","msg":"trace[369306233] 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:506; }","duration":"468.922765ms","start":"2026-05-07T03:49:01.080944Z","end":"2026-05-07T03:49:01.549839Z","steps":["trace[369306233] 'agreement among raft nodes before linearized reading' (duration: 462.962315ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:49:01.550427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:49:01.080919Z","time spent":"468.997436ms","remote":"127.0.0.1:52684","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:10000 "} {"level":"info","ts":"2026-05-07T03:49:02.06252Z","caller":"traceutil/trace.go:171","msg":"trace[777503183] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"508.60598ms","start":"2026-05-07T03:49:01.55388Z","end":"2026-05-07T03:49:02.062486Z","steps":["trace[777503183] 'process raft request' (duration: 497.25137ms)","trace[777503183] 'compare' (duration: 11.071045ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:49:02.062682Z","caller":"traceutil/trace.go:171","msg":"trace[1999533704] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"508.428407ms","start":"2026-05-07T03:49:01.554236Z","end":"2026-05-07T03:49:02.062665Z","steps":["trace[1999533704] 'process raft request' (duration: 508.185862ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:49:02.062873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:49:01.554229Z","time spent":"508.56728ms","remote":"127.0.0.1:53924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T03:49:02.063063Z","caller":"traceutil/trace.go:171","msg":"trace[968041693] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"509.018588ms","start":"2026-05-07T03:49:01.554028Z","end":"2026-05-07T03:49:02.063046Z","steps":["trace[968041693] 'process raft request' (duration: 508.323765ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:49:02.063156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:49:01.554022Z","time spent":"509.090429ms","remote":"127.0.0.1:53924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T03:49:02.063161Z","caller":"traceutil/trace.go:171","msg":"trace[57025010] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"507.49332ms","start":"2026-05-07T03:49:01.555608Z","end":"2026-05-07T03:49:02.063101Z","steps":["trace[57025010] 'process raft request' (duration: 506.98722ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:49:02.063428Z","caller":"traceutil/trace.go:171","msg":"trace[716128811] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"506.259786ms","start":"2026-05-07T03:49:01.557149Z","end":"2026-05-07T03:49:02.063409Z","steps":["trace[716128811] 'process raft request' (duration: 505.943791ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:49:02.06349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:49:01.55559Z","time spent":"507.700843ms","remote":"127.0.0.1:53722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23057,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T03:49:02.063555Z","caller":"traceutil/trace.go:171","msg":"trace[961457678] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"467.5458ms","start":"2026-05-07T03:49:01.595996Z","end":"2026-05-07T03:49:02.063542Z","steps":["trace[961457678] 'process raft request' (duration: 467.389067ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:49:02.063538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:49:01.557134Z","time spent":"506.350038ms","remote":"127.0.0.1:53924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T03:49:02.063555Z","caller":"traceutil/trace.go:171","msg":"trace[2055920292] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"506.361269ms","start":"2026-05-07T03:49:01.557176Z","end":"2026-05-07T03:49:02.063537Z","steps":["trace[2055920292] 'process raft request' (duration: 506.113634ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:49:02.06358Z","caller":"traceutil/trace.go:171","msg":"trace[1225757123] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"467.53954ms","start":"2026-05-07T03:49:01.596008Z","end":"2026-05-07T03:49:02.063548Z","steps":["trace[1225757123] 'process raft request' (duration: 467.492439ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:49:02.063623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:49:01.59597Z","time spent":"467.615381ms","remote":"127.0.0.1:53722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":150480,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T03:49:02.063667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:49:01.557168Z","time spent":"506.44414ms","remote":"127.0.0.1:53722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29461,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T03:49:02.063715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:49:01.595974Z","time spent":"467.682922ms","remote":"127.0.0.1:53722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17062,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-07T03:49:02.06601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:49:01.553867Z","time spent":"508.737782ms","remote":"127.0.0.1:53924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T03:49:08.280239Z","caller":"traceutil/trace.go:171","msg":"trace[1730321074] linearizableReadLoop","detail":"{readStateIndex:605; appliedIndex:604; }","duration":"186.607146ms","start":"2026-05-07T03:49:08.093598Z","end":"2026-05-07T03:49:08.280205Z","steps":["trace[1730321074] 'read index received' (duration: 184.12439ms)","trace[1730321074] 'applied index is now lower than readState.Index' (duration: 2.480046ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:49:08.280275Z","caller":"traceutil/trace.go:171","msg":"trace[1071652590] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"195.46873ms","start":"2026-05-07T03:49:08.084767Z","end":"2026-05-07T03:49:08.280236Z","steps":["trace[1071652590] 'process raft request' (duration: 192.18808ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:49:08.280443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.787909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-07T03:49:08.280473Z","caller":"traceutil/trace.go:171","msg":"trace[964492472] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:585; }","duration":"186.86021ms","start":"2026-05-07T03:49:08.093605Z","end":"2026-05-07T03:49:08.280465Z","steps":["trace[964492472] 'agreement among raft nodes before linearized reading' (duration: 186.752088ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:49:08.280487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.84382ms","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-05-07T03:49:08.280575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.440152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-07T03:49:08.280589Z","caller":"traceutil/trace.go:171","msg":"trace[1953426190] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:585; }","duration":"186.989223ms","start":"2026-05-07T03:49:08.093576Z","end":"2026-05-07T03:49:08.280565Z","steps":["trace[1953426190] 'agreement among raft nodes before linearized reading' (duration: 186.755569ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:49:08.280627Z","caller":"traceutil/trace.go:171","msg":"trace[1667896175] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:585; }","duration":"186.558855ms","start":"2026-05-07T03:49:08.094055Z","end":"2026-05-07T03:49:08.280614Z","steps":["trace[1667896175] 'agreement among raft nodes before linearized reading' (duration: 186.429862ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:49:08.280615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.184461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-7bf45\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-05-07T03:49:08.2807Z","caller":"traceutil/trace.go:171","msg":"trace[1998691523] range","detail":"{range_begin:/registry/pods/kube-system/cilium-7bf45; range_end:; response_count:1; response_revision:585; }","duration":"130.281294ms","start":"2026-05-07T03:49:08.150393Z","end":"2026-05-07T03:49:08.280675Z","steps":["trace[1998691523] 'agreement among raft nodes before linearized reading' (duration: 130.025958ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:49:08.280717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.838151ms","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-05-07T03:49:08.280827Z","caller":"traceutil/trace.go:171","msg":"trace[1985416088] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:585; }","duration":"187.186627ms","start":"2026-05-07T03:49:08.093617Z","end":"2026-05-07T03:49:08.280803Z","steps":["trace[1985416088] 'agreement among raft nodes before linearized reading' (duration: 186.914961ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:49:08.617401Z","caller":"traceutil/trace.go:171","msg":"trace[523792695] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"332.631041ms","start":"2026-05-07T03:49:08.284738Z","end":"2026-05-07T03:49:08.617369Z","steps":["trace[523792695] 'process raft request' (duration: 316.263738ms)","trace[523792695] 'compare' (duration: 16.006357ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:49:08.618011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:49:08.284712Z","time spent":"332.786194ms","remote":"127.0.0.1:53924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T03:49:08.925238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.02716ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T03:49:08.925546Z","caller":"traceutil/trace.go:171","msg":"trace[1512016136] linearizableReadLoop","detail":"{readStateIndex:611; appliedIndex:606; }","duration":"312.893394ms","start":"2026-05-07T03:49:08.612635Z","end":"2026-05-07T03:49:08.925528Z","steps":["trace[1512016136] 'read index received' (duration: 149.401596ms)","trace[1512016136] 'applied index is now lower than readState.Index' (duration: 163.490738ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:49:08.925643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.997547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T03:49:08.925676Z","caller":"traceutil/trace.go:171","msg":"trace[775611031] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:591; }","duration":"313.105428ms","start":"2026-05-07T03:49:08.612561Z","end":"2026-05-07T03:49:08.925667Z","steps":["trace[775611031] 'agreement among raft nodes before linearized reading' (duration: 313.032116ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:49:08.925661Z","caller":"traceutil/trace.go:171","msg":"trace[763807947] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"640.831109ms","start":"2026-05-07T03:49:08.2848Z","end":"2026-05-07T03:49:08.925631Z","steps":["trace[763807947] 'process raft request' (duration: 477.316651ms)","trace[763807947] 'compare' (duration: 162.877947ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:49:08.925713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:49:08.612533Z","time spent":"313.172659ms","remote":"127.0.0.1:53676","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-07T03:49:08.92581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:49:08.284754Z","time spent":"640.974282ms","remote":"127.0.0.1:53924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T03:49:08.925935Z","caller":"traceutil/trace.go:171","msg":"trace[862279532] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"631.839282ms","start":"2026-05-07T03:49:08.294076Z","end":"2026-05-07T03:49:08.925915Z","steps":["trace[862279532] 'process raft request' (duration: 631.365674ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:49:08.926058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:49:08.294058Z","time spent":"631.935703ms","remote":"127.0.0.1:53852","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T03:49:08.926094Z","caller":"traceutil/trace.go:171","msg":"trace[175729963] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"640.37814ms","start":"2026-05-07T03:49:08.285707Z","end":"2026-05-07T03:49:08.926085Z","steps":["trace[175729963] 'process raft request' (duration: 639.632547ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:49:08.9261Z","caller":"traceutil/trace.go:171","msg":"trace[524653705] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"589.575829ms","start":"2026-05-07T03:49:08.336496Z","end":"2026-05-07T03:49:08.926072Z","steps":["trace[524653705] 'process raft request' (duration: 588.991748ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:49:08.926158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:49:08.285691Z","time spent":"640.436762ms","remote":"127.0.0.1:53924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T03:49:08.926056Z","caller":"traceutil/trace.go:171","msg":"trace[360798965] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"640.037083ms","start":"2026-05-07T03:49:08.286008Z","end":"2026-05-07T03:49:08.926045Z","steps":["trace[360798965] 'process raft request' (duration: 639.391662ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:49:08.926226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:49:08.336459Z","time spent":"589.703562ms","remote":"127.0.0.1:53902","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-07T03:49:08.926313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:49:08.286001Z","time spent":"640.225578ms","remote":"127.0.0.1:53924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T03:49:10.413489Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.472765106s","expected-duration":"1s"} {"level":"info","ts":"2026-05-07T03:49:10.413873Z","caller":"traceutil/trace.go:171","msg":"trace[1607252388] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"1.473198144s","start":"2026-05-07T03:49:08.940651Z","end":"2026-05-07T03:49:10.413849Z","steps":["trace[1607252388] 'process raft request' (duration: 1.473050501s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:49:10.414033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:49:08.940643Z","time spent":"1.473325706s","remote":"127.0.0.1:53852","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2735,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-07T03:49:10.539766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.987018ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-07T03:49:10.540705Z","caller":"traceutil/trace.go:171","msg":"trace[1920712206] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"122.247015ms","start":"2026-05-07T03:49:10.41842Z","end":"2026-05-07T03:49:10.540667Z","steps":["trace[1920712206] 'process raft request' (duration: 121.972369ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:49:10.540853Z","caller":"traceutil/trace.go:171","msg":"trace[1429418526] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"120.375009ms","start":"2026-05-07T03:49:10.420455Z","end":"2026-05-07T03:49:10.54083Z","steps":["trace[1429418526] 'process raft request' (duration: 120.216256ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:49:10.540986Z","caller":"traceutil/trace.go:171","msg":"trace[1289023667] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"120.684185ms","start":"2026-05-07T03:49:10.420241Z","end":"2026-05-07T03:49:10.540925Z","steps":["trace[1289023667] 'process raft request' (duration: 120.376579ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:49:10.541093Z","caller":"traceutil/trace.go:171","msg":"trace[166034763] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"121.797715ms","start":"2026-05-07T03:49:10.41927Z","end":"2026-05-07T03:49:10.541067Z","steps":["trace[166034763] 'process raft request' (duration: 121.303116ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:49:11.201492Z","caller":"traceutil/trace.go:171","msg":"trace[495758841] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"268.044645ms","start":"2026-05-07T03:49:10.933416Z","end":"2026-05-07T03:49:11.201461Z","steps":["trace[495758841] 'process raft request' (duration: 267.84278ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:49:11.386362Z","caller":"traceutil/trace.go:171","msg":"trace[1989997164] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"451.34402ms","start":"2026-05-07T03:49:10.934981Z","end":"2026-05-07T03:49:11.386325Z","steps":["trace[1989997164] 'process raft request' (duration: 451.116345ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:49:11.386583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:49:10.934972Z","time spent":"451.507823ms","remote":"127.0.0.1:53924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T03:49:11.386674Z","caller":"traceutil/trace.go:171","msg":"trace[849174452] linearizableReadLoop","detail":"{readStateIndex:621; appliedIndex:618; }","duration":"225.512026ms","start":"2026-05-07T03:49:11.161135Z","end":"2026-05-07T03:49:11.386647Z","steps":["trace[849174452] 'read index received' (duration: 40.092862ms)","trace[849174452] 'applied index is now lower than readState.Index' (duration: 185.418034ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:49:11.386682Z","caller":"traceutil/trace.go:171","msg":"trace[2116812892] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"451.614645ms","start":"2026-05-07T03:49:10.935025Z","end":"2026-05-07T03:49:11.386639Z","steps":["trace[2116812892] 'process raft request' (duration: 451.231008ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:49:11.386773Z","caller":"traceutil/trace.go:171","msg":"trace[1172326047] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"223.635242ms","start":"2026-05-07T03:49:11.163079Z","end":"2026-05-07T03:49:11.386714Z","steps":["trace[1172326047] 'process raft request' (duration: 223.451299ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:49:11.386915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.75299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-7bf45\" ","response":"range_response_count:1 size:17145"} {"level":"warn","ts":"2026-05-07T03:49:11.386924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:49:10.935021Z","time spent":"451.759488ms","remote":"127.0.0.1:53924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T03:49:11.386953Z","caller":"traceutil/trace.go:171","msg":"trace[1773704387] range","detail":"{range_begin:/registry/pods/kube-system/cilium-7bf45; range_end:; response_count:1; response_revision:601; }","duration":"225.855653ms","start":"2026-05-07T03:49:11.161085Z","end":"2026-05-07T03:49:11.38694Z","steps":["trace[1773704387] 'agreement among raft nodes before linearized reading' (duration: 225.652479ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:49:11.628633Z","caller":"traceutil/trace.go:171","msg":"trace[1829214368] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"227.037985ms","start":"2026-05-07T03:49:11.401563Z","end":"2026-05-07T03:49:11.628601Z","steps":["trace[1829214368] 'process raft request' (duration: 226.764269ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:49:12.780166Z","caller":"traceutil/trace.go:171","msg":"trace[264311723] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"141.137674ms","start":"2026-05-07T03:49:12.639006Z","end":"2026-05-07T03:49:12.780143Z","steps":["trace[264311723] 'process raft request' (duration: 140.986551ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:49:14.679244Z","caller":"traceutil/trace.go:171","msg":"trace[760115551] linearizableReadLoop","detail":"{readStateIndex:656; appliedIndex:655; }","duration":"173.574944ms","start":"2026-05-07T03:49:14.505648Z","end":"2026-05-07T03:49:14.679223Z","steps":["trace[760115551] 'read index received' (duration: 173.33809ms)","trace[760115551] 'applied index is now lower than readState.Index' (duration: 235.904µs)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:49:14.679404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.750449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T03:49:14.679443Z","caller":"traceutil/trace.go:171","msg":"trace[1492598103] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:0; response_revision:633; }","duration":"173.845431ms","start":"2026-05-07T03:49:14.505587Z","end":"2026-05-07T03:49:14.679432Z","steps":["trace[1492598103] 'agreement among raft nodes before linearized reading' (duration: 173.718868ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:49:14.679387Z","caller":"traceutil/trace.go:171","msg":"trace[635551985] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"372.51065ms","start":"2026-05-07T03:49:14.306758Z","end":"2026-05-07T03:49:14.679268Z","steps":["trace[635551985] 'process raft request' (duration: 372.344096ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:49:14.679632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:49:14.306734Z","time spent":"372.829306ms","remote":"127.0.0.1:53722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3202,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-07T03:49:27.788635Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13322102897941248329,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-07T03:49:28.289252Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13322102897941248329,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-07T03:49:28.315328Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.050817583s","expected-duration":"1s"} {"level":"info","ts":"2026-05-07T03:49:28.31576Z","caller":"traceutil/trace.go:171","msg":"trace[2142167306] linearizableReadLoop","detail":"{readStateIndex:777; appliedIndex:776; }","duration":"1.027843686s","start":"2026-05-07T03:49:27.287892Z","end":"2026-05-07T03:49:28.315736Z","steps":["trace[2142167306] 'read index received' (duration: 1.027671593s)","trace[2142167306] 'applied index is now lower than readState.Index' (duration: 171.153µs)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:49:28.315814Z","caller":"traceutil/trace.go:171","msg":"trace[1898043132] transaction","detail":"{read_only:false; response_revision:750; number_of_response:1; }","duration":"1.051360382s","start":"2026-05-07T03:49:27.264426Z","end":"2026-05-07T03:49:28.315786Z","steps":["trace[1898043132] 'process raft request' (duration: 1.051169789s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:49:28.316005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.028099861s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-07T03:49:28.316045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.646541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-07T03:49:28.316068Z","caller":"traceutil/trace.go:171","msg":"trace[1472505987] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:750; }","duration":"1.028198712s","start":"2026-05-07T03:49:27.287855Z","end":"2026-05-07T03:49:28.316054Z","steps":["trace[1472505987] 'agreement among raft nodes before linearized reading' (duration: 1.028025019s)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:49:28.316104Z","caller":"traceutil/trace.go:171","msg":"trace[237358520] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:750; }","duration":"379.744513ms","start":"2026-05-07T03:49:27.936343Z","end":"2026-05-07T03:49:28.316088Z","steps":["trace[237358520] 'agreement among raft nodes before linearized reading' (duration: 379.643731ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:49:28.316044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:49:27.264413Z","time spent":"1.051528935s","remote":"127.0.0.1:53852","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4024,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T03:49:28.316225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.16382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"warn","ts":"2026-05-07T03:49:28.316229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"703.526819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T03:49:28.31626Z","caller":"traceutil/trace.go:171","msg":"trace[1553936688] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:750; }","duration":"137.224422ms","start":"2026-05-07T03:49:28.179027Z","end":"2026-05-07T03:49:28.316251Z","steps":["trace[1553936688] 'agreement among raft nodes before linearized reading' (duration: 137.14162ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:49:28.316279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"809.157726ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-07T03:49:28.316284Z","caller":"traceutil/trace.go:171","msg":"trace[853711116] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:750; }","duration":"703.593061ms","start":"2026-05-07T03:49:27.612673Z","end":"2026-05-07T03:49:28.316266Z","steps":["trace[853711116] 'agreement among raft nodes before linearized reading' (duration: 703.52212ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:49:28.31634Z","caller":"traceutil/trace.go:171","msg":"trace[203185740] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:750; }","duration":"809.232238ms","start":"2026-05-07T03:49:27.507097Z","end":"2026-05-07T03:49:28.316329Z","steps":["trace[203185740] 'agreement among raft nodes before linearized reading' (duration: 809.105776ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:49:28.316369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:49:27.612632Z","time spent":"703.729354ms","remote":"127.0.0.1:53676","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-07T03:49:28.316372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:49:27.507089Z","time spent":"809.274049ms","remote":"127.0.0.1:53924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-07T03:49:28.316145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"810.211905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-07T03:49:28.316496Z","caller":"traceutil/trace.go:171","msg":"trace[383609427] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:750; }","duration":"810.645454ms","start":"2026-05-07T03:49:27.505831Z","end":"2026-05-07T03:49:28.316476Z","steps":["trace[383609427] 'agreement among raft nodes before linearized reading' (duration: 810.104524ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:49:28.316556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:49:27.505806Z","time spent":"810.733867ms","remote":"127.0.0.1:53924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-07T03:49:28.316177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:49:27.936326Z","time spent":"379.840485ms","remote":"127.0.0.1:54168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":1,"response size":30,"request content":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-05-07T03:49:28.316137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:49:27.287841Z","time spent":"1.028264874s","remote":"127.0.0.1:53924","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-05-07T03:49:44.524387Z","caller":"traceutil/trace.go:171","msg":"trace[1433867862] linearizableReadLoop","detail":"{readStateIndex:932; appliedIndex:931; }","duration":"116.727955ms","start":"2026-05-07T03:49:44.407641Z","end":"2026-05-07T03:49:44.524369Z","steps":["trace[1433867862] 'read index received' (duration: 116.542962ms)","trace[1433867862] 'applied index is now lower than readState.Index' (duration: 184.033µs)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:49:44.524503Z","caller":"traceutil/trace.go:171","msg":"trace[363914466] transaction","detail":"{read_only:false; response_revision:901; number_of_response:1; }","duration":"187.03183ms","start":"2026-05-07T03:49:44.337454Z","end":"2026-05-07T03:49:44.524485Z","steps":["trace[363914466] 'process raft request' (duration: 186.795585ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:49:44.524566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.91498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-07T03:49:44.524601Z","caller":"traceutil/trace.go:171","msg":"trace[1218007935] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:901; }","duration":"116.986622ms","start":"2026-05-07T03:49:44.407602Z","end":"2026-05-07T03:49:44.524588Z","steps":["trace[1218007935] 'agreement among raft nodes before linearized reading' (duration: 116.877919ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:49:44.524732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.317429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-07T03:49:44.524816Z","caller":"traceutil/trace.go:171","msg":"trace[1805769220] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:901; }","duration":"111.443202ms","start":"2026-05-07T03:49:44.413355Z","end":"2026-05-07T03:49:44.524798Z","steps":["trace[1805769220] 'agreement among raft nodes before linearized reading' (duration: 111.255638ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:49:44.524708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.918081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-07T03:49:44.524942Z","caller":"traceutil/trace.go:171","msg":"trace[1771783386] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:901; }","duration":"111.187556ms","start":"2026-05-07T03:49:44.413746Z","end":"2026-05-07T03:49:44.524934Z","steps":["trace[1771783386] 'agreement among raft nodes before linearized reading' (duration: 110.819009ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:49:44.525068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.820878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-07T03:49:44.525112Z","caller":"traceutil/trace.go:171","msg":"trace[143428374] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:901; }","duration":"114.889949ms","start":"2026-05-07T03:49:44.41021Z","end":"2026-05-07T03:49:44.5251Z","steps":["trace[143428374] 'agreement among raft nodes before linearized reading' (duration: 114.328329ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:49:44.677379Z","caller":"traceutil/trace.go:171","msg":"trace[1030860739] transaction","detail":"{read_only:false; number_of_response:1; response_revision:901; }","duration":"150.20928ms","start":"2026-05-07T03:49:44.527144Z","end":"2026-05-07T03:49:44.677353Z","steps":["trace[1030860739] 'process raft request' (duration: 54.575612ms)","trace[1030860739] 'compare' (duration: 95.559687ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:49:44.677903Z","caller":"traceutil/trace.go:171","msg":"trace[1169980587] transaction","detail":"{read_only:false; response_revision:902; number_of_response:1; }","duration":"149.578747ms","start":"2026-05-07T03:49:44.528299Z","end":"2026-05-07T03:49:44.677877Z","steps":["trace[1169980587] 'process raft request' (duration: 149.401604ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:49:44.678115Z","caller":"traceutil/trace.go:171","msg":"trace[792015030] transaction","detail":"{read_only:false; response_revision:903; number_of_response:1; }","duration":"148.806781ms","start":"2026-05-07T03:49:44.529297Z","end":"2026-05-07T03:49:44.678103Z","steps":["trace[792015030] 'process raft request' (duration: 148.545346ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:49:44.678535Z","caller":"traceutil/trace.go:171","msg":"trace[356995258] transaction","detail":"{read_only:false; response_revision:904; number_of_response:1; }","duration":"149.19653ms","start":"2026-05-07T03:49:44.529325Z","end":"2026-05-07T03:49:44.678521Z","steps":["trace[356995258] 'process raft request' (duration: 148.71995ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:49:44.678523Z","caller":"traceutil/trace.go:171","msg":"trace[1780002919] transaction","detail":"{read_only:false; response_revision:905; number_of_response:1; }","duration":"148.171979ms","start":"2026-05-07T03:49:44.530323Z","end":"2026-05-07T03:49:44.678495Z","steps":["trace[1780002919] 'process raft request' (duration: 148.030896ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:50:05.17141Z","caller":"traceutil/trace.go:171","msg":"trace[1308479371] transaction","detail":"{read_only:false; response_revision:973; number_of_response:1; }","duration":"378.952948ms","start":"2026-05-07T03:50:04.792436Z","end":"2026-05-07T03:50:05.171389Z","steps":["trace[1308479371] 'process raft request' (duration: 378.778834ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:50:05.171607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:50:04.792424Z","time spent":"379.09754ms","remote":"127.0.0.1:53924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T03:50:05.905746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.458137ms","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-05-07T03:50:05.905875Z","caller":"traceutil/trace.go:171","msg":"trace[2085732451] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:973; }","duration":"377.59779ms","start":"2026-05-07T03:50:05.528258Z","end":"2026-05-07T03:50:05.905855Z","steps":["trace[2085732451] 'range keys from in-memory index tree' (duration: 377.435037ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:50:06.186964Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.015702189s","expected-duration":"1s"} {"level":"info","ts":"2026-05-07T03:50:06.187513Z","caller":"traceutil/trace.go:171","msg":"trace[2072261399] linearizableReadLoop","detail":"{readStateIndex:1012; appliedIndex:1008; }","duration":"1.208226908s","start":"2026-05-07T03:50:04.979256Z","end":"2026-05-07T03:50:06.187483Z","steps":["trace[2072261399] 'read index received' (duration: 191.988748ms)","trace[2072261399] 'applied index is now lower than readState.Index' (duration: 1.01623672s)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:50:06.187876Z","caller":"traceutil/trace.go:171","msg":"trace[1262102773] transaction","detail":"{read_only:false; response_revision:974; number_of_response:1; }","duration":"1.39530461s","start":"2026-05-07T03:50:04.792547Z","end":"2026-05-07T03:50:06.187852Z","steps":["trace[1262102773] 'process raft request' (duration: 1.394604075s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:50:06.187937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.208641467s","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-05-07T03:50:06.188052Z","caller":"traceutil/trace.go:171","msg":"trace[808448121] transaction","detail":"{read_only:false; response_revision:977; number_of_response:1; }","duration":"1.02484894s","start":"2026-05-07T03:50:05.163191Z","end":"2026-05-07T03:50:06.188039Z","steps":["trace[808448121] 'process raft request' (duration: 1.024253239s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:50:06.188071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:50:04.792531Z","time spent":"1.395399871s","remote":"127.0.0.1:53924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T03:50:06.188086Z","caller":"traceutil/trace.go:171","msg":"trace[1033418487] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:977; }","duration":"1.20884041s","start":"2026-05-07T03:50:04.979226Z","end":"2026-05-07T03:50:06.188067Z","steps":["trace[1033418487] 'agreement among raft nodes before linearized reading' (duration: 1.208600425s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:50:06.18823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:50:05.163143Z","time spent":"1.024950092s","remote":"127.0.0.1:53924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T03:50:06.188223Z","caller":"traceutil/trace.go:171","msg":"trace[1075277866] transaction","detail":"{read_only:false; response_revision:975; number_of_response:1; }","duration":"1.395590905s","start":"2026-05-07T03:50:04.792607Z","end":"2026-05-07T03:50:06.188198Z","steps":["trace[1075277866] 'process raft request' (duration: 1.39476335s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:50:06.188248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:50:04.979208Z","time spent":"1.209026643s","remote":"127.0.0.1:53880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true "} {"level":"warn","ts":"2026-05-07T03:50:06.188359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:50:04.792582Z","time spent":"1.395709307s","remote":"127.0.0.1:53924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T03:50:06.18778Z","caller":"traceutil/trace.go:171","msg":"trace[1921931850] transaction","detail":"{read_only:false; response_revision:976; number_of_response:1; }","duration":"1.394704337s","start":"2026-05-07T03:50:04.793043Z","end":"2026-05-07T03:50:06.187747Z","steps":["trace[1921931850] 'process raft request' (duration: 1.39436817s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:50:06.18957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:50:04.793032Z","time spent":"1.395374029s","remote":"127.0.0.1:53924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T03:50:06.242823Z","caller":"traceutil/trace.go:171","msg":"trace[896053872] transaction","detail":"{read_only:false; response_revision:978; number_of_response:1; }","duration":"820.784252ms","start":"2026-05-07T03:50:05.421992Z","end":"2026-05-07T03:50:06.242777Z","steps":["trace[896053872] 'process raft request' (duration: 817.818054ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:50:06.242872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.223515441s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-07T03:50:06.242964Z","caller":"traceutil/trace.go:171","msg":"trace[1086213130] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:978; }","duration":"1.223626094s","start":"2026-05-07T03:50:05.019316Z","end":"2026-05-07T03:50:06.242942Z","steps":["trace[1086213130] 'agreement among raft nodes before linearized reading' (duration: 1.22338137s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:50:06.243019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:50:05.01931Z","time spent":"1.223700516s","remote":"127.0.0.1:53924","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-05-07T03:50:06.242867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"630.400755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-07T03:50:06.243081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:50:05.421967Z","time spent":"820.975695ms","remote":"127.0.0.1:53924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T03:50:06.243097Z","caller":"traceutil/trace.go:171","msg":"trace[1979669142] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:978; }","duration":"630.694671ms","start":"2026-05-07T03:50:05.612385Z","end":"2026-05-07T03:50:06.24308Z","steps":["trace[1979669142] 'agreement among raft nodes before linearized reading' (duration: 630.397895ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:50:06.243145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:50:05.612365Z","time spent":"630.769562ms","remote":"127.0.0.1:53676","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-07T03:50:27.873584Z","caller":"traceutil/trace.go:171","msg":"trace[96116884] linearizableReadLoop","detail":"{readStateIndex:1086; appliedIndex:1085; }","duration":"261.336636ms","start":"2026-05-07T03:50:27.612219Z","end":"2026-05-07T03:50:27.873555Z","steps":["trace[96116884] 'read index received' (duration: 261.154682ms)","trace[96116884] 'applied index is now lower than readState.Index' (duration: 180.744µs)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:50:27.873692Z","caller":"traceutil/trace.go:171","msg":"trace[1133808606] transaction","detail":"{read_only:false; response_revision:1046; number_of_response:1; }","duration":"602.306161ms","start":"2026-05-07T03:50:27.271356Z","end":"2026-05-07T03:50:27.873662Z","steps":["trace[1133808606] 'process raft request' (duration: 602.013585ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:50:27.873788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.54232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T03:50:27.873893Z","caller":"traceutil/trace.go:171","msg":"trace[1412488601] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1046; }","duration":"261.700023ms","start":"2026-05-07T03:50:27.612169Z","end":"2026-05-07T03:50:27.873869Z","steps":["trace[1412488601] 'agreement among raft nodes before linearized reading' (duration: 261.53506ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:50:27.874023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:50:27.271339Z","time spent":"602.483814ms","remote":"127.0.0.1:53924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T03:50:28.699696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.225675ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T03:50:28.700218Z","caller":"traceutil/trace.go:171","msg":"trace[1999691722] transaction","detail":"{read_only:false; response_revision:1051; number_of_response:1; }","duration":"251.534816ms","start":"2026-05-07T03:50:28.448658Z","end":"2026-05-07T03:50:28.700193Z","steps":["trace[1999691722] 'process raft request' (duration: 110.6717ms)","trace[1999691722] 'compare' (duration: 140.106262ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:50:29.140431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.423581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-07T03:50:29.140581Z","caller":"traceutil/trace.go:171","msg":"trace[1120601315] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1051; }","duration":"317.589213ms","start":"2026-05-07T03:50:28.822973Z","end":"2026-05-07T03:50:29.140562Z","steps":["trace[1120601315] 'count revisions from in-memory index tree' (duration: 317.331659ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:50:29.140628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:50:28.822889Z","time spent":"317.727056ms","remote":"127.0.0.1:54116","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":30,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-05-07T03:50:29.510132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.622442ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-07T03:50:31.103178Z","caller":"traceutil/trace.go:171","msg":"trace[1099067906] transaction","detail":"{read_only:false; response_revision:1057; number_of_response:1; }","duration":"392.81541ms","start":"2026-05-07T03:50:30.710088Z","end":"2026-05-07T03:50:31.102903Z","steps":["trace[1099067906] 'process raft request' (duration: 392.652847ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:50:31.103332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:50:30.710062Z","time spent":"393.189897ms","remote":"127.0.0.1:53924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T03:50:32.17721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.054513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-07T03:50:32.177302Z","caller":"traceutil/trace.go:171","msg":"trace[873634521] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:1058; }","duration":"126.234776ms","start":"2026-05-07T03:50:32.051049Z","end":"2026-05-07T03:50:32.177283Z","steps":["trace[873634521] 'count revisions from in-memory index tree' (duration: 125.951902ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:50:44.157089Z","caller":"traceutil/trace.go:171","msg":"trace[406742333] linearizableReadLoop","detail":"{readStateIndex:1195; appliedIndex:1194; }","duration":"147.699743ms","start":"2026-05-07T03:50:44.009357Z","end":"2026-05-07T03:50:44.157057Z","steps":["trace[406742333] 'read index received' (duration: 147.471829ms)","trace[406742333] 'applied index is now lower than readState.Index' (duration: 226.394µs)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:50:44.1574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.02415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-07T03:50:44.157458Z","caller":"traceutil/trace.go:171","msg":"trace[1937230465] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1149; }","duration":"148.151872ms","start":"2026-05-07T03:50:44.009288Z","end":"2026-05-07T03:50:44.15744Z","steps":["trace[1937230465] 'agreement among raft nodes before linearized reading' (duration: 147.913298ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:50:44.77767Z","caller":"traceutil/trace.go:171","msg":"trace[1477377200] transaction","detail":"{read_only:false; response_revision:1150; number_of_response:1; }","duration":"618.464036ms","start":"2026-05-07T03:50:44.159174Z","end":"2026-05-07T03:50:44.777638Z","steps":["trace[1477377200] 'process raft request' (duration: 615.977801ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:50:44.777839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:50:44.159158Z","time spent":"618.598438ms","remote":"127.0.0.1:53698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T03:50:44.801792Z","caller":"traceutil/trace.go:171","msg":"trace[1826214205] linearizableReadLoop","detail":"{readStateIndex:1198; appliedIndex:1195; }","duration":"362.361984ms","start":"2026-05-07T03:50:44.439413Z","end":"2026-05-07T03:50:44.801775Z","steps":["trace[1826214205] 'read index received' (duration: 335.758893ms)","trace[1826214205] 'applied index is now lower than readState.Index' (duration: 26.602241ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:50:44.801864Z","caller":"traceutil/trace.go:171","msg":"trace[35096679] transaction","detail":"{read_only:false; response_revision:1152; number_of_response:1; }","duration":"366.482379ms","start":"2026-05-07T03:50:44.435375Z","end":"2026-05-07T03:50:44.801857Z","steps":["trace[35096679] 'process raft request' (duration: 366.376927ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:50:44.801843Z","caller":"traceutil/trace.go:171","msg":"trace[428173514] transaction","detail":"{read_only:false; response_revision:1151; number_of_response:1; }","duration":"639.19021ms","start":"2026-05-07T03:50:44.162634Z","end":"2026-05-07T03:50:44.801824Z","steps":["trace[428173514] 'process raft request' (duration: 639.024767ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:50:44.801938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:50:44.435356Z","time spent":"366.526371ms","remote":"127.0.0.1:53924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T03:50:44.801944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:50:44.162609Z","time spent":"639.283681ms","remote":"127.0.0.1:53924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T03:50:44.802113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.405678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-07T03:50:44.802152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.621584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T03:50:44.802174Z","caller":"traceutil/trace.go:171","msg":"trace[1657884051] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1152; }","duration":"185.671085ms","start":"2026-05-07T03:50:44.616496Z","end":"2026-05-07T03:50:44.802167Z","steps":["trace[1657884051] 'agreement among raft nodes before linearized reading' (duration: 185.631934ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:50:44.802167Z","caller":"traceutil/trace.go:171","msg":"trace[861635208] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1152; }","duration":"276.49929ms","start":"2026-05-07T03:50:44.525653Z","end":"2026-05-07T03:50:44.802152Z","steps":["trace[861635208] 'agreement among raft nodes before linearized reading' (duration: 276.331837ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:50:44.802391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.305916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-07T03:50:44.802496Z","caller":"traceutil/trace.go:171","msg":"trace[932726335] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1152; }","duration":"276.460778ms","start":"2026-05-07T03:50:44.526015Z","end":"2026-05-07T03:50:44.802476Z","steps":["trace[932726335] 'agreement among raft nodes before linearized reading' (duration: 276.169033ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:50:44.802894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.485413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-07T03:50:44.802973Z","caller":"traceutil/trace.go:171","msg":"trace[1960203759] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1152; }","duration":"363.591066ms","start":"2026-05-07T03:50:44.439368Z","end":"2026-05-07T03:50:44.802959Z","steps":["trace[1960203759] 'agreement among raft nodes before linearized reading' (duration: 363.447153ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:50:44.80301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:50:44.439356Z","time spent":"363.643656ms","remote":"127.0.0.1:53924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-07T03:50:59.360376Z","caller":"traceutil/trace.go:171","msg":"trace[264839096] transaction","detail":"{read_only:false; response_revision:1220; number_of_response:1; }","duration":"152.83451ms","start":"2026-05-07T03:50:59.207508Z","end":"2026-05-07T03:50:59.360342Z","steps":["trace[264839096] 'process raft request' (duration: 152.650426ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:51:01.174972Z","caller":"traceutil/trace.go:171","msg":"trace[657700718] linearizableReadLoop","detail":"{readStateIndex:1279; appliedIndex:1278; }","duration":"239.466447ms","start":"2026-05-07T03:51:00.935478Z","end":"2026-05-07T03:51:01.174944Z","steps":["trace[657700718] 'read index received' (duration: 239.231801ms)","trace[657700718] 'applied index is now lower than readState.Index' (duration: 233.516µs)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:51:01.175419Z","caller":"traceutil/trace.go:171","msg":"trace[133070788] transaction","detail":"{read_only:false; response_revision:1230; number_of_response:1; }","duration":"261.917621ms","start":"2026-05-07T03:51:00.913485Z","end":"2026-05-07T03:51:01.175403Z","steps":["trace[133070788] 'process raft request' (duration: 261.30555ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:51:01.175726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.22816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-07T03:51:01.175766Z","caller":"traceutil/trace.go:171","msg":"trace[309917076] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1230; }","duration":"240.334692ms","start":"2026-05-07T03:51:00.935416Z","end":"2026-05-07T03:51:01.175751Z","steps":["trace[309917076] 'agreement among raft nodes before linearized reading' (duration: 240.180809ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:51:01.175963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.324713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-07T03:51:01.175989Z","caller":"traceutil/trace.go:171","msg":"trace[1798626902] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1230; }","duration":"220.372114ms","start":"2026-05-07T03:51:00.955609Z","end":"2026-05-07T03:51:01.175981Z","steps":["trace[1798626902] 'agreement among raft nodes before linearized reading' (duration: 220.294923ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:51:01.176194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.359805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T03:51:01.176223Z","caller":"traceutil/trace.go:171","msg":"trace[1453692589] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:1230; }","duration":"207.406216ms","start":"2026-05-07T03:51:00.968809Z","end":"2026-05-07T03:51:01.176215Z","steps":["trace[1453692589] 'agreement among raft nodes before linearized reading' (duration: 207.349084ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:51:01.17644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.276244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-07T03:51:01.176518Z","caller":"traceutil/trace.go:171","msg":"trace[416409015] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1230; }","duration":"219.393526ms","start":"2026-05-07T03:51:00.957105Z","end":"2026-05-07T03:51:01.176498Z","steps":["trace[416409015] 'agreement among raft nodes before linearized reading' (duration: 219.128761ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:51:01.176503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.301395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-07T03:51:01.176592Z","caller":"traceutil/trace.go:171","msg":"trace[1500113019] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1230; }","duration":"219.423636ms","start":"2026-05-07T03:51:00.957149Z","end":"2026-05-07T03:51:01.176572Z","steps":["trace[1500113019] 'agreement among raft nodes before linearized reading' (duration: 219.129151ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:51:01.68631Z","caller":"traceutil/trace.go:171","msg":"trace[350068488] linearizableReadLoop","detail":"{readStateIndex:1283; appliedIndex:1280; }","duration":"297.16271ms","start":"2026-05-07T03:51:01.389125Z","end":"2026-05-07T03:51:01.686288Z","steps":["trace[350068488] 'read index received' (duration: 273.84722ms)","trace[350068488] 'applied index is now lower than readState.Index' (duration: 23.3144ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:51:01.686571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.425725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-05-07T03:51:01.686657Z","caller":"traceutil/trace.go:171","msg":"trace[81920052] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1234; }","duration":"297.550588ms","start":"2026-05-07T03:51:01.389095Z","end":"2026-05-07T03:51:01.686645Z","steps":["trace[81920052] 'agreement among raft nodes before linearized reading' (duration: 297.292503ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:51:01.686626Z","caller":"traceutil/trace.go:171","msg":"trace[1811250447] transaction","detail":"{read_only:false; response_revision:1232; number_of_response:1; }","duration":"503.848823ms","start":"2026-05-07T03:51:01.182727Z","end":"2026-05-07T03:51:01.686576Z","steps":["trace[1811250447] 'process raft request' (duration: 480.149796ms)","trace[1811250447] 'compare' (duration: 23.227339ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:51:01.686723Z","caller":"traceutil/trace.go:171","msg":"trace[1408322231] transaction","detail":"{read_only:false; response_revision:1233; number_of_response:1; }","duration":"501.836835ms","start":"2026-05-07T03:51:01.184831Z","end":"2026-05-07T03:51:01.686668Z","steps":["trace[1408322231] 'process raft request' (duration: 501.371877ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:51:01.68691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:51:01.184812Z","time spent":"502.002259ms","remote":"127.0.0.1:53924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T03:51:01.68671Z","caller":"traceutil/trace.go:171","msg":"trace[409583928] transaction","detail":"{read_only:false; response_revision:1234; number_of_response:1; }","duration":"501.497849ms","start":"2026-05-07T03:51:01.185183Z","end":"2026-05-07T03:51:01.686681Z","steps":["trace[409583928] 'process raft request' (duration: 501.065792ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:51:01.686925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:51:01.182693Z","time spent":"504.132758ms","remote":"127.0.0.1:53924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T03:51:01.687003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:51:01.185174Z","time spent":"501.794804ms","remote":"127.0.0.1:53924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T03:51:02.909965Z","caller":"traceutil/trace.go:171","msg":"trace[622161026] linearizableReadLoop","detail":"{readStateIndex:1285; appliedIndex:1284; }","duration":"299.226389ms","start":"2026-05-07T03:51:02.610724Z","end":"2026-05-07T03:51:02.909951Z","steps":["trace[622161026] 'read index received' (duration: 299.155137ms)","trace[622161026] 'applied index is now lower than readState.Index' (duration: 70.562µs)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:51:02.909992Z","caller":"traceutil/trace.go:171","msg":"trace[1648487520] transaction","detail":"{read_only:false; response_revision:1236; number_of_response:1; }","duration":"413.125809ms","start":"2026-05-07T03:51:02.496841Z","end":"2026-05-07T03:51:02.909967Z","steps":["trace[1648487520] 'process raft request' (duration: 413.007767ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:51:02.910051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.32418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T03:51:02.910069Z","caller":"traceutil/trace.go:171","msg":"trace[1240282038] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1236; }","duration":"299.396972ms","start":"2026-05-07T03:51:02.610666Z","end":"2026-05-07T03:51:02.910063Z","steps":["trace[1240282038] 'agreement among raft nodes before linearized reading' (duration: 299.342511ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:51:02.910104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:51:02.496806Z","time spent":"413.248312ms","remote":"127.0.0.1:53758","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":885,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-07T03:51:03.619847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"530.539065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-07T03:51:03.619921Z","caller":"traceutil/trace.go:171","msg":"trace[407679645] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:1236; }","duration":"530.649007ms","start":"2026-05-07T03:51:03.089257Z","end":"2026-05-07T03:51:03.619906Z","steps":["trace[407679645] 'count revisions from in-memory index tree' (duration: 530.482314ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:51:03.619951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:51:03.089243Z","time spent":"530.701758ms","remote":"127.0.0.1:54168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":1,"response size":30,"request content":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-05-07T03:51:03.69323Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13322102897941249457,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-07T03:51:03.753927Z","caller":"traceutil/trace.go:171","msg":"trace[721713361] transaction","detail":"{read_only:false; response_revision:1237; number_of_response:1; }","duration":"571.636824ms","start":"2026-05-07T03:51:03.18227Z","end":"2026-05-07T03:51:03.753906Z","steps":["trace[721713361] 'process raft request' (duration: 571.50161ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:51:03.754055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:51:03.18225Z","time spent":"571.729995ms","remote":"127.0.0.1:53924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T03:51:03.918329Z","caller":"traceutil/trace.go:171","msg":"trace[1609497201] linearizableReadLoop","detail":"{readStateIndex:1287; appliedIndex:1285; }","duration":"725.762055ms","start":"2026-05-07T03:51:03.192547Z","end":"2026-05-07T03:51:03.918309Z","steps":["trace[1609497201] 'read index received' (duration: 561.262711ms)","trace[1609497201] 'applied index is now lower than readState.Index' (duration: 164.498364ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:51:03.918562Z","caller":"traceutil/trace.go:171","msg":"trace[1448102060] transaction","detail":"{read_only:false; response_revision:1238; number_of_response:1; }","duration":"582.215619ms","start":"2026-05-07T03:51:03.336334Z","end":"2026-05-07T03:51:03.918549Z","steps":["trace[1448102060] 'process raft request' (duration: 500.506622ms)","trace[1448102060] 'compare' (duration: 81.373381ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:51:03.918662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:51:03.336306Z","time spent":"582.290519ms","remote":"127.0.0.1:53924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T03:51:03.918783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.314676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T03:51:03.918852Z","caller":"traceutil/trace.go:171","msg":"trace[73758081] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:1238; }","duration":"359.440729ms","start":"2026-05-07T03:51:03.559391Z","end":"2026-05-07T03:51:03.918831Z","steps":["trace[73758081] 'agreement among raft nodes before linearized reading' (duration: 359.314846ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:51:03.918869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"726.313845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-07T03:51:03.918904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:51:03.559369Z","time spent":"359.51917ms","remote":"127.0.0.1:41092","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true "} {"level":"info","ts":"2026-05-07T03:51:03.918952Z","caller":"traceutil/trace.go:171","msg":"trace[1659720454] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1238; }","duration":"726.430567ms","start":"2026-05-07T03:51:03.192503Z","end":"2026-05-07T03:51:03.918933Z","steps":["trace[1659720454] 'agreement among raft nodes before linearized reading' (duration: 726.192693ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:51:03.918996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:51:03.192497Z","time spent":"726.488768ms","remote":"127.0.0.1:53924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-07T03:51:03.919077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.72241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-07T03:51:03.919122Z","caller":"traceutil/trace.go:171","msg":"trace[1810433480] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1238; }","duration":"227.814112ms","start":"2026-05-07T03:51:03.691297Z","end":"2026-05-07T03:51:03.919111Z","steps":["trace[1810433480] 'agreement among raft nodes before linearized reading' (duration: 227.674228ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:51:03.91919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.962335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-07T03:51:03.919234Z","caller":"traceutil/trace.go:171","msg":"trace[2089871129] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1238; }","duration":"228.042915ms","start":"2026-05-07T03:51:03.691177Z","end":"2026-05-07T03:51:03.91922Z","steps":["trace[2089871129] 'agreement among raft nodes before linearized reading' (duration: 227.936194ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:51:03.919408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.12814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-07T03:51:03.919446Z","caller":"traceutil/trace.go:171","msg":"trace[485322370] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1238; }","duration":"214.17767ms","start":"2026-05-07T03:51:03.705259Z","end":"2026-05-07T03:51:03.919436Z","steps":["trace[485322370] 'agreement among raft nodes before linearized reading' (duration: 214.094729ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:51:03.919527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.307471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-07T03:51:03.919591Z","caller":"traceutil/trace.go:171","msg":"trace[1249839767] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1238; }","duration":"228.400653ms","start":"2026-05-07T03:51:03.691175Z","end":"2026-05-07T03:51:03.919576Z","steps":["trace[1249839767] 'agreement among raft nodes before linearized reading' (duration: 228.23935ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:51:04.051496Z","caller":"traceutil/trace.go:171","msg":"trace[231923443] transaction","detail":"{read_only:false; response_revision:1239; number_of_response:1; }","duration":"128.945228ms","start":"2026-05-07T03:51:03.922527Z","end":"2026-05-07T03:51:04.051472Z","steps":["trace[231923443] 'process raft request' (duration: 125.054816ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:51:04.051709Z","caller":"traceutil/trace.go:171","msg":"trace[242570963] transaction","detail":"{read_only:false; response_revision:1240; number_of_response:1; }","duration":"128.695743ms","start":"2026-05-07T03:51:03.923003Z","end":"2026-05-07T03:51:04.051699Z","steps":["trace[242570963] 'process raft request' (duration: 128.428438ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:51:04.051941Z","caller":"traceutil/trace.go:171","msg":"trace[2070864259] transaction","detail":"{read_only:false; response_revision:1241; number_of_response:1; }","duration":"127.803866ms","start":"2026-05-07T03:51:03.924127Z","end":"2026-05-07T03:51:04.05193Z","steps":["trace[2070864259] 'process raft request' (duration: 127.538862ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:51:04.052146Z","caller":"traceutil/trace.go:171","msg":"trace[422484681] transaction","detail":"{read_only:false; response_revision:1242; number_of_response:1; }","duration":"126.957562ms","start":"2026-05-07T03:51:03.925173Z","end":"2026-05-07T03:51:04.052131Z","steps":["trace[422484681] 'process raft request' (duration: 126.719567ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:51:04.052172Z","caller":"traceutil/trace.go:171","msg":"trace[1336809443] transaction","detail":"{read_only:false; response_revision:1243; number_of_response:1; }","duration":"126.395132ms","start":"2026-05-07T03:51:03.925753Z","end":"2026-05-07T03:51:04.052148Z","steps":["trace[1336809443] 'process raft request' (duration: 126.280979ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:51:04.917688Z","caller":"traceutil/trace.go:171","msg":"trace[653483976] transaction","detail":"{read_only:false; response_revision:1251; number_of_response:1; }","duration":"252.10009ms","start":"2026-05-07T03:51:04.665569Z","end":"2026-05-07T03:51:04.917669Z","steps":["trace[653483976] 'process raft request' (duration: 251.988958ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:51:06.129141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.922186ms","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-05-07T03:51:06.129199Z","caller":"traceutil/trace.go:171","msg":"trace[2138319308] 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:1252; }","duration":"188.030207ms","start":"2026-05-07T03:51:05.941155Z","end":"2026-05-07T03:51:06.129185Z","steps":["trace[2138319308] 'count revisions from in-memory index tree' (duration: 187.820804ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:51:15.401265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.507956ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T03:51:15.401372Z","caller":"traceutil/trace.go:171","msg":"trace[18762952] linearizableReadLoop","detail":"{readStateIndex:1371; appliedIndex:1370; }","duration":"209.995773ms","start":"2026-05-07T03:51:15.19136Z","end":"2026-05-07T03:51:15.401356Z","steps":["trace[18762952] 'read index received' (duration: 61.271µs)","trace[18762952] 'applied index is now lower than readState.Index' (duration: 209.933282ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:51:15.401493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.140466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-07T03:51:15.40152Z","caller":"traceutil/trace.go:171","msg":"trace[1636139205] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:1319; }","duration":"210.222438ms","start":"2026-05-07T03:51:15.191288Z","end":"2026-05-07T03:51:15.40151Z","steps":["trace[1636139205] 'agreement among raft nodes before linearized reading' (duration: 210.113386ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:51:15.401836Z","caller":"traceutil/trace.go:171","msg":"trace[1299994523] transaction","detail":"{read_only:false; response_revision:1319; number_of_response:1; }","duration":"467.008364ms","start":"2026-05-07T03:51:14.934811Z","end":"2026-05-07T03:51:15.401819Z","steps":["trace[1299994523] 'process raft request' (duration: 52.858005ms)","trace[1299994523] 'compare' (duration: 413.327863ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:51:15.401924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:51:14.934789Z","time spent":"467.089955ms","remote":"127.0.0.1:53924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T03:51:16.376002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"847.213275ms","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-05-07T03:51:16.376318Z","caller":"traceutil/trace.go:171","msg":"trace[1158853091] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1319; }","duration":"847.543271ms","start":"2026-05-07T03:51:15.528755Z","end":"2026-05-07T03:51:16.376299Z","steps":["trace[1158853091] 'range keys from in-memory index tree' (duration: 847.195034ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:51:16.376639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"764.737354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T03:51:16.376735Z","caller":"traceutil/trace.go:171","msg":"trace[296848514] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1319; }","duration":"764.905997ms","start":"2026-05-07T03:51:15.611811Z","end":"2026-05-07T03:51:16.376717Z","steps":["trace[296848514] 'range keys from in-memory index tree' (duration: 764.596572ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:51:16.376778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:51:15.611768Z","time spent":"765.000689ms","remote":"127.0.0.1:53676","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-07T03:51:16.377336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.796999ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T03:51:16.377508Z","caller":"traceutil/trace.go:171","msg":"trace[200892619] linearizableReadLoop","detail":"{readStateIndex:1372; appliedIndex:1371; }","duration":"145.442032ms","start":"2026-05-07T03:51:16.23204Z","end":"2026-05-07T03:51:16.377482Z","steps":["trace[200892619] 'read index received' (duration: 51.101µs)","trace[200892619] 'applied index is now lower than readState.Index' (duration: 145.388681ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:51:16.377648Z","caller":"traceutil/trace.go:171","msg":"trace[1661711619] transaction","detail":"{read_only:false; response_revision:1320; number_of_response:1; }","duration":"519.484921ms","start":"2026-05-07T03:51:15.858147Z","end":"2026-05-07T03:51:16.377632Z","steps":["trace[1661711619] 'process raft request' (duration: 348.258203ms)","trace[1661711619] 'compare' (duration: 169.666879ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:51:16.377716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:51:15.858114Z","time spent":"519.569902ms","remote":"127.0.0.1:53924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T03:51:16.378255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.25143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-07T03:51:16.378313Z","caller":"traceutil/trace.go:171","msg":"trace[2027855861] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1320; }","duration":"144.324201ms","start":"2026-05-07T03:51:16.233974Z","end":"2026-05-07T03:51:16.378298Z","steps":["trace[2027855861] 'agreement among raft nodes before linearized reading' (duration: 144.150188ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:51:16.378397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.076429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-05-07T03:51:16.378492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.212701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-07T03:51:16.37852Z","caller":"traceutil/trace.go:171","msg":"trace[83601639] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1320; }","duration":"136.285643ms","start":"2026-05-07T03:51:16.242227Z","end":"2026-05-07T03:51:16.378512Z","steps":["trace[83601639] 'agreement among raft nodes before linearized reading' (duration: 136.207751ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:51:16.378513Z","caller":"traceutil/trace.go:171","msg":"trace[514415922] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1320; }","duration":"136.243212ms","start":"2026-05-07T03:51:16.242242Z","end":"2026-05-07T03:51:16.378485Z","steps":["trace[514415922] 'agreement among raft nodes before linearized reading' (duration: 135.910746ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:51:16.378672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.377118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-05-07T03:51:16.378754Z","caller":"traceutil/trace.go:171","msg":"trace[1965952262] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1320; }","duration":"140.512281ms","start":"2026-05-07T03:51:16.238227Z","end":"2026-05-07T03:51:16.37874Z","steps":["trace[1965952262] 'agreement among raft nodes before linearized reading' (duration: 140.236896ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:51:16.378658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.602953ms","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-05-07T03:51:16.378913Z","caller":"traceutil/trace.go:171","msg":"trace[1002074798] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1320; }","duration":"146.904089ms","start":"2026-05-07T03:51:16.231975Z","end":"2026-05-07T03:51:16.378879Z","steps":["trace[1002074798] 'agreement among raft nodes before linearized reading' (duration: 146.469511ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:51:18.899471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.839137ms","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-05-07T03:51:18.899563Z","caller":"traceutil/trace.go:171","msg":"trace[2000634753] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1334; }","duration":"161.0111ms","start":"2026-05-07T03:51:18.73853Z","end":"2026-05-07T03:51:18.899541Z","steps":["trace[2000634753] 'range keys from in-memory index tree' (duration: 160.597522ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:51:50.720796Z","caller":"traceutil/trace.go:171","msg":"trace[1697324865] transaction","detail":"{read_only:false; response_revision:1477; number_of_response:1; }","duration":"240.597183ms","start":"2026-05-07T03:51:50.480173Z","end":"2026-05-07T03:51:50.72077Z","steps":["trace[1697324865] 'process raft request' (duration: 240.43011ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:51:50.746464Z","caller":"traceutil/trace.go:171","msg":"trace[2068951207] linearizableReadLoop","detail":"{readStateIndex:1537; appliedIndex:1535; }","duration":"133.901326ms","start":"2026-05-07T03:51:50.612541Z","end":"2026-05-07T03:51:50.746442Z","steps":["trace[2068951207] 'read index received' (duration: 108.023442ms)","trace[2068951207] 'applied index is now lower than readState.Index' (duration: 25.876634ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:51:50.746635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.07752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-07T03:51:50.746677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.628212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-07T03:51:50.746695Z","caller":"traceutil/trace.go:171","msg":"trace[1673121509] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1478; }","duration":"134.180341ms","start":"2026-05-07T03:51:50.612501Z","end":"2026-05-07T03:51:50.746681Z","steps":["trace[1673121509] 'agreement among raft nodes before linearized reading' (duration: 134.070949ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:51:50.746713Z","caller":"traceutil/trace.go:171","msg":"trace[2032832746] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1478; }","duration":"115.706892ms","start":"2026-05-07T03:51:50.630997Z","end":"2026-05-07T03:51:50.746704Z","steps":["trace[2032832746] 'agreement among raft nodes before linearized reading' (duration: 115.5771ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:51:50.746901Z","caller":"traceutil/trace.go:171","msg":"trace[889195816] transaction","detail":"{read_only:false; response_revision:1478; number_of_response:1; }","duration":"159.406984ms","start":"2026-05-07T03:51:50.587483Z","end":"2026-05-07T03:51:50.74689Z","steps":["trace[889195816] 'process raft request' (duration: 157.076851ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:52:16.396696Z","caller":"traceutil/trace.go:171","msg":"trace[1346547722] transaction","detail":"{read_only:false; response_revision:1596; number_of_response:1; }","duration":"627.640012ms","start":"2026-05-07T03:52:15.769009Z","end":"2026-05-07T03:52:16.396649Z","steps":["trace[1346547722] 'process raft request' (duration: 627.48308ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:52:16.396879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:52:15.768985Z","time spent":"627.796825ms","remote":"127.0.0.1:53924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T03:52:18.822748Z","caller":"traceutil/trace.go:171","msg":"trace[175490278] transaction","detail":"{read_only:false; response_revision:1605; number_of_response:1; }","duration":"161.985111ms","start":"2026-05-07T03:52:18.660737Z","end":"2026-05-07T03:52:18.822722Z","steps":["trace[175490278] 'process raft request' (duration: 161.836309ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:52:52.590211Z","caller":"traceutil/trace.go:171","msg":"trace[1916230405] transaction","detail":"{read_only:false; response_revision:1761; number_of_response:1; }","duration":"108.169318ms","start":"2026-05-07T03:52:52.482019Z","end":"2026-05-07T03:52:52.590189Z","steps":["trace[1916230405] 'process raft request' (duration: 108.017235ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:52:59.521355Z","caller":"traceutil/trace.go:171","msg":"trace[2060486968] transaction","detail":"{read_only:false; response_revision:1795; number_of_response:1; }","duration":"129.755844ms","start":"2026-05-07T03:52:59.391565Z","end":"2026-05-07T03:52:59.52132Z","steps":["trace[2060486968] 'process raft request' (duration: 129.416348ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:52:59.52151Z","caller":"traceutil/trace.go:171","msg":"trace[10908173] transaction","detail":"{read_only:false; response_revision:1796; number_of_response:1; }","duration":"129.437468ms","start":"2026-05-07T03:52:59.392044Z","end":"2026-05-07T03:52:59.521481Z","steps":["trace[10908173] 'process raft request' (duration: 129.225025ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:53:09.313391Z","caller":"traceutil/trace.go:171","msg":"trace[1077105926] linearizableReadLoop","detail":"{readStateIndex:1909; appliedIndex:1908; }","duration":"132.999321ms","start":"2026-05-07T03:53:09.180367Z","end":"2026-05-07T03:53:09.313366Z","steps":["trace[1077105926] 'read index received' (duration: 132.782817ms)","trace[1077105926] 'applied index is now lower than readState.Index' (duration: 215.514µs)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:53:09.313498Z","caller":"traceutil/trace.go:171","msg":"trace[1210627786] transaction","detail":"{read_only:false; response_revision:1835; number_of_response:1; }","duration":"154.331551ms","start":"2026-05-07T03:53:09.159136Z","end":"2026-05-07T03:53:09.313468Z","steps":["trace[1210627786] 'process raft request' (duration: 154.069696ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:53:09.3136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.214766ms","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:525"} {"level":"info","ts":"2026-05-07T03:53:09.313641Z","caller":"traceutil/trace.go:171","msg":"trace[616852303] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1835; }","duration":"133.312727ms","start":"2026-05-07T03:53:09.180314Z","end":"2026-05-07T03:53:09.313627Z","steps":["trace[616852303] 'agreement among raft nodes before linearized reading' (duration: 133.151514ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:53:13.777914Z","caller":"traceutil/trace.go:171","msg":"trace[1040242286] transaction","detail":"{read_only:false; response_revision:1857; number_of_response:1; }","duration":"152.498139ms","start":"2026-05-07T03:53:13.62539Z","end":"2026-05-07T03:53:13.777888Z","steps":["trace[1040242286] 'process raft request' (duration: 152.301126ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:53:13.963247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.360456ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T03:53:13.963395Z","caller":"traceutil/trace.go:171","msg":"trace[1669657317] transaction","detail":"{read_only:false; response_revision:1858; number_of_response:1; }","duration":"337.987996ms","start":"2026-05-07T03:53:13.625389Z","end":"2026-05-07T03:53:13.963377Z","steps":["trace[1669657317] 'process raft request' (duration: 213.420626ms)","trace[1669657317] 'compare' (duration: 124.238704ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:53:13.963468Z","caller":"traceutil/trace.go:171","msg":"trace[1702537470] linearizableReadLoop","detail":"{readStateIndex:1934; appliedIndex:1932; }","duration":"112.484636ms","start":"2026-05-07T03:53:13.85097Z","end":"2026-05-07T03:53:13.963454Z","steps":["trace[1702537470] 'read index received' (duration: 75.841µs)","trace[1702537470] 'applied index is now lower than readState.Index' (duration: 112.407375ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:53:13.963508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:53:13.625367Z","time spent":"338.062807ms","remote":"127.0.0.1:53924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T03:53:13.963527Z","caller":"traceutil/trace.go:171","msg":"trace[1595905593] transaction","detail":"{read_only:false; response_revision:1859; number_of_response:1; }","duration":"337.344704ms","start":"2026-05-07T03:53:13.626162Z","end":"2026-05-07T03:53:13.963506Z","steps":["trace[1595905593] 'process raft request' (duration: 337.170631ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:53:13.963659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:53:13.626146Z","time spent":"337.441166ms","remote":"127.0.0.1:53924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T03:53:13.963684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.70339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-05-07T03:53:13.963765Z","caller":"traceutil/trace.go:171","msg":"trace[161286236] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1859; }","duration":"112.837192ms","start":"2026-05-07T03:53:13.85091Z","end":"2026-05-07T03:53:13.963747Z","steps":["trace[161286236] 'agreement among raft nodes before linearized reading' (duration: 112.607498ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:53:14.122274Z","caller":"traceutil/trace.go:171","msg":"trace[1573225719] transaction","detail":"{read_only:false; response_revision:1860; number_of_response:1; }","duration":"154.27435ms","start":"2026-05-07T03:53:13.967974Z","end":"2026-05-07T03:53:14.122249Z","steps":["trace[1573225719] 'process raft request' (duration: 126.942172ms)","trace[1573225719] 'compare' (duration: 27.148205ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T03:53:15.105064Z","caller":"traceutil/trace.go:171","msg":"trace[457525637] linearizableReadLoop","detail":"{readStateIndex:1939; appliedIndex:1938; }","duration":"325.747108ms","start":"2026-05-07T03:53:14.779296Z","end":"2026-05-07T03:53:15.105043Z","steps":["trace[457525637] 'read index received' (duration: 325.511664ms)","trace[457525637] 'applied index is now lower than readState.Index' (duration: 234.594µs)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:53:15.105607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.274307ms","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-05-07T03:53:15.105697Z","caller":"traceutil/trace.go:171","msg":"trace[1184092646] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:1863; }","duration":"326.407369ms","start":"2026-05-07T03:53:14.779272Z","end":"2026-05-07T03:53:15.105679Z","steps":["trace[1184092646] 'agreement among raft nodes before linearized reading' (duration: 326.230976ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:53:15.105666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.384274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-07T03:53:15.105348Z","caller":"traceutil/trace.go:171","msg":"trace[1396300956] transaction","detail":"{read_only:false; response_revision:1863; number_of_response:1; }","duration":"390.3757ms","start":"2026-05-07T03:53:14.714956Z","end":"2026-05-07T03:53:15.105331Z","steps":["trace[1396300956] 'process raft request' (duration: 389.949482ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:53:15.105745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:53:14.779261Z","time spent":"326.47434ms","remote":"127.0.0.1:54068","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":30,"request content":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true "} {"level":"info","ts":"2026-05-07T03:53:15.106008Z","caller":"traceutil/trace.go:171","msg":"trace[1294418914] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:1863; }","duration":"277.808853ms","start":"2026-05-07T03:53:14.828083Z","end":"2026-05-07T03:53:15.105892Z","steps":["trace[1294418914] 'agreement among raft nodes before linearized reading' (duration: 277.379705ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:53:15.106099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:53:14.714937Z","time spent":"391.004851ms","remote":"127.0.0.1:53924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":493,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T03:53:33.617828Z","caller":"traceutil/trace.go:171","msg":"trace[1663295600] transaction","detail":"{read_only:false; response_revision:1948; number_of_response:1; }","duration":"103.76219ms","start":"2026-05-07T03:53:33.514039Z","end":"2026-05-07T03:53:33.617801Z","steps":["trace[1663295600] 'process raft request' (duration: 103.504986ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:53:37.631711Z","caller":"traceutil/trace.go:171","msg":"trace[307462724] transaction","detail":"{read_only:false; response_revision:1966; number_of_response:1; }","duration":"195.722999ms","start":"2026-05-07T03:53:37.435931Z","end":"2026-05-07T03:53:37.631654Z","steps":["trace[307462724] 'process raft request' (duration: 195.392893ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:53:37.971505Z","caller":"traceutil/trace.go:171","msg":"trace[1772321225] transaction","detail":"{read_only:false; response_revision:1967; number_of_response:1; }","duration":"334.636155ms","start":"2026-05-07T03:53:37.636834Z","end":"2026-05-07T03:53:37.97147Z","steps":["trace[1772321225] 'process raft request' (duration: 259.614467ms)","trace[1772321225] 'compare' (duration: 74.432407ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:53:37.971803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:53:37.636814Z","time spent":"334.923371ms","remote":"127.0.0.1:53924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T03:53:37.971502Z","caller":"traceutil/trace.go:171","msg":"trace[1761795758] linearizableReadLoop","detail":"{readStateIndex:2047; appliedIndex:2046; }","duration":"316.564634ms","start":"2026-05-07T03:53:37.654576Z","end":"2026-05-07T03:53:37.97114Z","steps":["trace[1761795758] 'read index received' (duration: 241.863512ms)","trace[1761795758] 'applied index is now lower than readState.Index' (duration: 74.699292ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T03:53:37.971651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.057513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-05-07T03:53:37.972178Z","caller":"traceutil/trace.go:171","msg":"trace[628281054] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1967; }","duration":"317.584252ms","start":"2026-05-07T03:53:37.654562Z","end":"2026-05-07T03:53:37.972146Z","steps":["trace[628281054] 'agreement among raft nodes before linearized reading' (duration: 316.980902ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:53:37.972273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:53:37.654555Z","time spent":"317.686794ms","remote":"127.0.0.1:53924","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-05-07T03:53:37.972289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.695278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T03:53:37.97235Z","caller":"traceutil/trace.go:171","msg":"trace[1866833371] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1967; }","duration":"301.79603ms","start":"2026-05-07T03:53:37.670539Z","end":"2026-05-07T03:53:37.972335Z","steps":["trace[1866833371] 'agreement among raft nodes before linearized reading' (duration: 301.656078ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:53:37.972389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T03:53:37.670532Z","time spent":"301.848341ms","remote":"127.0.0.1:41064","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"warn","ts":"2026-05-07T03:53:37.972441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.929483ms","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:534"} {"level":"info","ts":"2026-05-07T03:53:37.972498Z","caller":"traceutil/trace.go:171","msg":"trace[34124607] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1967; }","duration":"274.006154ms","start":"2026-05-07T03:53:37.698475Z","end":"2026-05-07T03:53:37.972481Z","steps":["trace[34124607] 'agreement among raft nodes before linearized reading' (duration: 273.882192ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:53:38.254326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.306745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-07T03:53:38.254413Z","caller":"traceutil/trace.go:171","msg":"trace[454395418] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1970; }","duration":"157.456128ms","start":"2026-05-07T03:53:38.096937Z","end":"2026-05-07T03:53:38.254393Z","steps":["trace[454395418] 'range keys from in-memory index tree' (duration: 157.08588ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:53:38.254783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.082791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T03:53:38.254896Z","caller":"traceutil/trace.go:171","msg":"trace[1572291994] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:1970; }","duration":"152.187893ms","start":"2026-05-07T03:53:38.102635Z","end":"2026-05-07T03:53:38.254823Z","steps":["trace[1572291994] 'count revisions from in-memory index tree' (duration: 151.99545ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T03:53:38.255048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.389145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-07T03:53:38.255125Z","caller":"traceutil/trace.go:171","msg":"trace[1767100775] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1970; }","duration":"125.520478ms","start":"2026-05-07T03:53:38.129589Z","end":"2026-05-07T03:53:38.25511Z","steps":["trace[1767100775] 'range keys from in-memory index tree' (duration: 125.225152ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:54:27.951923Z","caller":"traceutil/trace.go:171","msg":"trace[624329767] transaction","detail":"{read_only:false; response_revision:2224; number_of_response:1; }","duration":"126.705084ms","start":"2026-05-07T03:54:27.825186Z","end":"2026-05-07T03:54:27.951891Z","steps":["trace[624329767] 'process raft request' (duration: 126.537161ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T03:54:27.972612Z","caller":"traceutil/trace.go:171","msg":"trace[1232786513] transaction","detail":"{read_only:false; response_revision:2225; number_of_response:1; }","duration":"123.69917ms","start":"2026-05-07T03:54:27.848896Z","end":"2026-05-07T03:54:27.972595Z","steps":["trace[1232786513] 'process raft request' (duration: 123.605768ms)"],"step_count":1}