{"level":"warn","ts":"2026-07-25T20:03:10.789239Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-25T20:03:10.789338Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.229: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.229:2380","--initial-cluster=instance=https://199.204.45.229:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.229:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.229:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-07-25T20:03:10.789505Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-25T20:03:10.789522Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.229:2380"]} {"level":"info","ts":"2026-07-25T20:03:10.789541Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-25T20:03:10.790222Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.229:2379"]} {"level":"info","ts":"2026-07-25T20:03:10.790296Z","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.229:2380"],"listen-peer-urls":["https://199.204.45.229:2380"],"advertise-client-urls":["https://199.204.45.229:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.229:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.229:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-07-25T20:03:10.79689Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.401764ms"} {"level":"info","ts":"2026-07-25T20:03:10.880418Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"3ba9ae53cc4335db","cluster-id":"4ad2674607ff1d60"} {"level":"info","ts":"2026-07-25T20:03:10.880557Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db switched to configuration voters=()"} {"level":"info","ts":"2026-07-25T20:03:10.880589Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db became follower at term 0"} {"level":"info","ts":"2026-07-25T20:03:10.88061Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 3ba9ae53cc4335db [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-25T20:03:10.880632Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db became follower at term 1"} {"level":"info","ts":"2026-07-25T20:03:10.880732Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db switched to configuration voters=(4299158994234324443)"} {"level":"warn","ts":"2026-07-25T20:03:10.932513Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-25T20:03:10.934009Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-25T20:03:10.935171Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-07-25T20:03:10.937713Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"3ba9ae53cc4335db","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-25T20:03:10.938601Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"3ba9ae53cc4335db","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-25T20:03:10.938865Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-25T20:03:10.939199Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-25T20:03:10.93927Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-25T20:03:10.944481Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db switched to configuration voters=(4299158994234324443)"} {"level":"info","ts":"2026-07-25T20:03:10.945571Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"4ad2674607ff1d60","local-member-id":"3ba9ae53cc4335db","added-peer-id":"3ba9ae53cc4335db","added-peer-peer-urls":["https://199.204.45.229:2380"]} {"level":"info","ts":"2026-07-25T20:03:10.947745Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.229:2380"} {"level":"info","ts":"2026-07-25T20:03:10.947813Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.229:2380"} {"level":"info","ts":"2026-07-25T20:03:10.947635Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-25T20:03:10.948692Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-25T20:03:10.949498Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"3ba9ae53cc4335db","initial-advertise-peer-urls":["https://199.204.45.229:2380"],"listen-peer-urls":["https://199.204.45.229:2380"],"advertise-client-urls":["https://199.204.45.229:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.229:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-25T20:03:11.583583Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db is starting a new election at term 1"} {"level":"info","ts":"2026-07-25T20:03:11.583724Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db became pre-candidate at term 1"} {"level":"info","ts":"2026-07-25T20:03:11.583778Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db received MsgPreVoteResp from 3ba9ae53cc4335db at term 1"} {"level":"info","ts":"2026-07-25T20:03:11.583805Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db became candidate at term 2"} {"level":"info","ts":"2026-07-25T20:03:11.583825Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db received MsgVoteResp from 3ba9ae53cc4335db at term 2"} {"level":"info","ts":"2026-07-25T20:03:11.583846Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db became leader at term 2"} {"level":"info","ts":"2026-07-25T20:03:11.58387Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 3ba9ae53cc4335db elected leader 3ba9ae53cc4335db at term 2"} {"level":"info","ts":"2026-07-25T20:03:11.58508Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"3ba9ae53cc4335db","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.229:2379]}","request-path":"/0/members/3ba9ae53cc4335db/attributes","cluster-id":"4ad2674607ff1d60","publish-timeout":"7s"} {"level":"info","ts":"2026-07-25T20:03:11.585136Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-25T20:03:11.585302Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-25T20:03:11.585472Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-25T20:03:11.586281Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-25T20:03:11.586325Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-25T20:03:11.586937Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"4ad2674607ff1d60","local-member-id":"3ba9ae53cc4335db","cluster-version":"3.5"} {"level":"info","ts":"2026-07-25T20:03:11.587059Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-25T20:03:11.587103Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-25T20:03:11.588478Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.229:2379"} {"level":"info","ts":"2026-07-25T20:03:11.589331Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-25T20:03:16.358591Z","caller":"traceutil/trace.go:171","msg":"trace[208142131] transaction","detail":"{read_only:false; response_revision:214; number_of_response:1; }","duration":"148.307446ms","start":"2026-07-25T20:03:16.210243Z","end":"2026-07-25T20:03:16.35855Z","steps":["trace[208142131] 'process raft request' (duration: 58.947336ms)","trace[208142131] 'compare' (duration: 89.187865ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T20:03:17.054912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"478.137632ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-25T20:03:17.055146Z","caller":"traceutil/trace.go:171","msg":"trace[576310638] transaction","detail":"{read_only:false; response_revision:216; number_of_response:1; }","duration":"618.706501ms","start":"2026-07-25T20:03:16.436412Z","end":"2026-07-25T20:03:17.055118Z","steps":["trace[576310638] 'process raft request' (duration: 139.302547ms)","trace[576310638] 'compare' (duration: 477.959657ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T20:03:17.055252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T20:03:16.43639Z","time spent":"618.825824ms","remote":"127.0.0.1:50716","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-25T20:03:17.055176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T20:03:16.700559Z","time spent":"354.607055ms","remote":"127.0.0.1:50424","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-07-25T20:03:17.384268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.428797ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-25T20:03:17.384423Z","caller":"traceutil/trace.go:171","msg":"trace[2120575273] transaction","detail":"{read_only:false; response_revision:217; number_of_response:1; }","duration":"327.745843ms","start":"2026-07-25T20:03:17.056653Z","end":"2026-07-25T20:03:17.384399Z","steps":["trace[2120575273] 'process raft request' (duration: 210.368873ms)","trace[2120575273] 'compare' (duration: 116.256532ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T20:03:17.384488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T20:03:17.056626Z","time spent":"327.837105ms","remote":"127.0.0.1:50424","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":592,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-25T20:03:17.410735Z","caller":"traceutil/trace.go:171","msg":"trace[1061667676] transaction","detail":"{read_only:false; response_revision:218; number_of_response:1; }","duration":"351.073525ms","start":"2026-07-25T20:03:17.059625Z","end":"2026-07-25T20:03:17.410698Z","steps":["trace[1061667676] 'process raft request' (duration: 350.838399ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T20:03:17.410944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T20:03:17.059592Z","time spent":"351.265551ms","remote":"127.0.0.1:50716","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":526,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-25T20:03:17.410942Z","caller":"traceutil/trace.go:171","msg":"trace[880528424] transaction","detail":"{read_only:false; response_revision:219; number_of_response:1; }","duration":"350.197873ms","start":"2026-07-25T20:03:17.060645Z","end":"2026-07-25T20:03:17.410843Z","steps":["trace[880528424] 'process raft request' (duration: 349.953456ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T20:03:17.411188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T20:03:17.060633Z","time spent":"350.429739ms","remote":"127.0.0.1:50622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} 2026/07/25 20:03:17 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"info","ts":"2026-07-25T20:03:53.505352Z","caller":"traceutil/trace.go:171","msg":"trace[691151090] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"248.346725ms","start":"2026-07-25T20:03:53.256936Z","end":"2026-07-25T20:03:53.505282Z","steps":["trace[691151090] 'process raft request' (duration: 248.15961ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T20:03:53.511883Z","caller":"traceutil/trace.go:171","msg":"trace[1715546731] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"245.867931ms","start":"2026-07-25T20:03:53.265972Z","end":"2026-07-25T20:03:53.51184Z","steps":["trace[1715546731] 'process raft request' (duration: 245.638775ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T20:03:53.51195Z","caller":"traceutil/trace.go:171","msg":"trace[1223484290] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"245.878411ms","start":"2026-07-25T20:03:53.266018Z","end":"2026-07-25T20:03:53.511896Z","steps":["trace[1223484290] 'process raft request' (duration: 245.731727ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T20:03:53.512254Z","caller":"traceutil/trace.go:171","msg":"trace[1820893568] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"237.380739ms","start":"2026-07-25T20:03:53.274855Z","end":"2026-07-25T20:03:53.512236Z","steps":["trace[1820893568] 'process raft request' (duration: 236.931747ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T20:03:53.512243Z","caller":"traceutil/trace.go:171","msg":"trace[1826260959] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"237.062002ms","start":"2026-07-25T20:03:53.275141Z","end":"2026-07-25T20:03:53.512203Z","steps":["trace[1826260959] 'process raft request' (duration: 236.888306ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T20:03:53.512376Z","caller":"traceutil/trace.go:171","msg":"trace[545372548] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"235.906122ms","start":"2026-07-25T20:03:53.276453Z","end":"2026-07-25T20:03:53.51236Z","steps":["trace[545372548] 'process raft request' (duration: 235.630575ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T20:03:53.512382Z","caller":"traceutil/trace.go:171","msg":"trace[1393894444] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"237.252976ms","start":"2026-07-25T20:03:53.274919Z","end":"2026-07-25T20:03:53.512172Z","steps":["trace[1393894444] 'process raft request' (duration: 236.991938ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T20:03:53.512602Z","caller":"traceutil/trace.go:171","msg":"trace[1796207575] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"227.39094ms","start":"2026-07-25T20:03:53.28519Z","end":"2026-07-25T20:03:53.512581Z","steps":["trace[1796207575] 'process raft request' (duration: 226.927107ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T20:03:53.513445Z","caller":"traceutil/trace.go:171","msg":"trace[744957366] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"219.910402ms","start":"2026-07-25T20:03:53.293517Z","end":"2026-07-25T20:03:53.513428Z","steps":["trace[744957366] 'process raft request' (duration: 218.997249ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T20:03:53.513592Z","caller":"traceutil/trace.go:171","msg":"trace[650447390] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"191.469108ms","start":"2026-07-25T20:03:53.322099Z","end":"2026-07-25T20:03:53.513568Z","steps":["trace[650447390] 'process raft request' (duration: 191.164261ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T20:03:53.513847Z","caller":"traceutil/trace.go:171","msg":"trace[1524170281] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"177.071598ms","start":"2026-07-25T20:03:53.33673Z","end":"2026-07-25T20:03:53.513802Z","steps":["trace[1524170281] 'process raft request' (duration: 176.653898ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T20:03:59.749644Z","caller":"traceutil/trace.go:171","msg":"trace[115293856] linearizableReadLoop","detail":"{readStateIndex:641; appliedIndex:640; }","duration":"137.072555ms","start":"2026-07-25T20:03:59.612484Z","end":"2026-07-25T20:03:59.749556Z","steps":["trace[115293856] 'read index received' (duration: 133.468645ms)","trace[115293856] 'applied index is now lower than readState.Index' (duration: 3.59893ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T20:03:59.749928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.357483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T20:03:59.750199Z","caller":"traceutil/trace.go:171","msg":"trace[1339515701] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:620; }","duration":"137.639059ms","start":"2026-07-25T20:03:59.612452Z","end":"2026-07-25T20:03:59.750091Z","steps":["trace[1339515701] 'agreement among raft nodes before linearized reading' (duration: 137.25077ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T20:03:59.750712Z","caller":"traceutil/trace.go:171","msg":"trace[2094832945] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"141.944127ms","start":"2026-07-25T20:03:59.608697Z","end":"2026-07-25T20:03:59.750641Z","steps":["trace[2094832945] 'process raft request' (duration: 137.549557ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T20:04:00.142554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.946458ms","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-07-25T20:04:00.142887Z","caller":"traceutil/trace.go:171","msg":"trace[1963760006] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:621; }","duration":"144.307887ms","start":"2026-07-25T20:03:59.998546Z","end":"2026-07-25T20:04:00.142854Z","steps":["trace[1963760006] 'range keys from in-memory index tree' (duration: 143.913546ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T20:04:00.143017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.995905ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-25T20:04:00.143227Z","caller":"traceutil/trace.go:171","msg":"trace[1925725528] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"383.919312ms","start":"2026-07-25T20:03:59.759288Z","end":"2026-07-25T20:04:00.143207Z","steps":["trace[1925725528] 'process raft request' (duration: 34.450334ms)","trace[1925725528] 'compare' (duration: 348.858002ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T20:04:00.143305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T20:03:59.75928Z","time spent":"383.993974ms","remote":"127.0.0.1:50622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T20:04:00.143582Z","caller":"traceutil/trace.go:171","msg":"trace[1653062294] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"384.25004ms","start":"2026-07-25T20:03:59.759311Z","end":"2026-07-25T20:04:00.143561Z","steps":["trace[1653062294] 'process raft request' (duration: 383.8465ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T20:04:00.143605Z","caller":"traceutil/trace.go:171","msg":"trace[39301239] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"337.321775ms","start":"2026-07-25T20:03:59.806264Z","end":"2026-07-25T20:04:00.143586Z","steps":["trace[39301239] 'process raft request' (duration: 337.201892ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T20:04:00.143692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T20:03:59.759287Z","time spent":"384.345643ms","remote":"127.0.0.1:50622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T20:04:00.14386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T20:03:59.806234Z","time spent":"337.5381ms","remote":"127.0.0.1:50864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-25T20:04:00.373525Z","caller":"traceutil/trace.go:171","msg":"trace[1055340764] linearizableReadLoop","detail":"{readStateIndex:646; appliedIndex:645; }","duration":"296.607693ms","start":"2026-07-25T20:04:00.076885Z","end":"2026-07-25T20:04:00.373493Z","steps":["trace[1055340764] 'read index received' (duration: 244.731843ms)","trace[1055340764] 'applied index is now lower than readState.Index' (duration: 51.87403ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T20:04:00.373934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.028734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-5s6sq\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-07-25T20:04:00.373993Z","caller":"traceutil/trace.go:171","msg":"trace[1743508039] range","detail":"{range_begin:/registry/pods/kube-system/cilium-5s6sq; range_end:; response_count:1; response_revision:624; }","duration":"297.152287ms","start":"2026-07-25T20:04:00.076825Z","end":"2026-07-25T20:04:00.373978Z","steps":["trace[1743508039] 'agreement among raft nodes before linearized reading' (duration: 296.804469ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T20:04:00.374124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.294326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-25T20:04:00.374397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.991306ms","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-07-25T20:04:00.374522Z","caller":"traceutil/trace.go:171","msg":"trace[1103153331] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:624; }","duration":"217.749468ms","start":"2026-07-25T20:04:00.156645Z","end":"2026-07-25T20:04:00.374394Z","steps":["trace[1103153331] 'agreement among raft nodes before linearized reading' (duration: 217.227995ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T20:04:00.374564Z","caller":"traceutil/trace.go:171","msg":"trace[672762183] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:624; }","duration":"193.239733ms","start":"2026-07-25T20:04:00.181299Z","end":"2026-07-25T20:04:00.374538Z","steps":["trace[672762183] 'agreement among raft nodes before linearized reading' (duration: 192.753891ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T20:04:00.995418Z","caller":"traceutil/trace.go:171","msg":"trace[2084315425] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"613.929837ms","start":"2026-07-25T20:04:00.381451Z","end":"2026-07-25T20:04:00.995381Z","steps":["trace[2084315425] 'process raft request' (duration: 613.705211ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T20:04:00.995643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T20:04:00.381426Z","time spent":"614.09781ms","remote":"127.0.0.1:50622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T20:04:01.419379Z","caller":"traceutil/trace.go:171","msg":"trace[1392056392] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"1.028304581s","start":"2026-07-25T20:04:00.391028Z","end":"2026-07-25T20:04:01.419332Z","steps":["trace[1392056392] 'process raft request' (duration: 1.028061695s)"],"step_count":1} {"level":"warn","ts":"2026-07-25T20:04:01.419677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T20:04:00.390976Z","time spent":"1.028590099s","remote":"127.0.0.1:50538","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T20:04:01.419966Z","caller":"traceutil/trace.go:171","msg":"trace[1723698225] linearizableReadLoop","detail":"{readStateIndex:648; appliedIndex:646; }","duration":"805.62011ms","start":"2026-07-25T20:04:00.614299Z","end":"2026-07-25T20:04:01.419919Z","steps":["trace[1723698225] 'read index received' (duration: 380.940077ms)","trace[1723698225] 'applied index is now lower than readState.Index' (duration: 424.677003ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T20:04:01.420059Z","caller":"traceutil/trace.go:171","msg":"trace[1176918830] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"645.672561ms","start":"2026-07-25T20:04:00.774358Z","end":"2026-07-25T20:04:01.420031Z","steps":["trace[1176918830] 'process raft request' (duration: 645.100697ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T20:04:01.420249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T20:04:00.774328Z","time spent":"645.816545ms","remote":"127.0.0.1:50450","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036317,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T20:04:01.420286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"805.959009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T20:04:01.42035Z","caller":"traceutil/trace.go:171","msg":"trace[826984000] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:627; }","duration":"806.074281ms","start":"2026-07-25T20:04:00.614257Z","end":"2026-07-25T20:04:01.420332Z","steps":["trace[826984000] 'agreement among raft nodes before linearized reading' (duration: 805.808204ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T20:04:01.420408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T20:04:00.614236Z","time spent":"806.159253ms","remote":"127.0.0.1:50336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-25T20:04:17.024221Z","caller":"traceutil/trace.go:171","msg":"trace[1230246315] transaction","detail":"{read_only:false; response_revision:789; number_of_response:1; }","duration":"191.470939ms","start":"2026-07-25T20:04:16.832721Z","end":"2026-07-25T20:04:17.024192Z","steps":["trace[1230246315] 'process raft request' (duration: 191.269433ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T20:04:54.312942Z","caller":"traceutil/trace.go:171","msg":"trace[1570725205] transaction","detail":"{read_only:false; response_revision:1008; number_of_response:1; }","duration":"273.549707ms","start":"2026-07-25T20:04:54.039358Z","end":"2026-07-25T20:04:54.312907Z","steps":["trace[1570725205] 'process raft request' (duration: 273.341632ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T20:04:54.811188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.724424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T20:04:54.811276Z","caller":"traceutil/trace.go:171","msg":"trace[855873991] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1008; }","duration":"196.875498ms","start":"2026-07-25T20:04:54.614381Z","end":"2026-07-25T20:04:54.811256Z","steps":["trace[855873991] 'range keys from in-memory index tree' (duration: 196.617041ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T20:04:54.811355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.885723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-25T20:04:54.811462Z","caller":"traceutil/trace.go:171","msg":"trace[998642548] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1008; }","duration":"360.037007ms","start":"2026-07-25T20:04:54.451398Z","end":"2026-07-25T20:04:54.811435Z","steps":["trace[998642548] 'range keys from in-memory index tree' (duration: 359.681538ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T20:04:54.811527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T20:04:54.451387Z","time spent":"360.129059ms","remote":"127.0.0.1:50622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-25T20:04:54.811865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.79274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-25T20:04:54.811971Z","caller":"traceutil/trace.go:171","msg":"trace[1408581250] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1008; }","duration":"379.964255ms","start":"2026-07-25T20:04:54.431982Z","end":"2026-07-25T20:04:54.811946Z","steps":["trace[1408581250] 'range keys from in-memory index tree' (duration: 378.946329ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T20:04:54.812039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T20:04:54.431959Z","time spent":"380.069457ms","remote":"127.0.0.1:50622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-25T20:04:54.881689Z","caller":"traceutil/trace.go:171","msg":"trace[935816263] linearizableReadLoop","detail":"{readStateIndex:1044; appliedIndex:1043; }","duration":"189.868438ms","start":"2026-07-25T20:04:54.691786Z","end":"2026-07-25T20:04:54.881655Z","steps":["trace[935816263] 'read index received' (duration: 189.717504ms)","trace[935816263] 'applied index is now lower than readState.Index' (duration: 149.574µs)"],"step_count":2} {"level":"info","ts":"2026-07-25T20:04:54.881737Z","caller":"traceutil/trace.go:171","msg":"trace[841110880] transaction","detail":"{read_only:false; response_revision:1009; number_of_response:1; }","duration":"242.273842ms","start":"2026-07-25T20:04:54.639431Z","end":"2026-07-25T20:04:54.881705Z","steps":["trace[841110880] 'process raft request' (duration: 242.058946ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T20:04:54.881956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.162746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.229\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-25T20:04:54.882006Z","caller":"traceutil/trace.go:171","msg":"trace[1024102394] range","detail":"{range_begin:/registry/masterleases/199.204.45.229; range_end:; response_count:1; response_revision:1009; }","duration":"190.29499ms","start":"2026-07-25T20:04:54.691698Z","end":"2026-07-25T20:04:54.881993Z","steps":["trace[1024102394] 'agreement among raft nodes before linearized reading' (duration: 190.107514ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T20:04:55.381814Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3880870991439571615,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-25T20:04:55.885792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.664151ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-25T20:04:55.886018Z","caller":"traceutil/trace.go:171","msg":"trace[1281734140] transaction","detail":"{read_only:false; response_revision:1011; number_of_response:1; }","duration":"1.069174745s","start":"2026-07-25T20:04:54.816819Z","end":"2026-07-25T20:04:55.885994Z","steps":["trace[1281734140] 'process raft request' (duration: 1.069107223s)"],"step_count":1} {"level":"warn","ts":"2026-07-25T20:04:55.886087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T20:04:54.816782Z","time spent":"1.069270968s","remote":"127.0.0.1:50622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T20:04:55.886131Z","caller":"traceutil/trace.go:171","msg":"trace[1073296779] transaction","detail":"{read_only:false; response_revision:1010; number_of_response:1; }","duration":"1.069551834s","start":"2026-07-25T20:04:54.816518Z","end":"2026-07-25T20:04:55.88607Z","steps":["trace[1073296779] 'process raft request' (duration: 898.497193ms)","trace[1073296779] 'compare' (duration: 170.469616ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T20:04:55.886353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T20:04:54.816491Z","time spent":"1.069737449s","remote":"127.0.0.1:50622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T20:04:55.892175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T20:04:54.883176Z","time spent":"1.008976783s","remote":"127.0.0.1:50386","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-07-25T20:04:55.892265Z","caller":"traceutil/trace.go:171","msg":"trace[1018142707] linearizableReadLoop","detail":"{readStateIndex:1048; appliedIndex:1044; }","duration":"1.010468022s","start":"2026-07-25T20:04:54.881773Z","end":"2026-07-25T20:04:55.892241Z","steps":["trace[1018142707] 'read index received' (duration: 833.187483ms)","trace[1018142707] 'applied index is now lower than readState.Index' (duration: 177.279459ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T20:04:55.892392Z","caller":"traceutil/trace.go:171","msg":"trace[18577783] transaction","detail":"{read_only:false; response_revision:1012; number_of_response:1; }","duration":"565.146855ms","start":"2026-07-25T20:04:55.327235Z","end":"2026-07-25T20:04:55.892382Z","steps":["trace[18577783] 'process raft request' (duration: 564.878609ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T20:04:55.892511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T20:04:55.327214Z","time spent":"565.210076ms","remote":"127.0.0.1:50622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T20:04:55.892565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"672.403923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-25T20:04:55.892624Z","caller":"traceutil/trace.go:171","msg":"trace[104251980] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1012; }","duration":"672.520216ms","start":"2026-07-25T20:04:55.220089Z","end":"2026-07-25T20:04:55.892609Z","steps":["trace[104251980] 'agreement among raft nodes before linearized reading' (duration: 672.348102ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T20:04:55.892664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T20:04:55.220062Z","time spent":"672.593977ms","remote":"127.0.0.1:50622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-25T20:04:55.89268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"516.63216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-25T20:04:55.892728Z","caller":"traceutil/trace.go:171","msg":"trace[962214083] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:1012; }","duration":"516.769754ms","start":"2026-07-25T20:04:55.375942Z","end":"2026-07-25T20:04:55.892712Z","steps":["trace[962214083] 'agreement among raft nodes before linearized reading' (duration: 516.62098ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T20:04:55.892768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T20:04:55.375909Z","time spent":"516.853326ms","remote":"127.0.0.1:50558","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":43,"response size":29,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"warn","ts":"2026-07-25T20:04:55.892821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"569.246239ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-25T20:04:55.892862Z","caller":"traceutil/trace.go:171","msg":"trace[2221707] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1012; }","duration":"569.336041ms","start":"2026-07-25T20:04:55.323516Z","end":"2026-07-25T20:04:55.892852Z","steps":["trace[2221707] 'agreement among raft nodes before linearized reading' (duration: 569.230428ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T20:04:55.892889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T20:04:55.323492Z","time spent":"569.391102ms","remote":"127.0.0.1:50622","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-07-25T20:04:55.892908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.079348023s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T20:04:55.892938Z","caller":"traceutil/trace.go:171","msg":"trace[1835800281] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1012; }","duration":"1.079399244s","start":"2026-07-25T20:04:54.813528Z","end":"2026-07-25T20:04:55.892928Z","steps":["trace[1835800281] 'agreement among raft nodes before linearized reading' (duration: 1.079355063s)"],"step_count":1} {"level":"warn","ts":"2026-07-25T20:04:55.892963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T20:04:54.813506Z","time spent":"1.079452476s","remote":"127.0.0.1:50352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-25T20:05:22.198129Z","caller":"traceutil/trace.go:171","msg":"trace[772279923] transaction","detail":"{read_only:false; response_revision:1100; number_of_response:1; }","duration":"155.746261ms","start":"2026-07-25T20:05:22.042324Z","end":"2026-07-25T20:05:22.19807Z","steps":["trace[772279923] 'process raft request' (duration: 155.518865ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T20:05:24.59488Z","caller":"traceutil/trace.go:171","msg":"trace[2092174692] linearizableReadLoop","detail":"{readStateIndex:1148; appliedIndex:1147; }","duration":"112.325137ms","start":"2026-07-25T20:05:24.482529Z","end":"2026-07-25T20:05:24.594854Z","steps":["trace[2092174692] 'read index received' (duration: 112.155983ms)","trace[2092174692] 'applied index is now lower than readState.Index' (duration: 168.274µs)"],"step_count":2} {"level":"info","ts":"2026-07-25T20:05:24.595052Z","caller":"traceutil/trace.go:171","msg":"trace[667016543] transaction","detail":"{read_only:false; response_revision:1107; number_of_response:1; }","duration":"116.686568ms","start":"2026-07-25T20:05:24.478318Z","end":"2026-07-25T20:05:24.595004Z","steps":["trace[667016543] 'process raft request' (duration: 116.35659ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T20:05:24.59532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.517542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T20:05:24.595372Z","caller":"traceutil/trace.go:171","msg":"trace[550802506] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:1107; }","duration":"112.872641ms","start":"2026-07-25T20:05:24.482485Z","end":"2026-07-25T20:05:24.595358Z","steps":["trace[550802506] 'agreement among raft nodes before linearized reading' (duration: 112.474851ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T20:05:43.468758Z","caller":"traceutil/trace.go:171","msg":"trace[2032601871] transaction","detail":"{read_only:false; response_revision:1220; number_of_response:1; }","duration":"323.676962ms","start":"2026-07-25T20:05:43.145036Z","end":"2026-07-25T20:05:43.468713Z","steps":["trace[2032601871] 'process raft request' (duration: 323.488238ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T20:05:43.46894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T20:05:43.145012Z","time spent":"323.831637ms","remote":"127.0.0.1:50622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T20:05:56.979011Z","caller":"traceutil/trace.go:171","msg":"trace[1792507475] linearizableReadLoop","detail":"{readStateIndex:1311; appliedIndex:1310; }","duration":"140.180895ms","start":"2026-07-25T20:05:56.838806Z","end":"2026-07-25T20:05:56.978987Z","steps":["trace[1792507475] 'read index received' (duration: 139.96046ms)","trace[1792507475] 'applied index is now lower than readState.Index' (duration: 219.865µs)"],"step_count":2} {"level":"warn","ts":"2026-07-25T20:05:56.979231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.3991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-25T20:05:56.979184Z","caller":"traceutil/trace.go:171","msg":"trace[928468735] transaction","detail":"{read_only:false; response_revision:1261; number_of_response:1; }","duration":"142.959806ms","start":"2026-07-25T20:05:56.836168Z","end":"2026-07-25T20:05:56.979127Z","steps":["trace[928468735] 'process raft request' (duration: 142.694119ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T20:05:56.979262Z","caller":"traceutil/trace.go:171","msg":"trace[2121469632] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1261; }","duration":"140.471093ms","start":"2026-07-25T20:05:56.838783Z","end":"2026-07-25T20:05:56.979254Z","steps":["trace[2121469632] 'agreement among raft nodes before linearized reading' (duration: 140.317119ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T20:05:56.979364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.989196ms","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-07-25T20:05:56.979458Z","caller":"traceutil/trace.go:171","msg":"trace[786668296] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1261; }","duration":"119.111529ms","start":"2026-07-25T20:05:56.860323Z","end":"2026-07-25T20:05:56.979435Z","steps":["trace[786668296] 'agreement among raft nodes before linearized reading' (duration: 118.846083ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T20:05:59.753481Z","caller":"traceutil/trace.go:171","msg":"trace[708326855] linearizableReadLoop","detail":"{readStateIndex:1323; appliedIndex:1322; }","duration":"137.685291ms","start":"2026-07-25T20:05:59.615768Z","end":"2026-07-25T20:05:59.753453Z","steps":["trace[708326855] 'read index received' (duration: 137.561268ms)","trace[708326855] 'applied index is now lower than readState.Index' (duration: 122.973µs)"],"step_count":2} {"level":"info","ts":"2026-07-25T20:05:59.753548Z","caller":"traceutil/trace.go:171","msg":"trace[1337472309] transaction","detail":"{read_only:false; response_revision:1273; number_of_response:1; }","duration":"162.976066ms","start":"2026-07-25T20:05:59.590524Z","end":"2026-07-25T20:05:59.7535Z","steps":["trace[1337472309] 'process raft request' (duration: 162.788701ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T20:05:59.753693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.900798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T20:05:59.753794Z","caller":"traceutil/trace.go:171","msg":"trace[40299344] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1273; }","duration":"138.067282ms","start":"2026-07-25T20:05:59.615702Z","end":"2026-07-25T20:05:59.753769Z","steps":["trace[40299344] 'agreement among raft nodes before linearized reading' (duration: 137.865217ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T20:05:59.772628Z","caller":"traceutil/trace.go:171","msg":"trace[2069471614] transaction","detail":"{read_only:false; response_revision:1274; number_of_response:1; }","duration":"102.506716ms","start":"2026-07-25T20:05:59.669996Z","end":"2026-07-25T20:05:59.772502Z","steps":["trace[2069471614] 'process raft request' (duration: 102.286351ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T20:05:59.773111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.900916ms","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-07-25T20:05:59.773179Z","caller":"traceutil/trace.go:171","msg":"trace[2106654266] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1274; }","duration":"125.01947ms","start":"2026-07-25T20:05:59.648142Z","end":"2026-07-25T20:05:59.773161Z","steps":["trace[2106654266] 'agreement among raft nodes before linearized reading' (duration: 124.845274ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T20:06:00.554755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.529171ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-07-25T20:06:01.158538Z","caller":"traceutil/trace.go:171","msg":"trace[1099771842] linearizableReadLoop","detail":"{readStateIndex:1326; appliedIndex:1325; }","duration":"209.92753ms","start":"2026-07-25T20:06:00.94858Z","end":"2026-07-25T20:06:01.158507Z","steps":["trace[1099771842] 'read index received' (duration: 209.695604ms)","trace[1099771842] 'applied index is now lower than readState.Index' (duration: 230.426µs)"],"step_count":2} {"level":"info","ts":"2026-07-25T20:06:01.15865Z","caller":"traceutil/trace.go:171","msg":"trace[1577346570] transaction","detail":"{read_only:false; response_revision:1275; number_of_response:1; }","duration":"302.199757ms","start":"2026-07-25T20:06:00.856391Z","end":"2026-07-25T20:06:01.15859Z","steps":["trace[1577346570] 'process raft request' (duration: 301.887319ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T20:06:01.159055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T20:06:00.856367Z","time spent":"302.610887ms","remote":"127.0.0.1:50622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T20:06:01.158778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.155845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-25T20:06:01.159205Z","caller":"traceutil/trace.go:171","msg":"trace[1098227816] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1275; }","duration":"210.644818ms","start":"2026-07-25T20:06:00.948536Z","end":"2026-07-25T20:06:01.159181Z","steps":["trace[1098227816] 'agreement among raft nodes before linearized reading' (duration: 210.145445ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T20:06:01.15981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.857593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-25T20:06:01.160268Z","caller":"traceutil/trace.go:171","msg":"trace[675403561] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1275; }","duration":"119.354996ms","start":"2026-07-25T20:06:01.040896Z","end":"2026-07-25T20:06:01.160251Z","steps":["trace[675403561] 'agreement among raft nodes before linearized reading' (duration: 118.789771ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T20:06:01.160279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.243439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-25T20:06:01.160321Z","caller":"traceutil/trace.go:171","msg":"trace[850299491] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1275; }","duration":"107.29343ms","start":"2026-07-25T20:06:01.053016Z","end":"2026-07-25T20:06:01.160309Z","steps":["trace[850299491] 'agreement among raft nodes before linearized reading' (duration: 107.197667ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T20:06:01.160113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.169387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-25T20:06:01.160432Z","caller":"traceutil/trace.go:171","msg":"trace[83542901] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1275; }","duration":"107.531816ms","start":"2026-07-25T20:06:01.052891Z","end":"2026-07-25T20:06:01.160423Z","steps":["trace[83542901] 'agreement among raft nodes before linearized reading' (duration: 107.148946ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T20:06:01.160165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.125745ms","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-07-25T20:06:01.160611Z","caller":"traceutil/trace.go:171","msg":"trace[671267620] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1275; }","duration":"107.603048ms","start":"2026-07-25T20:06:01.05299Z","end":"2026-07-25T20:06:01.160593Z","steps":["trace[671267620] 'agreement among raft nodes before linearized reading' (duration: 106.919601ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T20:06:01.160201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.189412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-25T20:06:01.160841Z","caller":"traceutil/trace.go:171","msg":"trace[1190066205] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1275; }","duration":"108.852949ms","start":"2026-07-25T20:06:01.051978Z","end":"2026-07-25T20:06:01.160831Z","steps":["trace[1190066205] 'agreement among raft nodes before linearized reading' (duration: 108.168722ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T20:06:01.433561Z","caller":"traceutil/trace.go:171","msg":"trace[695188299] transaction","detail":"{read_only:false; response_revision:1276; number_of_response:1; }","duration":"268.029266ms","start":"2026-07-25T20:06:01.165492Z","end":"2026-07-25T20:06:01.433521Z","steps":["trace[695188299] 'process raft request' (duration: 199.751ms)","trace[695188299] 'compare' (duration: 68.057661ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T20:06:01.531332Z","caller":"traceutil/trace.go:171","msg":"trace[1186178734] transaction","detail":"{read_only:false; response_revision:1277; number_of_response:1; }","duration":"365.581548ms","start":"2026-07-25T20:06:01.165723Z","end":"2026-07-25T20:06:01.531304Z","steps":["trace[1186178734] 'process raft request' (duration: 365.409344ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T20:06:01.531504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T20:06:01.165711Z","time spent":"365.700511ms","remote":"127.0.0.1:50622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T20:06:01.531638Z","caller":"traceutil/trace.go:171","msg":"trace[82147245] transaction","detail":"{read_only:false; response_revision:1280; number_of_response:1; }","duration":"363.68389ms","start":"2026-07-25T20:06:01.167934Z","end":"2026-07-25T20:06:01.531618Z","steps":["trace[82147245] 'process raft request' (duration: 363.616278ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T20:06:01.53165Z","caller":"traceutil/trace.go:171","msg":"trace[281686212] transaction","detail":"{read_only:false; response_revision:1279; number_of_response:1; }","duration":"363.793533ms","start":"2026-07-25T20:06:01.167845Z","end":"2026-07-25T20:06:01.531639Z","steps":["trace[281686212] 'process raft request' (duration: 363.654029ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T20:06:01.531693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T20:06:01.167831Z","time spent":"363.837584ms","remote":"127.0.0.1:50622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T20:06:01.531699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T20:06:01.167926Z","time spent":"363.741271ms","remote":"127.0.0.1:50622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T20:06:01.531676Z","caller":"traceutil/trace.go:171","msg":"trace[1141031883] transaction","detail":"{read_only:false; response_revision:1278; number_of_response:1; }","duration":"364.931932ms","start":"2026-07-25T20:06:01.166686Z","end":"2026-07-25T20:06:01.531617Z","steps":["trace[1141031883] 'process raft request' (duration: 364.574263ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T20:06:01.531902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T20:06:01.166665Z","time spent":"365.104116ms","remote":"127.0.0.1:50622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T20:06:02.531091Z","caller":"traceutil/trace.go:171","msg":"trace[243274265] linearizableReadLoop","detail":"{readStateIndex:1333; appliedIndex:1332; }","duration":"475.73423ms","start":"2026-07-25T20:06:02.055325Z","end":"2026-07-25T20:06:02.531059Z","steps":["trace[243274265] 'read index received' (duration: 424.047966ms)","trace[243274265] 'applied index is now lower than readState.Index' (duration: 51.685054ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T20:06:02.531092Z","caller":"traceutil/trace.go:171","msg":"trace[944436743] transaction","detail":"{read_only:false; response_revision:1282; number_of_response:1; }","duration":"590.567621ms","start":"2026-07-25T20:06:01.940493Z","end":"2026-07-25T20:06:02.531061Z","steps":["trace[944436743] 'process raft request' (duration: 538.955749ms)","trace[944436743] 'compare' (duration: 51.093279ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T20:06:02.531266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T20:06:01.940467Z","time spent":"590.718124ms","remote":"127.0.0.1:50424","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":751,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-25T20:06:02.531386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.053848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:5331"} {"level":"info","ts":"2026-07-25T20:06:02.531439Z","caller":"traceutil/trace.go:171","msg":"trace[206106526] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:1282; }","duration":"476.152181ms","start":"2026-07-25T20:06:02.055276Z","end":"2026-07-25T20:06:02.531428Z","steps":["trace[206106526] 'agreement among raft nodes before linearized reading' (duration: 475.887394ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T20:06:02.531479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T20:06:02.055256Z","time spent":"476.215153ms","remote":"127.0.0.1:50534","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":5353,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-07-25T20:06:02.895605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.886318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T20:06:02.895716Z","caller":"traceutil/trace.go:171","msg":"trace[1197398760] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1282; }","duration":"223.057742ms","start":"2026-07-25T20:06:02.672632Z","end":"2026-07-25T20:06:02.89569Z","steps":["trace[1197398760] 'range keys from in-memory index tree' (duration: 222.768085ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T20:06:03.234202Z","caller":"traceutil/trace.go:171","msg":"trace[2084653724] transaction","detail":"{read_only:false; response_revision:1283; number_of_response:1; }","duration":"244.018736ms","start":"2026-07-25T20:06:02.990129Z","end":"2026-07-25T20:06:03.234148Z","steps":["trace[2084653724] 'process raft request' (duration: 243.75586ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T20:06:11.159925Z","caller":"traceutil/trace.go:171","msg":"trace[322656335] linearizableReadLoop","detail":"{readStateIndex:1401; appliedIndex:1400; }","duration":"416.668288ms","start":"2026-07-25T20:06:10.743228Z","end":"2026-07-25T20:06:11.159896Z","steps":["trace[322656335] 'read index received' (duration: 416.382591ms)","trace[322656335] 'applied index is now lower than readState.Index' (duration: 284.777µs)"],"step_count":2} {"level":"warn","ts":"2026-07-25T20:06:11.160109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.844062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T20:06:11.160151Z","caller":"traceutil/trace.go:171","msg":"trace[1050223428] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:1348; }","duration":"416.974156ms","start":"2026-07-25T20:06:10.743165Z","end":"2026-07-25T20:06:11.160139Z","steps":["trace[1050223428] 'agreement among raft nodes before linearized reading' (duration: 416.855073ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T20:06:11.160197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T20:06:10.743141Z","time spent":"417.045517ms","remote":"127.0.0.1:49312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true "} {"level":"info","ts":"2026-07-25T20:06:11.160518Z","caller":"traceutil/trace.go:171","msg":"trace[1170842980] transaction","detail":"{read_only:false; response_revision:1348; number_of_response:1; }","duration":"489.647864ms","start":"2026-07-25T20:06:10.670568Z","end":"2026-07-25T20:06:11.160216Z","steps":["trace[1170842980] 'process raft request' (duration: 489.166842ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T20:06:11.160747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T20:06:10.670546Z","time spent":"490.131715ms","remote":"127.0.0.1:50622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T20:06:16.754775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.76632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T20:06:16.754929Z","caller":"traceutil/trace.go:171","msg":"trace[2060412478] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1393; }","duration":"141.002036ms","start":"2026-07-25T20:06:16.613904Z","end":"2026-07-25T20:06:16.754906Z","steps":["trace[2060412478] 'range keys from in-memory index tree' (duration: 140.671658ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T20:06:17.222181Z","caller":"traceutil/trace.go:171","msg":"trace[682947120] transaction","detail":"{read_only:false; response_revision:1394; number_of_response:1; }","duration":"183.59392ms","start":"2026-07-25T20:06:17.038567Z","end":"2026-07-25T20:06:17.222161Z","steps":["trace[682947120] 'process raft request' (duration: 183.477307ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T20:06:27.913726Z","caller":"traceutil/trace.go:171","msg":"trace[1898675296] transaction","detail":"{read_only:false; response_revision:1447; number_of_response:1; }","duration":"113.652932ms","start":"2026-07-25T20:06:27.800039Z","end":"2026-07-25T20:06:27.913692Z","steps":["trace[1898675296] 'process raft request' (duration: 111.412095ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T20:06:28.138406Z","caller":"traceutil/trace.go:171","msg":"trace[939084589] transaction","detail":"{read_only:false; response_revision:1449; number_of_response:1; }","duration":"143.076339ms","start":"2026-07-25T20:06:27.995287Z","end":"2026-07-25T20:06:28.138364Z","steps":["trace[939084589] 'process raft request' (duration: 70.828121ms)","trace[939084589] 'compare' (duration: 72.071684ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T20:06:32.440351Z","caller":"traceutil/trace.go:171","msg":"trace[1342790563] transaction","detail":"{read_only:false; response_revision:1468; number_of_response:1; }","duration":"176.731715ms","start":"2026-07-25T20:06:32.263579Z","end":"2026-07-25T20:06:32.440311Z","steps":["trace[1342790563] 'process raft request' (duration: 176.550171ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T20:06:34.074731Z","caller":"traceutil/trace.go:171","msg":"trace[1239575086] linearizableReadLoop","detail":"{readStateIndex:1531; appliedIndex:1530; }","duration":"129.920804ms","start":"2026-07-25T20:06:33.944779Z","end":"2026-07-25T20:06:34.0747Z","steps":["trace[1239575086] 'read index received' (duration: 85.771431ms)","trace[1239575086] 'applied index is now lower than readState.Index' (duration: 44.147953ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T20:06:34.074973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.189891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-25T20:06:34.074956Z","caller":"traceutil/trace.go:171","msg":"trace[2042594548] transaction","detail":"{read_only:false; response_revision:1474; number_of_response:1; }","duration":"133.970638ms","start":"2026-07-25T20:06:33.940895Z","end":"2026-07-25T20:06:34.074865Z","steps":["trace[2042594548] 'process raft request' (duration: 89.792594ms)","trace[2042594548] 'compare' (duration: 43.913847ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T20:06:34.075034Z","caller":"traceutil/trace.go:171","msg":"trace[1436357750] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1474; }","duration":"130.274603ms","start":"2026-07-25T20:06:33.94475Z","end":"2026-07-25T20:06:34.075024Z","steps":["trace[1436357750] 'agreement among raft nodes before linearized reading' (duration: 130.084578ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T20:06:36.405747Z","caller":"traceutil/trace.go:171","msg":"trace[461120549] transaction","detail":"{read_only:false; response_revision:1485; number_of_response:1; }","duration":"122.075725ms","start":"2026-07-25T20:06:36.283627Z","end":"2026-07-25T20:06:36.405703Z","steps":["trace[461120549] 'process raft request' (duration: 96.923285ms)","trace[461120549] 'compare' (duration: 24.844022ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T20:06:37.89855Z","caller":"traceutil/trace.go:171","msg":"trace[618218825] transaction","detail":"{read_only:false; response_revision:1489; number_of_response:1; }","duration":"102.980721ms","start":"2026-07-25T20:06:37.795507Z","end":"2026-07-25T20:06:37.898487Z","steps":["trace[618218825] 'process raft request' (duration: 102.173372ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T20:06:38.115945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.56837ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-25T20:06:38.11607Z","caller":"traceutil/trace.go:171","msg":"trace[719943589] linearizableReadLoop","detail":"{readStateIndex:1548; appliedIndex:1547; }","duration":"198.25849ms","start":"2026-07-25T20:06:37.917791Z","end":"2026-07-25T20:06:38.116049Z","steps":["trace[719943589] 'read index received' (duration: 85.499995ms)","trace[719943589] 'applied index is now lower than readState.Index' (duration: 112.757125ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T20:06:38.116154Z","caller":"traceutil/trace.go:171","msg":"trace[859489272] transaction","detail":"{read_only:false; response_revision:1490; number_of_response:1; }","duration":"205.957942ms","start":"2026-07-25T20:06:37.910179Z","end":"2026-07-25T20:06:38.116137Z","steps":["trace[859489272] 'process raft request' (duration: 93.067584ms)","trace[859489272] 'compare' (duration: 112.451007ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T20:06:38.116375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.563648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-25T20:06:38.11648Z","caller":"traceutil/trace.go:171","msg":"trace[2026749992] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1490; }","duration":"198.716282ms","start":"2026-07-25T20:06:37.917736Z","end":"2026-07-25T20:06:38.116453Z","steps":["trace[2026749992] 'agreement among raft nodes before linearized reading' (duration: 198.449085ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T20:06:38.122323Z","caller":"traceutil/trace.go:171","msg":"trace[49036094] transaction","detail":"{read_only:false; response_revision:1491; number_of_response:1; }","duration":"172.687494ms","start":"2026-07-25T20:06:37.949582Z","end":"2026-07-25T20:06:38.12227Z","steps":["trace[49036094] 'process raft request' (duration: 172.076739ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T20:06:46.160259Z","caller":"traceutil/trace.go:171","msg":"trace[1506324961] transaction","detail":"{read_only:false; response_revision:1525; number_of_response:1; }","duration":"123.521931ms","start":"2026-07-25T20:06:46.036704Z","end":"2026-07-25T20:06:46.160226Z","steps":["trace[1506324961] 'process raft request' (duration: 123.297746ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T20:06:46.292886Z","caller":"traceutil/trace.go:171","msg":"trace[790206081] transaction","detail":"{read_only:false; response_revision:1526; number_of_response:1; }","duration":"100.623872ms","start":"2026-07-25T20:06:46.192223Z","end":"2026-07-25T20:06:46.292846Z","steps":["trace[790206081] 'process raft request' (duration: 24.95886ms)","trace[790206081] 'compare' (duration: 75.422396ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T20:06:48.508563Z","caller":"traceutil/trace.go:171","msg":"trace[912443898] transaction","detail":"{read_only:false; response_revision:1537; number_of_response:1; }","duration":"128.200077ms","start":"2026-07-25T20:06:48.380334Z","end":"2026-07-25T20:06:48.508534Z","steps":["trace[912443898] 'process raft request' (duration: 122.132636ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T20:06:48.51839Z","caller":"traceutil/trace.go:171","msg":"trace[1823295381] transaction","detail":"{read_only:false; response_revision:1540; number_of_response:1; }","duration":"135.068328ms","start":"2026-07-25T20:06:48.383303Z","end":"2026-07-25T20:06:48.518371Z","steps":["trace[1823295381] 'process raft request' (duration: 134.709209ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T20:06:48.518403Z","caller":"traceutil/trace.go:171","msg":"trace[1680945194] transaction","detail":"{read_only:false; response_revision:1538; number_of_response:1; }","duration":"137.52098ms","start":"2026-07-25T20:06:48.380833Z","end":"2026-07-25T20:06:48.518354Z","steps":["trace[1680945194] 'process raft request' (duration: 136.661359ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T20:06:48.518353Z","caller":"traceutil/trace.go:171","msg":"trace[1861125409] transaction","detail":"{read_only:false; response_revision:1539; number_of_response:1; }","duration":"137.385697ms","start":"2026-07-25T20:06:48.38092Z","end":"2026-07-25T20:06:48.518306Z","steps":["trace[1861125409] 'process raft request' (duration: 137.036648ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T20:07:02.742642Z","caller":"traceutil/trace.go:171","msg":"trace[672747772] transaction","detail":"{read_only:false; response_revision:1602; number_of_response:1; }","duration":"111.713538ms","start":"2026-07-25T20:07:02.63088Z","end":"2026-07-25T20:07:02.742594Z","steps":["trace[672747772] 'process raft request' (duration: 42.881086ms)","trace[672747772] 'compare' (duration: 68.595855ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T20:07:02.934339Z","caller":"traceutil/trace.go:171","msg":"trace[443243320] transaction","detail":"{read_only:false; response_revision:1604; number_of_response:1; }","duration":"185.062012ms","start":"2026-07-25T20:07:02.749247Z","end":"2026-07-25T20:07:02.934309Z","steps":["trace[443243320] 'process raft request' (duration: 181.059923ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T20:07:02.934765Z","caller":"traceutil/trace.go:171","msg":"trace[1988263286] transaction","detail":"{read_only:false; response_revision:1605; number_of_response:1; }","duration":"185.479302ms","start":"2026-07-25T20:07:02.749251Z","end":"2026-07-25T20:07:02.93473Z","steps":["trace[1988263286] 'process raft request' (duration: 185.00764ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T20:07:02.93485Z","caller":"traceutil/trace.go:171","msg":"trace[892878594] transaction","detail":"{read_only:false; response_revision:1606; number_of_response:1; }","duration":"185.426541ms","start":"2026-07-25T20:07:02.74935Z","end":"2026-07-25T20:07:02.934776Z","steps":["trace[892878594] 'process raft request' (duration: 185.230146ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T20:07:09.12263Z","caller":"traceutil/trace.go:171","msg":"trace[44022565] linearizableReadLoop","detail":"{readStateIndex:1694; appliedIndex:1693; }","duration":"104.903709ms","start":"2026-07-25T20:07:09.017689Z","end":"2026-07-25T20:07:09.122592Z","steps":["trace[44022565] 'read index received' (duration: 46.624659ms)","trace[44022565] 'applied index is now lower than readState.Index' (duration: 58.27718ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T20:07:09.122705Z","caller":"traceutil/trace.go:171","msg":"trace[1551138265] transaction","detail":"{read_only:false; response_revision:1630; number_of_response:1; }","duration":"276.178418ms","start":"2026-07-25T20:07:08.846474Z","end":"2026-07-25T20:07:09.122652Z","steps":["trace[1551138265] 'process raft request' (duration: 217.872848ms)","trace[1551138265] 'compare' (duration: 58.055264ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T20:07:09.12313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.39841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-25T20:07:09.123223Z","caller":"traceutil/trace.go:171","msg":"trace[1715222528] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1630; }","duration":"105.557405ms","start":"2026-07-25T20:07:09.017626Z","end":"2026-07-25T20:07:09.123184Z","steps":["trace[1715222528] 'agreement among raft nodes before linearized reading' (duration: 105.169526ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T20:07:09.123352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.807582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-25T20:07:09.123449Z","caller":"traceutil/trace.go:171","msg":"trace[309616108] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1630; }","duration":"101.954855ms","start":"2026-07-25T20:07:09.021476Z","end":"2026-07-25T20:07:09.12343Z","steps":["trace[309616108] 'agreement among raft nodes before linearized reading' (duration: 101.599846ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T20:07:09.124075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.995371ms","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-07-25T20:07:09.124162Z","caller":"traceutil/trace.go:171","msg":"trace[829051275] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1630; }","duration":"103.098944ms","start":"2026-07-25T20:07:09.021048Z","end":"2026-07-25T20:07:09.124147Z","steps":["trace[829051275] 'agreement among raft nodes before linearized reading' (duration: 101.927564ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T20:07:18.362821Z","caller":"traceutil/trace.go:171","msg":"trace[1802372768] transaction","detail":"{read_only:false; response_revision:1672; number_of_response:1; }","duration":"198.032603ms","start":"2026-07-25T20:07:18.164745Z","end":"2026-07-25T20:07:18.362778Z","steps":["trace[1802372768] 'process raft request' (duration: 197.854149ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T20:07:19.21077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"596.581436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-25T20:07:19.210807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"723.608374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-25T20:07:19.210964Z","caller":"traceutil/trace.go:171","msg":"trace[1164700179] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1672; }","duration":"723.809479ms","start":"2026-07-25T20:07:18.487129Z","end":"2026-07-25T20:07:19.210939Z","steps":["trace[1164700179] 'range keys from in-memory index tree' (duration: 723.425599ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T20:07:19.210965Z","caller":"traceutil/trace.go:171","msg":"trace[819176409] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1672; }","duration":"596.871613ms","start":"2026-07-25T20:07:18.614045Z","end":"2026-07-25T20:07:19.210917Z","steps":["trace[819176409] 'range keys from in-memory index tree' (duration: 596.406011ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T20:07:19.211015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T20:07:18.487108Z","time spent":"723.895451ms","remote":"127.0.0.1:50622","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-07-25T20:07:19.211026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T20:07:18.614025Z","time spent":"596.990655ms","remote":"127.0.0.1:50336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-25T20:07:19.216197Z","caller":"traceutil/trace.go:171","msg":"trace[755984861] transaction","detail":"{read_only:false; response_revision:1673; number_of_response:1; }","duration":"363.842838ms","start":"2026-07-25T20:07:18.852336Z","end":"2026-07-25T20:07:19.216179Z","steps":["trace[755984861] 'process raft request' (duration: 363.658033ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T20:07:19.216315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T20:07:18.852306Z","time spent":"363.93913ms","remote":"127.0.0.1:50622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T20:07:19.217431Z","caller":"traceutil/trace.go:171","msg":"trace[1127543493] transaction","detail":"{read_only:false; response_revision:1674; number_of_response:1; }","duration":"363.12248ms","start":"2026-07-25T20:07:18.854279Z","end":"2026-07-25T20:07:19.217401Z","steps":["trace[1127543493] 'process raft request' (duration: 362.945595ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T20:07:19.217681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T20:07:18.854264Z","time spent":"363.315585ms","remote":"127.0.0.1:50622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T20:07:19.397053Z","caller":"traceutil/trace.go:171","msg":"trace[236615386] transaction","detail":"{read_only:false; response_revision:1679; number_of_response:1; }","duration":"125.799068ms","start":"2026-07-25T20:07:19.271219Z","end":"2026-07-25T20:07:19.397018Z","steps":["trace[236615386] 'process raft request' (duration: 118.764532ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T20:07:19.405293Z","caller":"traceutil/trace.go:171","msg":"trace[1955206615] transaction","detail":"{read_only:false; response_revision:1680; number_of_response:1; }","duration":"133.960741ms","start":"2026-07-25T20:07:19.271304Z","end":"2026-07-25T20:07:19.405264Z","steps":["trace[1955206615] 'process raft request' (duration: 133.819237ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T20:07:25.344117Z","caller":"traceutil/trace.go:171","msg":"trace[1932025332] linearizableReadLoop","detail":"{readStateIndex:1770; appliedIndex:1769; }","duration":"101.31445ms","start":"2026-07-25T20:07:25.24277Z","end":"2026-07-25T20:07:25.344085Z","steps":["trace[1932025332] 'read index received' (duration: 101.115894ms)","trace[1932025332] 'applied index is now lower than readState.Index' (duration: 197.415µs)"],"step_count":2} {"level":"info","ts":"2026-07-25T20:07:25.344259Z","caller":"traceutil/trace.go:171","msg":"trace[2129037010] transaction","detail":"{read_only:false; response_revision:1702; number_of_response:1; }","duration":"102.300474ms","start":"2026-07-25T20:07:25.241947Z","end":"2026-07-25T20:07:25.344247Z","steps":["trace[2129037010] 'process raft request' (duration: 101.974625ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T20:07:25.344691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.894584ms","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-07-25T20:07:25.344745Z","caller":"traceutil/trace.go:171","msg":"trace[1272611909] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1702; }","duration":"102.015578ms","start":"2026-07-25T20:07:25.242716Z","end":"2026-07-25T20:07:25.344732Z","steps":["trace[1272611909] 'agreement among raft nodes before linearized reading' (duration: 101.731741ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T20:07:25.529716Z","caller":"traceutil/trace.go:171","msg":"trace[957869803] transaction","detail":"{read_only:false; response_revision:1704; number_of_response:1; }","duration":"179.800741ms","start":"2026-07-25T20:07:25.349853Z","end":"2026-07-25T20:07:25.529654Z","steps":["trace[957869803] 'process raft request' (duration: 179.692889ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T20:07:25.529746Z","caller":"traceutil/trace.go:171","msg":"trace[1379725863] transaction","detail":"{read_only:false; response_revision:1705; number_of_response:1; }","duration":"179.585045ms","start":"2026-07-25T20:07:25.350106Z","end":"2026-07-25T20:07:25.529691Z","steps":["trace[1379725863] 'process raft request' (duration: 179.491823ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T20:07:25.529732Z","caller":"traceutil/trace.go:171","msg":"trace[466142575] transaction","detail":"{read_only:false; response_revision:1703; number_of_response:1; }","duration":"180.332615ms","start":"2026-07-25T20:07:25.349301Z","end":"2026-07-25T20:07:25.529633Z","steps":["trace[466142575] 'process raft request' (duration: 91.541627ms)","trace[466142575] 'compare' (duration: 88.568732ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T20:07:25.529952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.667798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-25T20:07:25.53003Z","caller":"traceutil/trace.go:171","msg":"trace[1722094078] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1705; }","duration":"105.77642ms","start":"2026-07-25T20:07:25.424242Z","end":"2026-07-25T20:07:25.530019Z","steps":["trace[1722094078] 'agreement among raft nodes before linearized reading' (duration: 105.516084ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T20:07:25.52971Z","caller":"traceutil/trace.go:171","msg":"trace[1419080893] linearizableReadLoop","detail":"{readStateIndex:1773; appliedIndex:1770; }","duration":"105.380441ms","start":"2026-07-25T20:07:25.424263Z","end":"2026-07-25T20:07:25.529643Z","steps":["trace[1419080893] 'read index received' (duration: 16.683425ms)","trace[1419080893] 'applied index is now lower than readState.Index' (duration: 88.696176ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T20:07:25.53059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.463743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-25T20:07:25.530663Z","caller":"traceutil/trace.go:171","msg":"trace[658040905] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1705; }","duration":"103.562886ms","start":"2026-07-25T20:07:25.427088Z","end":"2026-07-25T20:07:25.530651Z","steps":["trace[658040905] 'agreement among raft nodes before linearized reading' (duration: 103.383801ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T20:07:43.932841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.54305ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-25T20:07:43.933065Z","caller":"traceutil/trace.go:171","msg":"trace[1788127675] linearizableReadLoop","detail":"{readStateIndex:1861; appliedIndex:1858; }","duration":"112.51995ms","start":"2026-07-25T20:07:43.820527Z","end":"2026-07-25T20:07:43.933047Z","steps":["trace[1788127675] 'read index received' (duration: 62.022µs)","trace[1788127675] 'applied index is now lower than readState.Index' (duration: 112.456588ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T20:07:43.933223Z","caller":"traceutil/trace.go:171","msg":"trace[1547543427] transaction","detail":"{read_only:false; response_revision:1789; number_of_response:1; }","duration":"176.77157ms","start":"2026-07-25T20:07:43.75643Z","end":"2026-07-25T20:07:43.933202Z","steps":["trace[1547543427] 'process raft request' (duration: 176.502283ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T20:07:43.93332Z","caller":"traceutil/trace.go:171","msg":"trace[15617660] transaction","detail":"{read_only:false; response_revision:1788; number_of_response:1; }","duration":"176.972874ms","start":"2026-07-25T20:07:43.756322Z","end":"2026-07-25T20:07:43.933295Z","steps":["trace[15617660] 'process raft request' (duration: 57.889631ms)","trace[15617660] 'compare' (duration: 118.272523ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T20:07:43.933431Z","caller":"traceutil/trace.go:171","msg":"trace[1811209753] transaction","detail":"{read_only:false; response_revision:1790; number_of_response:1; }","duration":"176.601786ms","start":"2026-07-25T20:07:43.756823Z","end":"2026-07-25T20:07:43.933425Z","steps":["trace[1811209753] 'process raft request' (duration: 176.171065ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T20:07:43.933835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.310819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-25T20:07:43.933887Z","caller":"traceutil/trace.go:171","msg":"trace[2044354175] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:1790; }","duration":"113.397892ms","start":"2026-07-25T20:07:43.820474Z","end":"2026-07-25T20:07:43.933872Z","steps":["trace[2044354175] 'agreement among raft nodes before linearized reading' (duration: 112.623903ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T20:07:45.603493Z","caller":"traceutil/trace.go:171","msg":"trace[1630288922] transaction","detail":"{read_only:false; response_revision:1794; number_of_response:1; }","duration":"104.162383ms","start":"2026-07-25T20:07:45.499279Z","end":"2026-07-25T20:07:45.603442Z","steps":["trace[1630288922] 'process raft request' (duration: 103.921947ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T20:07:52.102338Z","caller":"traceutil/trace.go:171","msg":"trace[624106049] linearizableReadLoop","detail":"{readStateIndex:1899; appliedIndex:1897; }","duration":"116.094559ms","start":"2026-07-25T20:07:51.986217Z","end":"2026-07-25T20:07:52.102311Z","steps":["trace[624106049] 'read index received' (duration: 24.973382ms)","trace[624106049] 'applied index is now lower than readState.Index' (duration: 91.119737ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T20:07:52.102417Z","caller":"traceutil/trace.go:171","msg":"trace[1594733026] transaction","detail":"{read_only:false; response_revision:1826; number_of_response:1; }","duration":"117.29929ms","start":"2026-07-25T20:07:51.98508Z","end":"2026-07-25T20:07:52.102379Z","steps":["trace[1594733026] 'process raft request' (duration: 117.067954ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T20:07:52.102726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.469378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-07-25T20:07:52.1029Z","caller":"traceutil/trace.go:171","msg":"trace[2030063091] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1826; }","duration":"116.678435ms","start":"2026-07-25T20:07:51.986195Z","end":"2026-07-25T20:07:52.102873Z","steps":["trace[2030063091] 'agreement among raft nodes before linearized reading' (duration: 116.248383ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T20:07:59.972227Z","caller":"traceutil/trace.go:171","msg":"trace[628663981] transaction","detail":"{read_only:false; response_revision:1859; number_of_response:1; }","duration":"110.193274ms","start":"2026-07-25T20:07:59.862001Z","end":"2026-07-25T20:07:59.972195Z","steps":["trace[628663981] 'process raft request' (duration: 110.04661ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T20:08:00.148065Z","caller":"traceutil/trace.go:171","msg":"trace[1764019598] linearizableReadLoop","detail":"{readStateIndex:1935; appliedIndex:1934; }","duration":"129.346759ms","start":"2026-07-25T20:08:00.018633Z","end":"2026-07-25T20:08:00.147979Z","steps":["trace[1764019598] 'read index received' (duration: 129.047522ms)","trace[1764019598] 'applied index is now lower than readState.Index' (duration: 298.337µs)"],"step_count":2} {"level":"info","ts":"2026-07-25T20:08:00.148153Z","caller":"traceutil/trace.go:171","msg":"trace[576070666] transaction","detail":"{read_only:false; response_revision:1860; number_of_response:1; }","duration":"144.839815ms","start":"2026-07-25T20:08:00.003246Z","end":"2026-07-25T20:08:00.148085Z","steps":["trace[576070666] 'process raft request' (duration: 144.542928ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T20:08:00.148399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.74246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-07-25T20:08:00.148381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.890045ms","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-07-25T20:08:00.148467Z","caller":"traceutil/trace.go:171","msg":"trace[719135779] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1860; }","duration":"129.882794ms","start":"2026-07-25T20:08:00.018564Z","end":"2026-07-25T20:08:00.148447Z","steps":["trace[719135779] 'agreement among raft nodes before linearized reading' (duration: 129.649547ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T20:08:00.148477Z","caller":"traceutil/trace.go:171","msg":"trace[906544237] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1860; }","duration":"104.02503ms","start":"2026-07-25T20:08:00.044431Z","end":"2026-07-25T20:08:00.148456Z","steps":["trace[906544237] 'agreement among raft nodes before linearized reading' (duration: 103.806334ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T20:08:00.271307Z","caller":"traceutil/trace.go:171","msg":"trace[640322435] transaction","detail":"{read_only:false; response_revision:1861; number_of_response:1; }","duration":"115.804642ms","start":"2026-07-25T20:08:00.15547Z","end":"2026-07-25T20:08:00.271275Z","steps":["trace[640322435] 'process raft request' (duration: 43.961335ms)","trace[640322435] 'compare' (duration: 71.642472ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T20:08:00.271671Z","caller":"traceutil/trace.go:171","msg":"trace[1628980425] transaction","detail":"{read_only:false; response_revision:1862; number_of_response:1; }","duration":"115.911344ms","start":"2026-07-25T20:08:00.155743Z","end":"2026-07-25T20:08:00.271655Z","steps":["trace[1628980425] 'process raft request' (duration: 115.486964ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T20:08:00.2718Z","caller":"traceutil/trace.go:171","msg":"trace[671582555] linearizableReadLoop","detail":"{readStateIndex:1938; appliedIndex:1935; }","duration":"105.921267ms","start":"2026-07-25T20:08:00.165866Z","end":"2026-07-25T20:08:00.271787Z","steps":["trace[671582555] 'read index received' (duration: 33.572016ms)","trace[671582555] 'applied index is now lower than readState.Index' (duration: 72.348321ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T20:08:00.272014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.116141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-25T20:08:00.272054Z","caller":"traceutil/trace.go:171","msg":"trace[1214212274] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1863; }","duration":"106.196353ms","start":"2026-07-25T20:08:00.165848Z","end":"2026-07-25T20:08:00.272045Z","steps":["trace[1214212274] 'agreement among raft nodes before linearized reading' (duration: 105.996178ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T20:08:00.272207Z","caller":"traceutil/trace.go:171","msg":"trace[1925755220] transaction","detail":"{read_only:false; response_revision:1863; number_of_response:1; }","duration":"116.347997ms","start":"2026-07-25T20:08:00.155846Z","end":"2026-07-25T20:08:00.272194Z","steps":["trace[1925755220] 'process raft request' (duration: 115.579477ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T20:10:51.895841Z","caller":"traceutil/trace.go:171","msg":"trace[1141251790] transaction","detail":"{read_only:false; response_revision:2646; number_of_response:1; }","duration":"169.780279ms","start":"2026-07-25T20:10:51.726033Z","end":"2026-07-25T20:10:51.895814Z","steps":["trace[1141251790] 'process raft request' (duration: 91.638402ms)","trace[1141251790] 'compare' (duration: 78.043394ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T20:10:52.716992Z","caller":"traceutil/trace.go:171","msg":"trace[888347472] linearizableReadLoop","detail":"{readStateIndex:2757; appliedIndex:2756; }","duration":"100.670108ms","start":"2026-07-25T20:10:52.616291Z","end":"2026-07-25T20:10:52.716961Z","steps":["trace[888347472] 'read index received' (duration: 100.436682ms)","trace[888347472] 'applied index is now lower than readState.Index' (duration: 232.296µs)"],"step_count":2} {"level":"warn","ts":"2026-07-25T20:10:52.71717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.853662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T20:10:52.717208Z","caller":"traceutil/trace.go:171","msg":"trace[524930355] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2648; }","duration":"100.962895ms","start":"2026-07-25T20:10:52.616235Z","end":"2026-07-25T20:10:52.717198Z","steps":["trace[524930355] 'agreement among raft nodes before linearized reading' (duration: 100.835842ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T20:10:52.71762Z","caller":"traceutil/trace.go:171","msg":"trace[1911549698] transaction","detail":"{read_only:false; response_revision:2648; number_of_response:1; }","duration":"200.928955ms","start":"2026-07-25T20:10:52.516669Z","end":"2026-07-25T20:10:52.717598Z","steps":["trace[1911549698] 'process raft request' (duration: 200.136465ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T20:10:54.00309Z","caller":"traceutil/trace.go:171","msg":"trace[307780573] linearizableReadLoop","detail":"{readStateIndex:2763; appliedIndex:2762; }","duration":"201.377956ms","start":"2026-07-25T20:10:53.80169Z","end":"2026-07-25T20:10:54.003068Z","steps":["trace[307780573] 'read index received' (duration: 201.302074ms)","trace[307780573] 'applied index is now lower than readState.Index' (duration: 74.602µs)"],"step_count":2} {"level":"info","ts":"2026-07-25T20:10:54.003184Z","caller":"traceutil/trace.go:171","msg":"trace[1422201263] transaction","detail":"{read_only:false; response_revision:2654; number_of_response:1; }","duration":"273.820741ms","start":"2026-07-25T20:10:53.729336Z","end":"2026-07-25T20:10:54.003157Z","steps":["trace[1422201263] 'process raft request' (duration: 273.565445ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T20:10:54.003333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.631751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-07-25T20:10:54.003396Z","caller":"traceutil/trace.go:171","msg":"trace[1032625595] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2654; }","duration":"201.757715ms","start":"2026-07-25T20:10:53.801622Z","end":"2026-07-25T20:10:54.003379Z","steps":["trace[1032625595] 'agreement among raft nodes before linearized reading' (duration: 201.580631ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T20:10:54.113508Z","caller":"traceutil/trace.go:171","msg":"trace[241881961] linearizableReadLoop","detail":"{readStateIndex:2764; appliedIndex:2763; }","duration":"110.316588ms","start":"2026-07-25T20:10:54.003172Z","end":"2026-07-25T20:10:54.113488Z","steps":["trace[241881961] 'read index received' (duration: 77.350918ms)","trace[241881961] 'applied index is now lower than readState.Index' (duration: 32.96468ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T20:10:54.113711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.975109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-07-25T20:10:54.113754Z","caller":"traceutil/trace.go:171","msg":"trace[2108154282] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2655; }","duration":"214.065711ms","start":"2026-07-25T20:10:53.899678Z","end":"2026-07-25T20:10:54.113744Z","steps":["trace[2108154282] 'agreement among raft nodes before linearized reading' (duration: 213.901177ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T20:10:54.113592Z","caller":"traceutil/trace.go:171","msg":"trace[1054424412] transaction","detail":"{read_only:false; response_revision:2655; number_of_response:1; }","duration":"154.826176ms","start":"2026-07-25T20:10:53.958733Z","end":"2026-07-25T20:10:54.113559Z","steps":["trace[1054424412] 'process raft request' (duration: 121.779754ms)","trace[1054424412] 'compare' (duration: 32.882378ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T20:10:54.193073Z","caller":"traceutil/trace.go:171","msg":"trace[863340960] transaction","detail":"{read_only:false; response_revision:2656; number_of_response:1; }","duration":"185.122221ms","start":"2026-07-25T20:10:54.007931Z","end":"2026-07-25T20:10:54.193053Z","steps":["trace[863340960] 'process raft request' (duration: 185.015788ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T20:10:54.222694Z","caller":"traceutil/trace.go:171","msg":"trace[1709689470] transaction","detail":"{read_only:false; response_revision:2657; number_of_response:1; }","duration":"104.991325ms","start":"2026-07-25T20:10:54.117684Z","end":"2026-07-25T20:10:54.222676Z","steps":["trace[1709689470] 'process raft request' (duration: 102.910433ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T20:11:38.709751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.280666ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-25T20:11:38.709872Z","caller":"traceutil/trace.go:171","msg":"trace[890107770] linearizableReadLoop","detail":"{readStateIndex:2977; appliedIndex:2976; }","duration":"203.839647ms","start":"2026-07-25T20:11:38.506014Z","end":"2026-07-25T20:11:38.709854Z","steps":["trace[890107770] 'read index received' (duration: 51.267284ms)","trace[890107770] 'applied index is now lower than readState.Index' (duration: 152.570593ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T20:11:38.709891Z","caller":"traceutil/trace.go:171","msg":"trace[175595653] transaction","detail":"{read_only:false; response_revision:2859; number_of_response:1; }","duration":"283.407385ms","start":"2026-07-25T20:11:38.426447Z","end":"2026-07-25T20:11:38.709854Z","steps":["trace[175595653] 'process raft request' (duration: 130.881233ms)","trace[175595653] 'compare' (duration: 152.143593ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T20:11:38.710166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.157715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-07-25T20:11:38.710228Z","caller":"traceutil/trace.go:171","msg":"trace[717695381] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2859; }","duration":"204.268738ms","start":"2026-07-25T20:11:38.505944Z","end":"2026-07-25T20:11:38.710213Z","steps":["trace[717695381] 'agreement among raft nodes before linearized reading' (duration: 204.041002ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T20:11:38.710466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.245658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-07-25T20:11:38.71046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.511397ms","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:533"} {"level":"info","ts":"2026-07-25T20:11:38.710533Z","caller":"traceutil/trace.go:171","msg":"trace[915865754] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2859; }","duration":"163.322379ms","start":"2026-07-25T20:11:38.547187Z","end":"2026-07-25T20:11:38.71051Z","steps":["trace[915865754] 'agreement among raft nodes before linearized reading' (duration: 163.183426ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T20:11:38.710539Z","caller":"traceutil/trace.go:171","msg":"trace[1103035945] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2859; }","duration":"182.620789ms","start":"2026-07-25T20:11:38.527885Z","end":"2026-07-25T20:11:38.710506Z","steps":["trace[1103035945] 'agreement among raft nodes before linearized reading' (duration: 182.419774ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T20:11:38.710565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.442637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-07-25T20:11:38.710639Z","caller":"traceutil/trace.go:171","msg":"trace[459130208] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2859; }","duration":"172.529009ms","start":"2026-07-25T20:11:38.538093Z","end":"2026-07-25T20:11:38.710622Z","steps":["trace[459130208] 'agreement among raft nodes before linearized reading' (duration: 172.316534ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T20:11:38.959552Z","caller":"traceutil/trace.go:171","msg":"trace[319374131] transaction","detail":"{read_only:false; response_revision:2861; number_of_response:1; }","duration":"243.829051ms","start":"2026-07-25T20:11:38.715688Z","end":"2026-07-25T20:11:38.959517Z","steps":["trace[319374131] 'process raft request' (duration: 188.120186ms)","trace[319374131] 'compare' (duration: 55.289044ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T20:11:38.959975Z","caller":"traceutil/trace.go:171","msg":"trace[934713679] transaction","detail":"{read_only:false; response_revision:2862; number_of_response:1; }","duration":"244.107368ms","start":"2026-07-25T20:11:38.715821Z","end":"2026-07-25T20:11:38.959928Z","steps":["trace[934713679] 'process raft request' (duration: 243.586755ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T20:11:38.959983Z","caller":"traceutil/trace.go:171","msg":"trace[1226262779] transaction","detail":"{read_only:false; response_revision:2863; number_of_response:1; }","duration":"242.963059ms","start":"2026-07-25T20:11:38.716983Z","end":"2026-07-25T20:11:38.959946Z","steps":["trace[1226262779] 'process raft request' (duration: 242.680882ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T20:11:39.754233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.216791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T20:11:39.754349Z","caller":"traceutil/trace.go:171","msg":"trace[2102483100] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2864; }","duration":"139.541189ms","start":"2026-07-25T20:11:39.614781Z","end":"2026-07-25T20:11:39.754322Z","steps":["trace[2102483100] 'range keys from in-memory index tree' (duration: 139.027796ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T20:11:45.471794Z","caller":"traceutil/trace.go:171","msg":"trace[1487254745] linearizableReadLoop","detail":"{readStateIndex:3011; appliedIndex:3010; }","duration":"137.184319ms","start":"2026-07-25T20:11:45.334583Z","end":"2026-07-25T20:11:45.471768Z","steps":["trace[1487254745] 'read index received' (duration: 136.975533ms)","trace[1487254745] 'applied index is now lower than readState.Index' (duration: 207.146µs)"],"step_count":2} {"level":"warn","ts":"2026-07-25T20:11:45.472036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.422496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T20:11:45.472094Z","caller":"traceutil/trace.go:171","msg":"trace[1708030791] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:2891; }","duration":"137.550719ms","start":"2026-07-25T20:11:45.334526Z","end":"2026-07-25T20:11:45.472077Z","steps":["trace[1708030791] 'agreement among raft nodes before linearized reading' (duration: 137.345024ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T20:11:45.472246Z","caller":"traceutil/trace.go:171","msg":"trace[171303271] transaction","detail":"{read_only:false; response_revision:2891; number_of_response:1; }","duration":"287.546177ms","start":"2026-07-25T20:11:45.184671Z","end":"2026-07-25T20:11:45.472217Z","steps":["trace[171303271] 'process raft request' (duration: 286.918001ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T20:11:46.376935Z","caller":"traceutil/trace.go:171","msg":"trace[818618040] transaction","detail":"{read_only:false; response_revision:2894; number_of_response:1; }","duration":"103.597455ms","start":"2026-07-25T20:11:46.273305Z","end":"2026-07-25T20:11:46.376902Z","steps":["trace[818618040] 'process raft request' (duration: 103.39604ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T20:11:46.695791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.668361ms","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-07-25T20:11:46.696522Z","caller":"traceutil/trace.go:171","msg":"trace[107331368] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2894; }","duration":"317.441601ms","start":"2026-07-25T20:11:46.379051Z","end":"2026-07-25T20:11:46.696493Z","steps":["trace[107331368] 'range keys from in-memory index tree' (duration: 316.437906ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T20:11:46.696631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T20:11:46.379027Z","time spent":"317.572905ms","remote":"127.0.0.1:50622","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-07-25T20:11:48.100142Z","caller":"traceutil/trace.go:171","msg":"trace[1633291830] linearizableReadLoop","detail":"{readStateIndex:3020; appliedIndex:3019; }","duration":"123.179181ms","start":"2026-07-25T20:11:47.976937Z","end":"2026-07-25T20:11:48.100117Z","steps":["trace[1633291830] 'read index received' (duration: 123.061669ms)","trace[1633291830] 'applied index is now lower than readState.Index' (duration: 116.342µs)"],"step_count":2} {"level":"info","ts":"2026-07-25T20:11:48.100249Z","caller":"traceutil/trace.go:171","msg":"trace[190419093] transaction","detail":"{read_only:false; response_revision:2900; number_of_response:1; }","duration":"160.421067ms","start":"2026-07-25T20:11:47.939798Z","end":"2026-07-25T20:11:48.100219Z","steps":["trace[190419093] 'process raft request' (duration: 160.13431ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T20:11:48.100433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.476319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-07-25T20:11:48.100469Z","caller":"traceutil/trace.go:171","msg":"trace[578700612] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2900; }","duration":"123.584702ms","start":"2026-07-25T20:11:47.976874Z","end":"2026-07-25T20:11:48.100459Z","steps":["trace[578700612] 'agreement among raft nodes before linearized reading' (duration: 123.381637ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T20:11:49.108177Z","caller":"traceutil/trace.go:171","msg":"trace[463665905] transaction","detail":"{read_only:false; response_revision:2905; number_of_response:1; }","duration":"127.52044ms","start":"2026-07-25T20:11:48.980632Z","end":"2026-07-25T20:11:49.108153Z","steps":["trace[463665905] 'process raft request' (duration: 127.384427ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T20:11:50.575639Z","caller":"traceutil/trace.go:171","msg":"trace[815363930] transaction","detail":"{read_only:false; response_revision:2911; number_of_response:1; }","duration":"588.009385ms","start":"2026-07-25T20:11:49.987598Z","end":"2026-07-25T20:11:50.575607Z","steps":["trace[815363930] 'process raft request' (duration: 587.850391ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T20:11:50.575791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T20:11:49.987573Z","time spent":"588.141078ms","remote":"127.0.0.1:50622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T20:11:50.746189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.59503ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-07-25T20:11:50.746325Z","caller":"traceutil/trace.go:171","msg":"trace[2141850742] linearizableReadLoop","detail":"{readStateIndex:3032; appliedIndex:3030; }","duration":"634.128212ms","start":"2026-07-25T20:11:50.112174Z","end":"2026-07-25T20:11:50.746302Z","steps":["trace[2141850742] 'read index received' (duration: 463.378768ms)","trace[2141850742] 'applied index is now lower than readState.Index' (duration: 170.747394ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T20:11:50.746679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"634.47843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-07-25T20:11:50.746739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.053367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T20:11:50.746779Z","caller":"traceutil/trace.go:171","msg":"trace[1028809574] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2911; }","duration":"634.646326ms","start":"2026-07-25T20:11:50.112111Z","end":"2026-07-25T20:11:50.746757Z","steps":["trace[1028809574] 'agreement among raft nodes before linearized reading' (duration: 634.308137ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T20:11:50.746775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.457545ms","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-07-25T20:11:50.746848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T20:11:50.112088Z","time spent":"634.750738ms","remote":"127.0.0.1:50622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-25T20:11:50.746854Z","caller":"traceutil/trace.go:171","msg":"trace[1093774629] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2911; }","duration":"355.548697ms","start":"2026-07-25T20:11:50.391292Z","end":"2026-07-25T20:11:50.746841Z","steps":["trace[1093774629] 'agreement among raft nodes before linearized reading' (duration: 355.30083ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T20:11:50.746858Z","caller":"traceutil/trace.go:171","msg":"trace[1368451515] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2911; }","duration":"133.221652ms","start":"2026-07-25T20:11:50.613614Z","end":"2026-07-25T20:11:50.746836Z","steps":["trace[1368451515] 'agreement among raft nodes before linearized reading' (duration: 133.010336ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T20:11:50.746896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T20:11:50.391231Z","time spent":"355.65574ms","remote":"127.0.0.1:50622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-25T20:11:53.276149Z","caller":"traceutil/trace.go:171","msg":"trace[1843088974] transaction","detail":"{read_only:false; response_revision:2922; number_of_response:1; }","duration":"167.433771ms","start":"2026-07-25T20:11:53.108693Z","end":"2026-07-25T20:11:53.276127Z","steps":["trace[1843088974] 'process raft request' (duration: 166.939959ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T20:11:53.425891Z","caller":"traceutil/trace.go:171","msg":"trace[448585152] transaction","detail":"{read_only:false; response_revision:2923; number_of_response:1; }","duration":"145.366663ms","start":"2026-07-25T20:11:53.280498Z","end":"2026-07-25T20:11:53.425864Z","steps":["trace[448585152] 'process raft request' (duration: 73.068936ms)","trace[448585152] 'compare' (duration: 72.111443ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T20:11:58.117103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.541108ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-25T20:11:58.117558Z","caller":"traceutil/trace.go:171","msg":"trace[2055098812] transaction","detail":"{read_only:false; response_revision:2945; number_of_response:1; }","duration":"185.01904ms","start":"2026-07-25T20:11:57.932229Z","end":"2026-07-25T20:11:58.117249Z","steps":["trace[2055098812] 'process raft request' (duration: 82.189223ms)","trace[2055098812] 'compare' (duration: 102.321503ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T20:11:59.133341Z","caller":"traceutil/trace.go:171","msg":"trace[2073905935] linearizableReadLoop","detail":"{readStateIndex:3070; appliedIndex:3069; }","duration":"159.576116ms","start":"2026-07-25T20:11:58.973738Z","end":"2026-07-25T20:11:59.133314Z","steps":["trace[2073905935] 'read index received' (duration: 158.750826ms)","trace[2073905935] 'applied index is now lower than readState.Index' (duration: 824.21µs)"],"step_count":2} {"level":"info","ts":"2026-07-25T20:11:59.133377Z","caller":"traceutil/trace.go:171","msg":"trace[381963081] transaction","detail":"{read_only:false; response_revision:2948; number_of_response:1; }","duration":"164.119319ms","start":"2026-07-25T20:11:58.969222Z","end":"2026-07-25T20:11:59.133341Z","steps":["trace[381963081] 'process raft request' (duration: 163.37166ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T20:11:59.133591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.825773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-07-25T20:11:59.133626Z","caller":"traceutil/trace.go:171","msg":"trace[1987675466] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2948; }","duration":"159.925946ms","start":"2026-07-25T20:11:58.97369Z","end":"2026-07-25T20:11:59.133616Z","steps":["trace[1987675466] 'agreement among raft nodes before linearized reading' (duration: 159.757321ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T20:11:59.133763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.966286ms","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-07-25T20:11:59.133863Z","caller":"traceutil/trace.go:171","msg":"trace[232048830] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2948; }","duration":"160.12021ms","start":"2026-07-25T20:11:58.973718Z","end":"2026-07-25T20:11:59.133838Z","steps":["trace[232048830] 'agreement among raft nodes before linearized reading' (duration: 159.866694ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T20:11:59.335459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.213807ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-25T20:11:59.335582Z","caller":"traceutil/trace.go:171","msg":"trace[1017442297] transaction","detail":"{read_only:false; response_revision:2949; number_of_response:1; }","duration":"194.370391ms","start":"2026-07-25T20:11:59.141181Z","end":"2026-07-25T20:11:59.335552Z","steps":["trace[1017442297] 'process raft request' (duration: 69.950179ms)","trace[1017442297] 'compare' (duration: 124.085934ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T20:11:59.389867Z","caller":"traceutil/trace.go:171","msg":"trace[2071651186] transaction","detail":"{read_only:false; response_revision:2950; number_of_response:1; }","duration":"248.558208ms","start":"2026-07-25T20:11:59.14129Z","end":"2026-07-25T20:11:59.389848Z","steps":["trace[2071651186] 'process raft request' (duration: 248.427945ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T20:11:59.390045Z","caller":"traceutil/trace.go:171","msg":"trace[29339704] transaction","detail":"{read_only:false; response_revision:2951; number_of_response:1; }","duration":"248.665401ms","start":"2026-07-25T20:11:59.141357Z","end":"2026-07-25T20:11:59.390023Z","steps":["trace[29339704] 'process raft request' (duration: 248.465406ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T20:12:00.24586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.216573ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-07-25T20:12:00.246004Z","caller":"traceutil/trace.go:171","msg":"trace[221586638] linearizableReadLoop","detail":"{readStateIndex:3078; appliedIndex:3077; }","duration":"122.497774ms","start":"2026-07-25T20:12:00.123482Z","end":"2026-07-25T20:12:00.245979Z","steps":["trace[221586638] 'read index received' (duration: 3.149108ms)","trace[221586638] 'applied index is now lower than readState.Index' (duration: 119.346686ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T20:12:00.246197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.706229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-07-25T20:12:00.246229Z","caller":"traceutil/trace.go:171","msg":"trace[1050253245] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2955; }","duration":"122.788892ms","start":"2026-07-25T20:12:00.123429Z","end":"2026-07-25T20:12:00.246218Z","steps":["trace[1050253245] 'agreement among raft nodes before linearized reading' (duration: 122.608567ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T20:12:00.383271Z","caller":"traceutil/trace.go:171","msg":"trace[1717278429] linearizableReadLoop","detail":"{readStateIndex:3079; appliedIndex:3078; }","duration":"120.503995ms","start":"2026-07-25T20:12:00.262741Z","end":"2026-07-25T20:12:00.383245Z","steps":["trace[1717278429] 'read index received' (duration: 120.394262ms)","trace[1717278429] 'applied index is now lower than readState.Index' (duration: 107.933µs)"],"step_count":2} {"level":"warn","ts":"2026-07-25T20:12:00.383455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.69044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-25T20:12:00.383431Z","caller":"traceutil/trace.go:171","msg":"trace[96159341] transaction","detail":"{read_only:false; response_revision:2956; number_of_response:1; }","duration":"132.77664ms","start":"2026-07-25T20:12:00.250609Z","end":"2026-07-25T20:12:00.383386Z","steps":["trace[96159341] 'process raft request' (duration: 132.464642ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T20:12:00.383496Z","caller":"traceutil/trace.go:171","msg":"trace[1158524660] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:2956; }","duration":"120.783543ms","start":"2026-07-25T20:12:00.262696Z","end":"2026-07-25T20:12:00.38348Z","steps":["trace[1158524660] 'agreement among raft nodes before linearized reading' (duration: 120.654629ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T20:12:01.654787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.338558ms","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-07-25T20:12:01.654888Z","caller":"traceutil/trace.go:171","msg":"trace[157024999] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2957; }","duration":"315.491821ms","start":"2026-07-25T20:12:01.339377Z","end":"2026-07-25T20:12:01.654869Z","steps":["trace[157024999] 'range keys from in-memory index tree' (duration: 315.154633ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T20:12:01.654934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T20:12:01.339355Z","time spent":"315.569374ms","remote":"127.0.0.1:50622","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-07-25T20:12:01.654988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.345606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-07-25T20:12:01.65486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"515.062713ms","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-07-25T20:12:01.655041Z","caller":"traceutil/trace.go:171","msg":"trace[1928304502] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2957; }","duration":"261.424098ms","start":"2026-07-25T20:12:01.393596Z","end":"2026-07-25T20:12:01.65502Z","steps":["trace[1928304502] 'range keys from in-memory index tree' (duration: 261.219213ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T20:12:01.655078Z","caller":"traceutil/trace.go:171","msg":"trace[1395532372] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2957; }","duration":"515.3412ms","start":"2026-07-25T20:12:01.139699Z","end":"2026-07-25T20:12:01.65504Z","steps":["trace[1395532372] 'range keys from in-memory index tree' (duration: 514.896808ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T20:12:01.655181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T20:12:01.139678Z","time spent":"515.473013ms","remote":"127.0.0.1:50622","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-07-25T20:12:01.771584Z","caller":"traceutil/trace.go:171","msg":"trace[799731394] transaction","detail":"{read_only:false; response_revision:2958; number_of_response:1; }","duration":"112.33187ms","start":"2026-07-25T20:12:01.659221Z","end":"2026-07-25T20:12:01.771553Z","steps":["trace[799731394] 'process raft request' (duration: 112.140196ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T20:12:01.790545Z","caller":"traceutil/trace.go:171","msg":"trace[1224708575] transaction","detail":"{read_only:false; response_revision:2959; number_of_response:1; }","duration":"131.185209ms","start":"2026-07-25T20:12:01.65934Z","end":"2026-07-25T20:12:01.790525Z","steps":["trace[1224708575] 'process raft request' (duration: 130.924963ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T20:12:01.790544Z","caller":"traceutil/trace.go:171","msg":"trace[1385168785] transaction","detail":"{read_only:false; response_revision:2960; number_of_response:1; }","duration":"130.460302ms","start":"2026-07-25T20:12:01.660048Z","end":"2026-07-25T20:12:01.790509Z","steps":["trace[1385168785] 'process raft request' (duration: 130.40612ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T20:12:02.163066Z","caller":"traceutil/trace.go:171","msg":"trace[304908648] linearizableReadLoop","detail":"{readStateIndex:3085; appliedIndex:3084; }","duration":"127.021515ms","start":"2026-07-25T20:12:02.036027Z","end":"2026-07-25T20:12:02.163048Z","steps":["trace[304908648] 'read index received' (duration: 126.81018ms)","trace[304908648] 'applied index is now lower than readState.Index' (duration: 210.725µs)"],"step_count":2} {"level":"info","ts":"2026-07-25T20:12:02.163138Z","caller":"traceutil/trace.go:171","msg":"trace[1094615688] transaction","detail":"{read_only:false; response_revision:2962; number_of_response:1; }","duration":"175.94573ms","start":"2026-07-25T20:12:01.987141Z","end":"2026-07-25T20:12:02.163087Z","steps":["trace[1094615688] 'process raft request' (duration: 175.780486ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T20:12:02.163235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.190399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-07-25T20:12:02.163264Z","caller":"traceutil/trace.go:171","msg":"trace[1630384669] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2962; }","duration":"127.280061ms","start":"2026-07-25T20:12:02.035977Z","end":"2026-07-25T20:12:02.163257Z","steps":["trace[1630384669] 'agreement among raft nodes before linearized reading' (duration: 127.146628ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T20:12:04.340262Z","caller":"traceutil/trace.go:171","msg":"trace[1659367624] transaction","detail":"{read_only:false; response_revision:2972; number_of_response:1; }","duration":"109.266864ms","start":"2026-07-25T20:12:04.230963Z","end":"2026-07-25T20:12:04.34023Z","steps":["trace[1659367624] 'process raft request' (duration: 48.30131ms)","trace[1659367624] 'compare' (duration: 60.606555ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T20:12:06.113598Z","caller":"traceutil/trace.go:171","msg":"trace[688496011] linearizableReadLoop","detail":"{readStateIndex:3110; appliedIndex:3109; }","duration":"204.355525ms","start":"2026-07-25T20:12:05.909215Z","end":"2026-07-25T20:12:06.11357Z","steps":["trace[688496011] 'read index received' (duration: 204.15563ms)","trace[688496011] 'applied index is now lower than readState.Index' (duration: 198.695µs)"],"step_count":2} {"level":"info","ts":"2026-07-25T20:12:06.113736Z","caller":"traceutil/trace.go:171","msg":"trace[1410272988] transaction","detail":"{read_only:false; response_revision:2985; number_of_response:1; }","duration":"248.463361ms","start":"2026-07-25T20:12:05.865259Z","end":"2026-07-25T20:12:06.113722Z","steps":["trace[1410272988] 'process raft request' (duration: 248.042191ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T20:12:06.114011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.572866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-07-25T20:12:06.11407Z","caller":"traceutil/trace.go:171","msg":"trace[706322290] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2985; }","duration":"203.679509ms","start":"2026-07-25T20:12:05.910377Z","end":"2026-07-25T20:12:06.114056Z","steps":["trace[706322290] 'agreement among raft nodes before linearized reading' (duration: 203.431843ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T20:12:06.1144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.119944ms","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-07-25T20:12:06.114546Z","caller":"traceutil/trace.go:171","msg":"trace[1005584936] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2985; }","duration":"205.36886ms","start":"2026-07-25T20:12:05.909144Z","end":"2026-07-25T20:12:06.114513Z","steps":["trace[1005584936] 'agreement among raft nodes before linearized reading' (duration: 204.863018ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T20:12:06.340031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.791282ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-25T20:12:06.340411Z","caller":"traceutil/trace.go:171","msg":"trace[1101363171] transaction","detail":"{read_only:false; response_revision:2987; number_of_response:1; }","duration":"221.083901ms","start":"2026-07-25T20:12:06.119292Z","end":"2026-07-25T20:12:06.340376Z","steps":["trace[1101363171] 'process raft request' (duration: 76.706695ms)","trace[1101363171] 'compare' (duration: 143.637198ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T20:12:06.340459Z","caller":"traceutil/trace.go:171","msg":"trace[598032024] transaction","detail":"{read_only:false; response_revision:2989; number_of_response:1; }","duration":"144.195272ms","start":"2026-07-25T20:12:06.196236Z","end":"2026-07-25T20:12:06.340431Z","steps":["trace[598032024] 'process raft request' (duration: 144.13711ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T20:12:06.340532Z","caller":"traceutil/trace.go:171","msg":"trace[4768772] transaction","detail":"{read_only:false; response_revision:2988; number_of_response:1; }","duration":"220.381033ms","start":"2026-07-25T20:12:06.120119Z","end":"2026-07-25T20:12:06.3405Z","steps":["trace[4768772] 'process raft request' (duration: 220.067946ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T20:12:06.650283Z","caller":"traceutil/trace.go:171","msg":"trace[22278744] transaction","detail":"{read_only:false; response_revision:2991; number_of_response:1; }","duration":"155.526383ms","start":"2026-07-25T20:12:06.494731Z","end":"2026-07-25T20:12:06.650258Z","steps":["trace[22278744] 'process raft request' (duration: 155.137793ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T20:12:07.076897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.313911ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-25T20:12:07.077079Z","caller":"traceutil/trace.go:171","msg":"trace[404068548] transaction","detail":"{read_only:false; response_revision:2992; number_of_response:1; }","duration":"415.745486ms","start":"2026-07-25T20:12:06.661269Z","end":"2026-07-25T20:12:07.077014Z","steps":["trace[404068548] 'process raft request' (duration: 247.21131ms)","trace[404068548] 'compare' (duration: 168.177858ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T20:12:07.077169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T20:12:06.66124Z","time spent":"415.88518ms","remote":"127.0.0.1:50424","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":881,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T20:12:08.193089Z","caller":"traceutil/trace.go:171","msg":"trace[746982118] transaction","detail":"{read_only:false; response_revision:2993; number_of_response:1; }","duration":"330.13704ms","start":"2026-07-25T20:12:07.862914Z","end":"2026-07-25T20:12:08.193051Z","steps":["trace[746982118] 'process raft request' (duration: 329.814682ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T20:12:08.19332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T20:12:07.862877Z","time spent":"330.309154ms","remote":"127.0.0.1:50622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T20:12:08.610964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.87012ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-25T20:12:08.611172Z","caller":"traceutil/trace.go:171","msg":"trace[49179291] transaction","detail":"{read_only:false; response_revision:2995; number_of_response:1; }","duration":"263.66876ms","start":"2026-07-25T20:12:08.347488Z","end":"2026-07-25T20:12:08.611156Z","steps":["trace[49179291] 'process raft request' (duration: 263.590567ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T20:12:08.611298Z","caller":"traceutil/trace.go:171","msg":"trace[552944213] transaction","detail":"{read_only:false; response_revision:2994; number_of_response:1; }","duration":"412.606399ms","start":"2026-07-25T20:12:08.198685Z","end":"2026-07-25T20:12:08.611292Z","steps":["trace[552944213] 'process raft request' (duration: 130.324437ms)","trace[552944213] 'compare' (duration: 281.737917ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T20:12:08.611329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T20:12:08.198656Z","time spent":"412.65413ms","remote":"127.0.0.1:50622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T20:12:08.611387Z","caller":"traceutil/trace.go:171","msg":"trace[1751949039] linearizableReadLoop","detail":"{readStateIndex:3119; appliedIndex:3118; }","duration":"265.343301ms","start":"2026-07-25T20:12:08.346036Z","end":"2026-07-25T20:12:08.611379Z","steps":["trace[1751949039] 'read index received' (duration: 49.391µs)","trace[1751949039] 'applied index is now lower than readState.Index' (duration: 265.29294ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T20:12:08.61153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.508784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-07-25T20:12:08.611544Z","caller":"traceutil/trace.go:171","msg":"trace[1357334479] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2995; }","duration":"265.562186ms","start":"2026-07-25T20:12:08.345976Z","end":"2026-07-25T20:12:08.611538Z","steps":["trace[1357334479] 'agreement among raft nodes before linearized reading' (duration: 265.467683ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T20:12:08.611621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.187703ms","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-07-25T20:12:08.611632Z","caller":"traceutil/trace.go:171","msg":"trace[1130806417] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2995; }","duration":"264.250174ms","start":"2026-07-25T20:12:08.347378Z","end":"2026-07-25T20:12:08.611629Z","steps":["trace[1130806417] 'agreement among raft nodes before linearized reading' (duration: 264.212493ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T20:12:08.611752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.52749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-07-25T20:12:08.611768Z","caller":"traceutil/trace.go:171","msg":"trace[1928675121] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2995; }","duration":"239.568231ms","start":"2026-07-25T20:12:08.372196Z","end":"2026-07-25T20:12:08.611764Z","steps":["trace[1928675121] 'agreement among raft nodes before linearized reading' (duration: 239.52763ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T20:12:08.611888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.351833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-07-25T20:12:08.611899Z","caller":"traceutil/trace.go:171","msg":"trace[1736710180] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2995; }","duration":"249.413424ms","start":"2026-07-25T20:12:08.362482Z","end":"2026-07-25T20:12:08.611896Z","steps":["trace[1736710180] 'agreement among raft nodes before linearized reading' (duration: 249.373743ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T20:12:09.010627Z","caller":"traceutil/trace.go:171","msg":"trace[1454857743] transaction","detail":"{read_only:false; response_revision:2996; number_of_response:1; }","duration":"394.054608ms","start":"2026-07-25T20:12:08.616537Z","end":"2026-07-25T20:12:09.010592Z","steps":["trace[1454857743] 'process raft request' (duration: 393.75622ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T20:12:09.010929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T20:12:08.61651Z","time spent":"394.223842ms","remote":"127.0.0.1:50622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T20:12:09.47871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.192625ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-25T20:12:09.479033Z","caller":"traceutil/trace.go:171","msg":"trace[684982838] linearizableReadLoop","detail":"{readStateIndex:3125; appliedIndex:3120; }","duration":"823.695958ms","start":"2026-07-25T20:12:08.655315Z","end":"2026-07-25T20:12:09.479011Z","steps":["trace[684982838] 'read index received' (duration: 355.195093ms)","trace[684982838] 'applied index is now lower than readState.Index' (duration: 468.499415ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T20:12:09.479012Z","caller":"traceutil/trace.go:171","msg":"trace[97083618] transaction","detail":"{read_only:false; response_revision:2997; number_of_response:1; }","duration":"860.534793ms","start":"2026-07-25T20:12:08.61843Z","end":"2026-07-25T20:12:09.478965Z","steps":["trace[97083618] 'process raft request' (duration: 617.966799ms)","trace[97083618] 'compare' (duration: 241.932459ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T20:12:09.479167Z","caller":"traceutil/trace.go:171","msg":"trace[223634341] transaction","detail":"{read_only:false; response_revision:3000; number_of_response:1; }","duration":"858.79492ms","start":"2026-07-25T20:12:08.62036Z","end":"2026-07-25T20:12:09.479155Z","steps":["trace[223634341] 'process raft request' (duration: 858.605055ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T20:12:09.479208Z","caller":"traceutil/trace.go:171","msg":"trace[798284767] transaction","detail":"{read_only:false; response_revision:2999; number_of_response:1; }","duration":"860.597225ms","start":"2026-07-25T20:12:08.618563Z","end":"2026-07-25T20:12:09.479161Z","steps":["trace[798284767] 'process raft request' (duration: 860.363859ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T20:12:09.479242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T20:12:08.618413Z","time spent":"860.695857ms","remote":"127.0.0.1:50622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T20:12:09.479399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"824.097298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-07-25T20:12:09.479394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T20:12:08.618557Z","time spent":"860.743839ms","remote":"127.0.0.1:50622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T20:12:09.479443Z","caller":"traceutil/trace.go:171","msg":"trace[1281277474] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3000; }","duration":"824.197851ms","start":"2026-07-25T20:12:08.655236Z","end":"2026-07-25T20:12:09.479434Z","steps":["trace[1281277474] 'agreement among raft nodes before linearized reading' (duration: 824.033687ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T20:12:09.479475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T20:12:08.6552Z","time spent":"824.268373ms","remote":"127.0.0.1:50622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-25T20:12:09.479242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T20:12:08.620346Z","time spent":"858.855601ms","remote":"127.0.0.1:50622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":511,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T20:12:09.479609Z","caller":"traceutil/trace.go:171","msg":"trace[58902810] transaction","detail":"{read_only:false; response_revision:2998; number_of_response:1; }","duration":"861.084938ms","start":"2026-07-25T20:12:08.618514Z","end":"2026-07-25T20:12:09.479599Z","steps":["trace[58902810] 'process raft request' (duration: 860.351409ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T20:12:09.479667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T20:12:08.618507Z","time spent":"861.130889ms","remote":"127.0.0.1:50622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T20:12:10.087441Z","caller":"traceutil/trace.go:171","msg":"trace[1558830925] linearizableReadLoop","detail":"{readStateIndex:3127; appliedIndex:3126; }","duration":"472.137376ms","start":"2026-07-25T20:12:09.615279Z","end":"2026-07-25T20:12:10.087417Z","steps":["trace[1558830925] 'read index received' (duration: 386.5077ms)","trace[1558830925] 'applied index is now lower than readState.Index' (duration: 85.628526ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T20:12:10.087635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.329392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T20:12:10.087706Z","caller":"traceutil/trace.go:171","msg":"trace[309674763] transaction","detail":"{read_only:false; response_revision:3002; number_of_response:1; }","duration":"601.889129ms","start":"2026-07-25T20:12:09.48578Z","end":"2026-07-25T20:12:10.087669Z","steps":["trace[309674763] 'process raft request' (duration: 516.011856ms)","trace[309674763] 'compare' (duration: 85.304468ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T20:12:10.087729Z","caller":"traceutil/trace.go:171","msg":"trace[439405260] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3002; }","duration":"472.471735ms","start":"2026-07-25T20:12:09.615236Z","end":"2026-07-25T20:12:10.087707Z","steps":["trace[439405260] 'agreement among raft nodes before linearized reading' (duration: 472.27677ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T20:12:10.087802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T20:12:09.615215Z","time spent":"472.568337ms","remote":"127.0.0.1:50336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-25T20:12:10.087917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T20:12:09.485741Z","time spent":"602.039593ms","remote":"127.0.0.1:50622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T20:12:10.180237Z","caller":"traceutil/trace.go:171","msg":"trace[1075652310] transaction","detail":"{read_only:false; response_revision:3003; number_of_response:1; }","duration":"309.672852ms","start":"2026-07-25T20:12:09.870544Z","end":"2026-07-25T20:12:10.180217Z","steps":["trace[1075652310] 'process raft request' (duration: 309.540739ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T20:12:10.180382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T20:12:09.870507Z","time spent":"309.794935ms","remote":"127.0.0.1:50622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T20:12:10.842162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.868519ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-07-25T20:12:10.842433Z","caller":"traceutil/trace.go:171","msg":"trace[951308900] transaction","detail":"{read_only:false; response_revision:3004; number_of_response:1; }","duration":"225.627435ms","start":"2026-07-25T20:12:10.616787Z","end":"2026-07-25T20:12:10.842414Z","steps":["trace[951308900] 'process raft request' (duration: 225.495621ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T20:12:10.842449Z","caller":"traceutil/trace.go:171","msg":"trace[1559132267] linearizableReadLoop","detail":"{readStateIndex:3129; appliedIndex:3128; }","duration":"753.218908ms","start":"2026-07-25T20:12:10.089199Z","end":"2026-07-25T20:12:10.842418Z","steps":["trace[1559132267] 'read index received' (duration: 91.023311ms)","trace[1559132267] 'applied index is now lower than readState.Index' (duration: 662.191737ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T20:12:10.842641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"753.413413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-25T20:12:10.842742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"644.667772ms","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-07-25T20:12:10.842759Z","caller":"traceutil/trace.go:171","msg":"trace[1399364288] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3004; }","duration":"753.577377ms","start":"2026-07-25T20:12:10.089149Z","end":"2026-07-25T20:12:10.842726Z","steps":["trace[1399364288] 'agreement among raft nodes before linearized reading' (duration: 753.357922ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T20:12:10.842756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.358608ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:1948"} {"level":"info","ts":"2026-07-25T20:12:10.842781Z","caller":"traceutil/trace.go:171","msg":"trace[644282067] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3004; }","duration":"644.787154ms","start":"2026-07-25T20:12:10.197982Z","end":"2026-07-25T20:12:10.842769Z","steps":["trace[644282067] 'agreement among raft nodes before linearized reading' (duration: 644.60186ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T20:12:10.842843Z","caller":"traceutil/trace.go:171","msg":"trace[266267616] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:3004; }","duration":"157.491912ms","start":"2026-07-25T20:12:10.685335Z","end":"2026-07-25T20:12:10.842827Z","steps":["trace[266267616] 'agreement among raft nodes before linearized reading' (duration: 157.268376ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T20:12:10.842842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T20:12:10.089126Z","time spent":"753.678159ms","remote":"127.0.0.1:50352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-25T20:12:10.84286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T20:12:10.197951Z","time spent":"644.899677ms","remote":"127.0.0.1:50622","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-07-25T20:12:10.843182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.212217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-25T20:12:10.84326Z","caller":"traceutil/trace.go:171","msg":"trace[1783123449] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3004; }","duration":"226.411544ms","start":"2026-07-25T20:12:10.616832Z","end":"2026-07-25T20:12:10.843243Z","steps":["trace[1783123449] 'agreement among raft nodes before linearized reading' (duration: 225.84474ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T20:12:11.367573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.600052ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-25T20:12:11.36784Z","caller":"traceutil/trace.go:171","msg":"trace[1752316775] linearizableReadLoop","detail":"{readStateIndex:3133; appliedIndex:3130; }","duration":"516.133201ms","start":"2026-07-25T20:12:10.85169Z","end":"2026-07-25T20:12:11.367823Z","steps":["trace[1752316775] 'read index received' (duration: 98.286361ms)","trace[1752316775] 'applied index is now lower than readState.Index' (duration: 417.84591ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T20:12:11.367938Z","caller":"traceutil/trace.go:171","msg":"trace[88332723] transaction","detail":"{read_only:false; response_revision:3005; number_of_response:1; }","duration":"520.688583ms","start":"2026-07-25T20:12:10.847239Z","end":"2026-07-25T20:12:11.367928Z","steps":["trace[88332723] 'process raft request' (duration: 102.617348ms)","trace[88332723] 'compare' (duration: 417.408528ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T20:12:11.368003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T20:12:10.847217Z","time spent":"520.752034ms","remote":"127.0.0.1:50622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T20:12:11.368077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"516.362376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T20:12:11.368158Z","caller":"traceutil/trace.go:171","msg":"trace[1351695731] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3007; }","duration":"516.476598ms","start":"2026-07-25T20:12:10.851663Z","end":"2026-07-25T20:12:11.368139Z","steps":["trace[1351695731] 'agreement among raft nodes before linearized reading' (duration: 516.312104ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T20:12:11.368197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.876705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-07-25T20:12:11.368204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T20:12:10.851653Z","time spent":"516.544251ms","remote":"127.0.0.1:50336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-25T20:12:11.368225Z","caller":"traceutil/trace.go:171","msg":"trace[662189663] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3007; }","duration":"352.980357ms","start":"2026-07-25T20:12:11.015235Z","end":"2026-07-25T20:12:11.368215Z","steps":["trace[662189663] 'agreement among raft nodes before linearized reading' (duration: 352.874665ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T20:12:11.368216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.130643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T20:12:11.368209Z","caller":"traceutil/trace.go:171","msg":"trace[1503450054] transaction","detail":"{read_only:false; response_revision:3006; number_of_response:1; }","duration":"519.567605ms","start":"2026-07-25T20:12:10.848614Z","end":"2026-07-25T20:12:11.368182Z","steps":["trace[1503450054] 'process raft request' (duration: 519.066653ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T20:12:11.368308Z","caller":"traceutil/trace.go:171","msg":"trace[1300178415] transaction","detail":"{read_only:false; response_revision:3007; number_of_response:1; }","duration":"519.225247ms","start":"2026-07-25T20:12:10.849072Z","end":"2026-07-25T20:12:11.368297Z","steps":["trace[1300178415] 'process raft request' (duration: 518.713475ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T20:12:11.36831Z","caller":"traceutil/trace.go:171","msg":"trace[810649242] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:3007; }","duration":"281.272987ms","start":"2026-07-25T20:12:11.087013Z","end":"2026-07-25T20:12:11.368286Z","steps":["trace[810649242] 'agreement among raft nodes before linearized reading' (duration: 281.126333ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T20:12:11.368367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T20:12:10.849057Z","time spent":"519.281458ms","remote":"127.0.0.1:50622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T20:12:11.368255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T20:12:11.015211Z","time spent":"353.037689ms","remote":"127.0.0.1:50622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-25T20:12:11.368461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T20:12:10.848589Z","time spent":"519.73752ms","remote":"127.0.0.1:50622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T20:12:11.595407Z","caller":"traceutil/trace.go:171","msg":"trace[1913140984] transaction","detail":"{read_only:false; response_revision:3010; number_of_response:1; }","duration":"105.965532ms","start":"2026-07-25T20:12:11.489413Z","end":"2026-07-25T20:12:11.595378Z","steps":["trace[1913140984] 'process raft request' (duration: 82.498809ms)","trace[1913140984] 'compare' (duration: 23.34679ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T20:12:11.784949Z","caller":"traceutil/trace.go:171","msg":"trace[1768656130] transaction","detail":"{read_only:false; response_revision:3015; number_of_response:1; }","duration":"117.621491ms","start":"2026-07-25T20:12:11.667299Z","end":"2026-07-25T20:12:11.78492Z","steps":["trace[1768656130] 'process raft request' (duration: 43.424468ms)","trace[1768656130] 'compare' (duration: 74.06526ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T20:12:13.594808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.948359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T20:12:13.595002Z","caller":"traceutil/trace.go:171","msg":"trace[1569463247] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:3020; }","duration":"155.186235ms","start":"2026-07-25T20:12:13.439794Z","end":"2026-07-25T20:12:13.59498Z","steps":["trace[1569463247] 'count revisions from in-memory index tree' (duration: 154.847266ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T20:12:13.594905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.517773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-25T20:12:13.595257Z","caller":"traceutil/trace.go:171","msg":"trace[1761114795] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3020; }","duration":"157.923153ms","start":"2026-07-25T20:12:13.437239Z","end":"2026-07-25T20:12:13.595162Z","steps":["trace[1761114795] 'range keys from in-memory index tree' (duration: 157.299317ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T20:12:13.725642Z","caller":"traceutil/trace.go:171","msg":"trace[665148350] transaction","detail":"{read_only:false; response_revision:3022; number_of_response:1; }","duration":"125.282362ms","start":"2026-07-25T20:12:13.600332Z","end":"2026-07-25T20:12:13.725614Z","steps":["trace[665148350] 'process raft request' (duration: 124.448212ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T20:12:13.725747Z","caller":"traceutil/trace.go:171","msg":"trace[1125995385] linearizableReadLoop","detail":"{readStateIndex:3149; appliedIndex:3146; }","duration":"111.420898ms","start":"2026-07-25T20:12:13.6143Z","end":"2026-07-25T20:12:13.725721Z","steps":["trace[1125995385] 'read index received' (duration: 35.146553ms)","trace[1125995385] 'applied index is now lower than readState.Index' (duration: 76.272315ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T20:12:13.725925Z","caller":"traceutil/trace.go:171","msg":"trace[344924973] transaction","detail":"{read_only:false; response_revision:3023; number_of_response:1; }","duration":"122.741739ms","start":"2026-07-25T20:12:13.60315Z","end":"2026-07-25T20:12:13.725891Z","steps":["trace[344924973] 'process raft request' (duration: 122.417891ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T20:12:13.725993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.630252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T20:12:13.726046Z","caller":"traceutil/trace.go:171","msg":"trace[351394731] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3023; }","duration":"111.818617ms","start":"2026-07-25T20:12:13.614206Z","end":"2026-07-25T20:12:13.726024Z","steps":["trace[351394731] 'agreement among raft nodes before linearized reading' (duration: 111.609082ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T20:12:14.104658Z","caller":"traceutil/trace.go:171","msg":"trace[1433074956] linearizableReadLoop","detail":"{readStateIndex:3151; appliedIndex:3150; }","duration":"154.13197ms","start":"2026-07-25T20:12:13.950503Z","end":"2026-07-25T20:12:14.104635Z","steps":["trace[1433074956] 'read index received' (duration: 112.329451ms)","trace[1433074956] 'applied index is now lower than readState.Index' (duration: 41.801579ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T20:12:14.104693Z","caller":"traceutil/trace.go:171","msg":"trace[2016314330] transaction","detail":"{read_only:false; response_revision:3025; number_of_response:1; }","duration":"156.968639ms","start":"2026-07-25T20:12:13.947698Z","end":"2026-07-25T20:12:14.104666Z","steps":["trace[2016314330] 'process raft request' (duration: 115.12448ms)","trace[2016314330] 'compare' (duration: 41.698695ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T20:12:14.105028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.498808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:1783"} {"level":"info","ts":"2026-07-25T20:12:14.105171Z","caller":"traceutil/trace.go:171","msg":"trace[1921750551] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:3025; }","duration":"154.668072ms","start":"2026-07-25T20:12:13.95048Z","end":"2026-07-25T20:12:14.105148Z","steps":["trace[1921750551] 'agreement among raft nodes before linearized reading' (duration: 154.339444ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T20:12:14.105485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.050263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/default/test-pvc\" ","response":"range_response_count:1 size:1243"} {"level":"info","ts":"2026-07-25T20:12:14.105544Z","caller":"traceutil/trace.go:171","msg":"trace[664561341] range","detail":"{range_begin:/registry/persistentvolumeclaims/default/test-pvc; range_end:; response_count:1; response_revision:3025; }","duration":"143.136175ms","start":"2026-07-25T20:12:13.962392Z","end":"2026-07-25T20:12:14.105528Z","steps":["trace[664561341] 'agreement among raft nodes before linearized reading' (duration: 142.375767ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T20:12:14.616434Z","caller":"traceutil/trace.go:171","msg":"trace[447605942] transaction","detail":"{read_only:false; response_revision:3031; number_of_response:1; }","duration":"125.740883ms","start":"2026-07-25T20:12:14.490674Z","end":"2026-07-25T20:12:14.616415Z","steps":["trace[447605942] 'process raft request' (duration: 125.303602ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T20:12:15.434859Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3880870991439576526,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-25T20:12:15.935654Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3880870991439576526,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-25T20:12:16.435854Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3880870991439576526,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-25T20:12:16.936539Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3880870991439576526,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-25T20:12:17.437045Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3880870991439576526,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-25T20:12:17.614386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000445795s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-07-25T20:12:17.614493Z","caller":"traceutil/trace.go:171","msg":"trace[1620963392] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000606339s","start":"2026-07-25T20:12:15.613858Z","end":"2026-07-25T20:12:17.614465Z","steps":["trace[1620963392] 'agreement among raft nodes before linearized reading' (duration: 2.000482426s)"],"step_count":1} {"level":"warn","ts":"2026-07-25T20:12:17.615015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T20:12:15.613836Z","time spent":"2.000713872s","remote":"127.0.0.1:50352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/07/25 20:12:17 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-07-25T20:12:17.876209Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.9778358s","expected-duration":"1s"} {"level":"info","ts":"2026-07-25T20:12:17.883595Z","caller":"traceutil/trace.go:171","msg":"trace[736677459] transaction","detail":"{read_only:false; response_revision:3036; number_of_response:1; }","duration":"2.985306084s","start":"2026-07-25T20:12:14.898265Z","end":"2026-07-25T20:12:17.883572Z","steps":["trace[736677459] 'process raft request' (duration: 2.978220169s)"],"step_count":1} {"level":"warn","ts":"2026-07-25T20:12:17.883736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T20:12:14.898234Z","time spent":"2.985422947s","remote":"127.0.0.1:50622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-25T20:12:17.884154Z","caller":"traceutil/trace.go:171","msg":"trace[727010745] linearizableReadLoop","detail":"{readStateIndex:3164; appliedIndex:3162; }","duration":"2.949367423s","start":"2026-07-25T20:12:14.93477Z","end":"2026-07-25T20:12:17.884138Z","steps":["trace[727010745] 'read index received' (duration: 2.941729964s)","trace[727010745] 'applied index is now lower than readState.Index' (duration: 7.636609ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T20:12:17.884198Z","caller":"traceutil/trace.go:171","msg":"trace[1387047188] transaction","detail":"{read_only:false; response_revision:3037; number_of_response:1; }","duration":"1.573026569s","start":"2026-07-25T20:12:16.311147Z","end":"2026-07-25T20:12:17.884174Z","steps":["trace[1387047188] 'process raft request' (duration: 1.572851205s)"],"step_count":1} {"level":"info","ts":"2026-07-25T20:12:17.884258Z","caller":"traceutil/trace.go:171","msg":"trace[1315566595] transaction","detail":"{read_only:false; response_revision:3038; number_of_response:1; }","duration":"265.898284ms","start":"2026-07-25T20:12:17.618347Z","end":"2026-07-25T20:12:17.884245Z","steps":["trace[1315566595] 'process raft request' (duration: 265.74232ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T20:12:17.884342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T20:12:16.311126Z","time spent":"1.573148943s","remote":"127.0.0.1:50622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-25T20:12:17.884405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.482241862s","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-07-25T20:12:17.884449Z","caller":"traceutil/trace.go:171","msg":"trace[1987598246] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3038; }","duration":"2.482324823s","start":"2026-07-25T20:12:15.402114Z","end":"2026-07-25T20:12:17.884438Z","steps":["trace[1987598246] 'agreement among raft nodes before linearized reading' (duration: 2.48220133s)"],"step_count":1} {"level":"warn","ts":"2026-07-25T20:12:17.884483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T20:12:15.402093Z","time spent":"2.482382535s","remote":"127.0.0.1:50622","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-07-25T20:12:17.884545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.003783728s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-25T20:12:17.8846Z","caller":"traceutil/trace.go:171","msg":"trace[1467525508] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:3038; }","duration":"2.00386364s","start":"2026-07-25T20:12:15.880706Z","end":"2026-07-25T20:12:17.884569Z","steps":["trace[1467525508] 'agreement among raft nodes before linearized reading' (duration: 2.003796078s)"],"step_count":1} {"level":"warn","ts":"2026-07-25T20:12:17.88462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.511383919s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-07-25T20:12:17.884651Z","caller":"traceutil/trace.go:171","msg":"trace[94032255] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:3038; }","duration":"1.511450621s","start":"2026-07-25T20:12:16.373188Z","end":"2026-07-25T20:12:17.884639Z","steps":["trace[94032255] 'agreement among raft nodes before linearized reading' (duration: 1.511359038s)"],"step_count":1} {"level":"warn","ts":"2026-07-25T20:12:17.884678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T20:12:16.373169Z","time spent":"1.511504871s","remote":"127.0.0.1:50622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":557,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-07-25T20:12:17.884675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.034669144s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:2723"} {"level":"info","ts":"2026-07-25T20:12:17.884709Z","caller":"traceutil/trace.go:171","msg":"trace[1578440512] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:3038; }","duration":"2.034740506s","start":"2026-07-25T20:12:15.84996Z","end":"2026-07-25T20:12:17.8847Z","steps":["trace[1578440512] 'agreement among raft nodes before linearized reading' (duration: 2.034645264s)"],"step_count":1} {"level":"warn","ts":"2026-07-25T20:12:17.884719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.688251781s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-07-25T20:12:17.884743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T20:12:15.849939Z","time spent":"2.034796168s","remote":"127.0.0.1:50538","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":1,"response size":2745,"request content":"key:\"/registry/pods/default/test-pvc-pod\" "} {"level":"warn","ts":"2026-07-25T20:12:17.884767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.950008899s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T20:12:17.884791Z","caller":"traceutil/trace.go:171","msg":"trace[1570323243] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:3038; }","duration":"2.950091191s","start":"2026-07-25T20:12:14.934694Z","end":"2026-07-25T20:12:17.884785Z","steps":["trace[1570323243] 'agreement among raft nodes before linearized reading' (duration: 2.95005142s)"],"step_count":1} {"level":"warn","ts":"2026-07-25T20:12:17.88479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.756142987s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-25T20:12:17.884813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T20:12:14.934662Z","time spent":"2.950146333s","remote":"127.0.0.1:50764","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":27,"request content":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true "} {"level":"warn","ts":"2026-07-25T20:12:17.88465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T20:12:15.880681Z","time spent":"2.003955203s","remote":"127.0.0.1:50780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":29,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-07-25T20:12:17.884859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.987020261s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-25T20:12:17.884899Z","caller":"traceutil/trace.go:171","msg":"trace[807959789] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:3038; }","duration":"1.987118954s","start":"2026-07-25T20:12:15.897769Z","end":"2026-07-25T20:12:17.884888Z","steps":["trace[807959789] 'agreement among raft nodes before linearized reading' (duration: 1.987064042s)"],"step_count":1} {"level":"warn","ts":"2026-07-25T20:12:17.884925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.474234022s","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-07-25T20:12:17.884941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T20:12:15.897739Z","time spent":"1.987193285s","remote":"127.0.0.1:35334","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true "} {"level":"info","ts":"2026-07-25T20:12:17.884961Z","caller":"traceutil/trace.go:171","msg":"trace[284416367] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3038; }","duration":"2.474309244s","start":"2026-07-25T20:12:15.410642Z","end":"2026-07-25T20:12:17.884951Z","steps":["trace[284416367] 'agreement among raft nodes before linearized reading' (duration: 2.474220412s)"],"step_count":1} {"level":"warn","ts":"2026-07-25T20:12:17.884989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T20:12:15.410612Z","time spent":"2.474369695s","remote":"127.0.0.1:50622","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-07-25T20:12:17.884992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.593967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-25T20:12:17.884819Z","caller":"traceutil/trace.go:171","msg":"trace[134230152] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:3038; }","duration":"1.756215609s","start":"2026-07-25T20:12:16.128596Z","end":"2026-07-25T20:12:17.884812Z","steps":["trace[134230152] 'agreement among raft nodes before linearized reading' (duration: 1.756167988s)"],"step_count":1} {"level":"info","ts":"2026-07-25T20:12:17.885066Z","caller":"traceutil/trace.go:171","msg":"trace[857213841] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3038; }","duration":"261.69284ms","start":"2026-07-25T20:12:17.623354Z","end":"2026-07-25T20:12:17.885047Z","steps":["trace[857213841] 'agreement among raft nodes before linearized reading' (duration: 261.601937ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T20:12:17.885082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T20:12:16.128545Z","time spent":"1.756528107s","remote":"127.0.0.1:50776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":29,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"info","ts":"2026-07-25T20:12:17.884748Z","caller":"traceutil/trace.go:171","msg":"trace[2051713815] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3038; }","duration":"1.688318903s","start":"2026-07-25T20:12:16.196421Z","end":"2026-07-25T20:12:17.88474Z","steps":["trace[2051713815] 'agreement among raft nodes before linearized reading' (duration: 1.688245221s)"],"step_count":1} {"level":"warn","ts":"2026-07-25T20:12:17.885162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T20:12:16.196398Z","time spent":"1.688754063s","remote":"127.0.0.1:50622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-25T20:12:17.885167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.15488207s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-07-25T20:12:17.885229Z","caller":"traceutil/trace.go:171","msg":"trace[769571465] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3038; }","duration":"2.154936101s","start":"2026-07-25T20:12:15.730266Z","end":"2026-07-25T20:12:17.885202Z","steps":["trace[769571465] 'agreement among raft nodes before linearized reading' (duration: 2.154745797s)"],"step_count":1} {"level":"warn","ts":"2026-07-25T20:12:17.885274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.155068975s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-07-25T20:12:17.884936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.283326841s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-25T20:12:17.885292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T20:12:15.73026Z","time spent":"2.155022713s","remote":"127.0.0.1:50622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-25T20:12:17.885321Z","caller":"traceutil/trace.go:171","msg":"trace[1192683075] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3038; }","duration":"2.155148397s","start":"2026-07-25T20:12:15.730162Z","end":"2026-07-25T20:12:17.88531Z","steps":["trace[1192683075] 'agreement among raft nodes before linearized reading' (duration: 2.155039364s)"],"step_count":1} {"level":"warn","ts":"2026-07-25T20:12:17.885361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T20:12:15.730144Z","time spent":"2.155208168s","remote":"127.0.0.1:50622","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-07-25T20:12:17.885326Z","caller":"traceutil/trace.go:171","msg":"trace[1194586510] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:3038; }","duration":"2.283795332s","start":"2026-07-25T20:12:15.601522Z","end":"2026-07-25T20:12:17.885317Z","steps":["trace[1194586510] 'agreement among raft nodes before linearized reading' (duration: 2.28328266s)"],"step_count":1} {"level":"warn","ts":"2026-07-25T20:12:17.88547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.231868453s","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:533"} {"level":"info","ts":"2026-07-25T20:12:17.885494Z","caller":"traceutil/trace.go:171","msg":"trace[1443578960] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:3038; }","duration":"2.231917814s","start":"2026-07-25T20:12:15.653571Z","end":"2026-07-25T20:12:17.885489Z","steps":["trace[1443578960] 'agreement among raft nodes before linearized reading' (duration: 2.231852453s)"],"step_count":1} {"level":"warn","ts":"2026-07-25T20:12:17.885494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T20:12:15.601505Z","time spent":"2.283951126s","remote":"127.0.0.1:50472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":5,"response size":29,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true "} {"level":"warn","ts":"2026-07-25T20:12:17.885516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-25T20:12:15.653559Z","time spent":"2.231952475s","remote":"127.0.0.1:50622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":555,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"info","ts":"2026-07-25T20:12:18.041722Z","caller":"traceutil/trace.go:171","msg":"trace[1976744025] transaction","detail":"{read_only:false; response_revision:3041; number_of_response:1; }","duration":"151.076082ms","start":"2026-07-25T20:12:17.890613Z","end":"2026-07-25T20:12:18.041689Z","steps":["trace[1976744025] 'process raft request' (duration: 150.960359ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T20:12:18.041734Z","caller":"traceutil/trace.go:171","msg":"trace[481292675] transaction","detail":"{read_only:false; response_revision:3040; number_of_response:1; }","duration":"151.094263ms","start":"2026-07-25T20:12:17.890617Z","end":"2026-07-25T20:12:18.041712Z","steps":["trace[481292675] 'process raft request' (duration: 145.36005ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T20:12:18.041878Z","caller":"traceutil/trace.go:171","msg":"trace[1583122907] transaction","detail":"{read_only:false; response_revision:3042; number_of_response:1; }","duration":"151.199336ms","start":"2026-07-25T20:12:17.890669Z","end":"2026-07-25T20:12:18.041868Z","steps":["trace[1583122907] 'process raft request' (duration: 150.97824ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T20:12:18.042183Z","caller":"traceutil/trace.go:171","msg":"trace[1414624517] transaction","detail":"{read_only:false; response_revision:3043; number_of_response:1; }","duration":"151.481472ms","start":"2026-07-25T20:12:17.890684Z","end":"2026-07-25T20:12:18.042165Z","steps":["trace[1414624517] 'process raft request' (duration: 151.128623ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T20:12:18.042574Z","caller":"traceutil/trace.go:171","msg":"trace[1823245007] transaction","detail":"{read_only:false; response_revision:3044; number_of_response:1; }","duration":"151.566505ms","start":"2026-07-25T20:12:17.890986Z","end":"2026-07-25T20:12:18.042553Z","steps":["trace[1823245007] 'process raft request' (duration: 151.121614ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T20:12:18.042569Z","caller":"traceutil/trace.go:171","msg":"trace[726901913] transaction","detail":"{read_only:false; response_revision:3045; number_of_response:1; }","duration":"151.455242ms","start":"2026-07-25T20:12:17.891084Z","end":"2026-07-25T20:12:18.04254Z","steps":["trace[726901913] 'process raft request' (duration: 151.35949ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T20:12:24.037377Z","caller":"traceutil/trace.go:171","msg":"trace[339827829] transaction","detail":"{read_only:false; response_revision:3073; number_of_response:1; }","duration":"133.187337ms","start":"2026-07-25T20:12:23.904136Z","end":"2026-07-25T20:12:24.037323Z","steps":["trace[339827829] 'process raft request' (duration: 94.621079ms)","trace[339827829] 'compare' (duration: 37.990314ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T20:12:29.811592Z","caller":"traceutil/trace.go:171","msg":"trace[861057969] linearizableReadLoop","detail":"{readStateIndex:3231; appliedIndex:3230; }","duration":"169.892349ms","start":"2026-07-25T20:12:29.641662Z","end":"2026-07-25T20:12:29.811554Z","steps":["trace[861057969] 'read index received' (duration: 169.570371ms)","trace[861057969] 'applied index is now lower than readState.Index' (duration: 320.598µs)"],"step_count":2} {"level":"info","ts":"2026-07-25T20:12:29.811789Z","caller":"traceutil/trace.go:171","msg":"trace[728928096] transaction","detail":"{read_only:false; response_revision:3102; number_of_response:1; }","duration":"194.122641ms","start":"2026-07-25T20:12:29.617651Z","end":"2026-07-25T20:12:29.811774Z","steps":["trace[728928096] 'process raft request' (duration: 193.68068ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T20:12:29.812296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.595597ms","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-07-25T20:12:29.812412Z","caller":"traceutil/trace.go:171","msg":"trace[1216177726] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:3102; }","duration":"170.763391ms","start":"2026-07-25T20:12:29.641617Z","end":"2026-07-25T20:12:29.81238Z","steps":["trace[1216177726] 'agreement among raft nodes before linearized reading' (duration: 170.405642ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T20:12:29.81227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.269682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-07-25T20:12:29.812524Z","caller":"traceutil/trace.go:171","msg":"trace[161360506] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:3102; }","duration":"156.640251ms","start":"2026-07-25T20:12:29.655856Z","end":"2026-07-25T20:12:29.812496Z","steps":["trace[161360506] 'agreement among raft nodes before linearized reading' (duration: 156.18384ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T20:12:30.427492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.836123ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-25T20:12:30.427706Z","caller":"traceutil/trace.go:171","msg":"trace[1809474280] transaction","detail":"{read_only:false; response_revision:3108; number_of_response:1; }","duration":"214.299912ms","start":"2026-07-25T20:12:30.213371Z","end":"2026-07-25T20:12:30.427671Z","steps":["trace[1809474280] 'process raft request' (duration: 109.134561ms)","trace[1809474280] 'compare' (duration: 104.639788ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T20:12:30.42782Z","caller":"traceutil/trace.go:171","msg":"trace[2140479397] transaction","detail":"{read_only:false; response_revision:3113; number_of_response:1; }","duration":"212.866327ms","start":"2026-07-25T20:12:30.214935Z","end":"2026-07-25T20:12:30.427802Z","steps":["trace[2140479397] 'process raft request' (duration: 212.828457ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T20:12:30.428055Z","caller":"traceutil/trace.go:171","msg":"trace[1242140338] transaction","detail":"{read_only:false; response_revision:3109; number_of_response:1; }","duration":"213.648136ms","start":"2026-07-25T20:12:30.214396Z","end":"2026-07-25T20:12:30.428045Z","steps":["trace[1242140338] 'process raft request' (duration: 213.206596ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T20:12:30.428135Z","caller":"traceutil/trace.go:171","msg":"trace[1765881105] transaction","detail":"{read_only:false; response_revision:3110; number_of_response:1; }","duration":"213.722177ms","start":"2026-07-25T20:12:30.214404Z","end":"2026-07-25T20:12:30.428127Z","steps":["trace[1765881105] 'process raft request' (duration: 213.256706ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T20:12:30.428298Z","caller":"traceutil/trace.go:171","msg":"trace[1936018310] transaction","detail":"{read_only:false; response_revision:3111; number_of_response:1; }","duration":"213.795391ms","start":"2026-07-25T20:12:30.214476Z","end":"2026-07-25T20:12:30.428271Z","steps":["trace[1936018310] 'process raft request' (duration: 213.218816ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T20:12:30.428419Z","caller":"traceutil/trace.go:171","msg":"trace[1962476358] transaction","detail":"{read_only:false; response_revision:3112; number_of_response:1; }","duration":"213.927384ms","start":"2026-07-25T20:12:30.214483Z","end":"2026-07-25T20:12:30.42841Z","steps":["trace[1962476358] 'process raft request' (duration: 213.249777ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T20:12:31.008701Z","caller":"traceutil/trace.go:171","msg":"trace[822237143] transaction","detail":"{read_only:false; response_revision:3115; number_of_response:1; }","duration":"169.619323ms","start":"2026-07-25T20:12:30.839055Z","end":"2026-07-25T20:12:31.008675Z","steps":["trace[822237143] 'process raft request' (duration: 169.231794ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T20:12:38.678897Z","caller":"traceutil/trace.go:171","msg":"trace[1041940270] linearizableReadLoop","detail":"{readStateIndex:3279; appliedIndex:3278; }","duration":"116.7547ms","start":"2026-07-25T20:12:38.562107Z","end":"2026-07-25T20:12:38.678861Z","steps":["trace[1041940270] 'read index received' (duration: 116.551785ms)","trace[1041940270] 'applied index is now lower than readState.Index' (duration: 201.944µs)"],"step_count":2} {"level":"info","ts":"2026-07-25T20:12:38.679048Z","caller":"traceutil/trace.go:171","msg":"trace[1836994038] transaction","detail":"{read_only:false; response_revision:3147; number_of_response:1; }","duration":"137.100676ms","start":"2026-07-25T20:12:38.541924Z","end":"2026-07-25T20:12:38.679025Z","steps":["trace[1836994038] 'process raft request' (duration: 136.700766ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T20:12:38.679345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.696798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-07-25T20:12:38.679362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.9434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-07-25T20:12:38.67936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.960985ms","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:533"} {"level":"info","ts":"2026-07-25T20:12:38.679437Z","caller":"traceutil/trace.go:171","msg":"trace[1248432143] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3147; }","duration":"116.840512ms","start":"2026-07-25T20:12:38.562575Z","end":"2026-07-25T20:12:38.679416Z","steps":["trace[1248432143] 'agreement among raft nodes before linearized reading' (duration: 116.557325ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T20:12:38.679441Z","caller":"traceutil/trace.go:171","msg":"trace[460789435] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3147; }","duration":"116.049833ms","start":"2026-07-25T20:12:38.563373Z","end":"2026-07-25T20:12:38.679422Z","steps":["trace[460789435] 'agreement among raft nodes before linearized reading' (duration: 115.870089ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T20:12:38.679447Z","caller":"traceutil/trace.go:171","msg":"trace[361509685] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:3147; }","duration":"115.077628ms","start":"2026-07-25T20:12:38.564354Z","end":"2026-07-25T20:12:38.679431Z","steps":["trace[361509685] 'agreement among raft nodes before linearized reading' (duration: 114.844643ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T20:12:38.679581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.584416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-07-25T20:12:38.679641Z","caller":"traceutil/trace.go:171","msg":"trace[1207732569] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3147; }","duration":"116.684258ms","start":"2026-07-25T20:12:38.562933Z","end":"2026-07-25T20:12:38.679617Z","steps":["trace[1207732569] 'agreement among raft nodes before linearized reading' (duration: 116.564435ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T20:12:38.679847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.087398ms","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-07-25T20:12:38.679887Z","caller":"traceutil/trace.go:171","msg":"trace[145771778] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3147; }","duration":"117.846456ms","start":"2026-07-25T20:12:38.562031Z","end":"2026-07-25T20:12:38.679878Z","steps":["trace[145771778] 'agreement among raft nodes before linearized reading' (duration: 116.997206ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T20:12:38.859658Z","caller":"traceutil/trace.go:171","msg":"trace[1059564220] transaction","detail":"{read_only:false; response_revision:3153; number_of_response:1; }","duration":"145.666037ms","start":"2026-07-25T20:12:38.713954Z","end":"2026-07-25T20:12:38.85962Z","steps":["trace[1059564220] 'process raft request' (duration: 84.020547ms)","trace[1059564220] 'compare' (duration: 61.493257ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T20:12:40.307468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.387209ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"warn","ts":"2026-07-25T20:12:44.916232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.997513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-07-25T20:12:44.916557Z","caller":"traceutil/trace.go:171","msg":"trace[70481877] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3177; }","duration":"104.395413ms","start":"2026-07-25T20:12:44.812134Z","end":"2026-07-25T20:12:44.916529Z","steps":["trace[70481877] 'range keys from in-memory index tree' (duration: 91.457652ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T20:12:44.969933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.152573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/envoy-gateway-gateway-helm-envoy-gateway-role\" ","response":"range_response_count:1 size:1944"} {"level":"info","ts":"2026-07-25T20:12:44.970057Z","caller":"traceutil/trace.go:171","msg":"trace[1069879291] range","detail":"{range_begin:/registry/clusterroles/envoy-gateway-gateway-helm-envoy-gateway-role; range_end:; response_count:1; response_revision:3181; }","duration":"124.299816ms","start":"2026-07-25T20:12:44.845721Z","end":"2026-07-25T20:12:44.970021Z","steps":["trace[1069879291] 'agreement among raft nodes before linearized reading' (duration: 123.967438ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T20:12:45.256761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.594616ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-25T20:12:45.256898Z","caller":"traceutil/trace.go:171","msg":"trace[1263304599] linearizableReadLoop","detail":"{readStateIndex:3318; appliedIndex:3317; }","duration":"231.49736ms","start":"2026-07-25T20:12:45.025381Z","end":"2026-07-25T20:12:45.256878Z","steps":["trace[1263304599] 'read index received' (duration: 112.566655ms)","trace[1263304599] 'applied index is now lower than readState.Index' (duration: 118.929015ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T20:12:45.256924Z","caller":"traceutil/trace.go:171","msg":"trace[62272504] transaction","detail":"{read_only:false; response_revision:3184; number_of_response:1; }","duration":"281.296918ms","start":"2026-07-25T20:12:44.975583Z","end":"2026-07-25T20:12:45.25688Z","steps":["trace[62272504] 'process raft request' (duration: 162.444025ms)","trace[62272504] 'compare' (duration: 118.293669ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T20:12:45.257139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.765207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/envoy-gateway-gateway-helm-certgen:envoy-gateway-system\" ","response":"range_response_count:1 size:1107"} {"level":"info","ts":"2026-07-25T20:12:45.257178Z","caller":"traceutil/trace.go:171","msg":"trace[1640452181] range","detail":"{range_begin:/registry/clusterroles/envoy-gateway-gateway-helm-certgen:envoy-gateway-system; range_end:; response_count:1; response_revision:3184; }","duration":"231.850259ms","start":"2026-07-25T20:12:45.025314Z","end":"2026-07-25T20:12:45.257164Z","steps":["trace[1640452181] 'agreement among raft nodes before linearized reading' (duration: 231.666734ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T20:12:45.25754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.652911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/envoy-gateway-gateway-helm-envoy-gateway-role\" ","response":"range_response_count:1 size:1944"} {"level":"info","ts":"2026-07-25T20:12:45.257633Z","caller":"traceutil/trace.go:171","msg":"trace[1659341218] range","detail":"{range_begin:/registry/clusterroles/envoy-gateway-gateway-helm-envoy-gateway-role; range_end:; response_count:1; response_revision:3184; }","duration":"187.799915ms","start":"2026-07-25T20:12:45.069808Z","end":"2026-07-25T20:12:45.257608Z","steps":["trace[1659341218] 'agreement among raft nodes before linearized reading' (duration: 187.487047ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T20:12:48.329107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.602742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:job-controller\" ","response":"range_response_count:1 size:780"} {"level":"info","ts":"2026-07-25T20:12:48.32922Z","caller":"traceutil/trace.go:171","msg":"trace[208897047] range","detail":"{range_begin:/registry/clusterroles/system:controller:job-controller; range_end:; response_count:1; response_revision:3192; }","duration":"115.757606ms","start":"2026-07-25T20:12:48.213435Z","end":"2026-07-25T20:12:48.329193Z","steps":["trace[208897047] 'range keys from in-memory index tree' (duration: 115.468858ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T20:12:50.229246Z","caller":"traceutil/trace.go:171","msg":"trace[1997229106] linearizableReadLoop","detail":"{readStateIndex:3338; appliedIndex:3337; }","duration":"106.476454ms","start":"2026-07-25T20:12:50.122731Z","end":"2026-07-25T20:12:50.229208Z","steps":["trace[1997229106] 'read index received' (duration: 64.83608ms)","trace[1997229106] 'applied index is now lower than readState.Index' (duration: 41.638624ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T20:12:50.229445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.30403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:811"} {"level":"info","ts":"2026-07-25T20:12:50.229487Z","caller":"traceutil/trace.go:171","msg":"trace[306960633] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:3203; }","duration":"106.411313ms","start":"2026-07-25T20:12:50.123065Z","end":"2026-07-25T20:12:50.229476Z","steps":["trace[306960633] 'agreement among raft nodes before linearized reading' (duration: 106.29637ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T20:12:50.229576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.825663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:resourcequota-controller\" ","response":"range_response_count:1 size:698"} {"level":"info","ts":"2026-07-25T20:12:50.229699Z","caller":"traceutil/trace.go:171","msg":"trace[320389471] range","detail":"{range_begin:/registry/clusterroles/system:controller:resourcequota-controller; range_end:; response_count:1; response_revision:3203; }","duration":"107.003138ms","start":"2026-07-25T20:12:50.122665Z","end":"2026-07-25T20:12:50.229668Z","steps":["trace[320389471] 'agreement among raft nodes before linearized reading' (duration: 106.639809ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T20:13:05.378667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.558584ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-25T20:13:05.378786Z","caller":"traceutil/trace.go:171","msg":"trace[1714665624] linearizableReadLoop","detail":"{readStateIndex:3412; appliedIndex:3411; }","duration":"167.828598ms","start":"2026-07-25T20:13:05.21094Z","end":"2026-07-25T20:13:05.378768Z","steps":["trace[1714665624] 'read index received' (duration: 12.039078ms)","trace[1714665624] 'applied index is now lower than readState.Index' (duration: 155.78841ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T20:13:05.378898Z","caller":"traceutil/trace.go:171","msg":"trace[1933697226] transaction","detail":"{read_only:false; response_revision:3274; number_of_response:1; }","duration":"178.063373ms","start":"2026-07-25T20:13:05.200822Z","end":"2026-07-25T20:13:05.378885Z","steps":["trace[1933697226] 'process raft request' (duration: 22.194501ms)","trace[1933697226] 'compare' (duration: 155.452322ms)"],"step_count":2} {"level":"warn","ts":"2026-07-25T20:13:05.379172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.949887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-25T20:13:05.379261Z","caller":"traceutil/trace.go:171","msg":"trace[1102141438] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3274; }","duration":"163.09072ms","start":"2026-07-25T20:13:05.216152Z","end":"2026-07-25T20:13:05.379243Z","steps":["trace[1102141438] 'agreement among raft nodes before linearized reading' (duration: 162.825653ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T20:13:05.379383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.438274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/ceph-config\" ","response":"range_response_count:1 size:899"} {"level":"info","ts":"2026-07-25T20:13:05.379443Z","caller":"traceutil/trace.go:171","msg":"trace[641028752] range","detail":"{range_begin:/registry/configmaps/kube-system/ceph-config; range_end:; response_count:1; response_revision:3274; }","duration":"168.535435ms","start":"2026-07-25T20:13:05.210894Z","end":"2026-07-25T20:13:05.37943Z","steps":["trace[641028752] 'agreement among raft nodes before linearized reading' (duration: 168.346541ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T20:13:05.379644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.017153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-25T20:13:05.37969Z","caller":"traceutil/trace.go:171","msg":"trace[900401128] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3274; }","duration":"139.082785ms","start":"2026-07-25T20:13:05.240597Z","end":"2026-07-25T20:13:05.37968Z","steps":["trace[900401128] 'agreement among raft nodes before linearized reading' (duration: 138.986072ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T20:13:05.37979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.914688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/ceph-csi-config\" ","response":"range_response_count:1 size:919"} {"level":"info","ts":"2026-07-25T20:13:05.379914Z","caller":"traceutil/trace.go:171","msg":"trace[137288127] range","detail":"{range_begin:/registry/configmaps/kube-system/ceph-csi-config; range_end:; response_count:1; response_revision:3274; }","duration":"153.092793ms","start":"2026-07-25T20:13:05.226797Z","end":"2026-07-25T20:13:05.37989Z","steps":["trace[137288127] 'agreement among raft nodes before linearized reading' (duration: 152.851447ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T20:13:05.534562Z","caller":"traceutil/trace.go:171","msg":"trace[1302112475] linearizableReadLoop","detail":"{readStateIndex:3415; appliedIndex:3412; }","duration":"146.532459ms","start":"2026-07-25T20:13:05.388004Z","end":"2026-07-25T20:13:05.534536Z","steps":["trace[1302112475] 'read index received' (duration: 42.011114ms)","trace[1302112475] 'applied index is now lower than readState.Index' (duration: 104.520355ms)"],"step_count":2} {"level":"info","ts":"2026-07-25T20:13:05.534674Z","caller":"traceutil/trace.go:171","msg":"trace[943184950] transaction","detail":"{read_only:false; response_revision:3277; number_of_response:1; }","duration":"147.338819ms","start":"2026-07-25T20:13:05.387294Z","end":"2026-07-25T20:13:05.534632Z","steps":["trace[943184950] 'process raft request' (duration: 143.462553ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T20:13:05.535321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.285319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/\" range_end:\"/registry/events/kube-system0\" limit:500 ","response":"range_response_count:115 size:88872"} {"level":"warn","ts":"2026-07-25T20:13:05.535322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.004912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/\" range_end:\"/registry/events/kube-system0\" limit:500 ","response":"range_response_count:115 size:88872"} {"level":"info","ts":"2026-07-25T20:13:05.535371Z","caller":"traceutil/trace.go:171","msg":"trace[90734638] range","detail":"{range_begin:/registry/events/kube-system/; range_end:/registry/events/kube-system0; response_count:115; response_revision:3277; }","duration":"147.378621ms","start":"2026-07-25T20:13:05.38798Z","end":"2026-07-25T20:13:05.535359Z","steps":["trace[90734638] 'agreement among raft nodes before linearized reading' (duration: 146.687873ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T20:13:05.53538Z","caller":"traceutil/trace.go:171","msg":"trace[1209902790] range","detail":"{range_begin:/registry/events/kube-system/; range_end:/registry/events/kube-system0; response_count:115; response_revision:3277; }","duration":"147.096224ms","start":"2026-07-25T20:13:05.38827Z","end":"2026-07-25T20:13:05.535367Z","steps":["trace[1209902790] 'agreement among raft nodes before linearized reading' (duration: 146.467657ms)"],"step_count":1} {"level":"warn","ts":"2026-07-25T20:13:10.243795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.695504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/kube-system/system::extension-apiserver-authentication-reader\" ","response":"range_response_count:1 size:856"} {"level":"info","ts":"2026-07-25T20:13:10.243597Z","caller":"traceutil/trace.go:171","msg":"trace[688753986] transaction","detail":"{read_only:false; response_revision:3294; number_of_response:1; }","duration":"156.653471ms","start":"2026-07-25T20:13:10.086924Z","end":"2026-07-25T20:13:10.243578Z","steps":["trace[688753986] 'process raft request' (duration: 156.335593ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T20:13:10.243877Z","caller":"traceutil/trace.go:171","msg":"trace[1954118009] range","detail":"{range_begin:/registry/rolebindings/kube-system/system::extension-apiserver-authentication-reader; range_end:; response_count:1; response_revision:3294; }","duration":"138.831688ms","start":"2026-07-25T20:13:10.105024Z","end":"2026-07-25T20:13:10.243856Z","steps":["trace[1954118009] 'agreement among raft nodes before linearized reading' (duration: 138.668844ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T20:13:10.243501Z","caller":"traceutil/trace.go:171","msg":"trace[935795125] linearizableReadLoop","detail":"{readStateIndex:3432; appliedIndex:3431; }","duration":"138.397247ms","start":"2026-07-25T20:13:10.10507Z","end":"2026-07-25T20:13:10.243468Z","steps":["trace[935795125] 'read index received' (duration: 138.080899ms)","trace[935795125] 'applied index is now lower than readState.Index' (duration: 315.038µs)"],"step_count":2} {"level":"warn","ts":"2026-07-25T20:13:10.295611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.858727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/kube-system/system::leader-locking-kube-controller-manager\" ","response":"range_response_count:1 size:688"} {"level":"warn","ts":"2026-07-25T20:13:10.295623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.466884ms","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-07-25T20:13:10.295714Z","caller":"traceutil/trace.go:171","msg":"trace[1747394163] range","detail":"{range_begin:/registry/roles/kube-system/system::leader-locking-kube-controller-manager; range_end:; response_count:1; response_revision:3294; }","duration":"150.976161ms","start":"2026-07-25T20:13:10.144707Z","end":"2026-07-25T20:13:10.295683Z","steps":["trace[1747394163] 'agreement among raft nodes before linearized reading' (duration: 150.772086ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T20:13:10.295723Z","caller":"traceutil/trace.go:171","msg":"trace[395803186] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:3294; }","duration":"110.633668ms","start":"2026-07-25T20:13:10.185066Z","end":"2026-07-25T20:13:10.2957Z","steps":["trace[395803186] 'agreement among raft nodes before linearized reading' (duration: 110.367351ms)"],"step_count":1} {"level":"info","ts":"2026-07-25T20:13:11.609954Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":1912} {"level":"info","ts":"2026-07-25T20:13:11.661986Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":1912,"took":"51.502929ms","hash":2213024028,"current-db-size-bytes":24526848,"current-db-size":"24 MB","current-db-size-in-use-bytes":6930432,"current-db-size-in-use":"6.9 MB"} {"level":"info","ts":"2026-07-25T20:13:11.662043Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2213024028,"revision":1912,"compact-revision":-1}