{"level":"warn","ts":"2026-05-08T14:34:05.171726Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-08T14:34:05.17184Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.109: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.109:2380","--initial-cluster=instance=https://199.204.45.109:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.109:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.109:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-08T14:34:05.172095Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-08T14:34:05.172123Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.109:2380"]} {"level":"info","ts":"2026-05-08T14:34:05.17215Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-08T14:34:05.173023Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.109:2379"]} {"level":"info","ts":"2026-05-08T14:34:05.173116Z","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.109:2380"],"listen-peer-urls":["https://199.204.45.109:2380"],"advertise-client-urls":["https://199.204.45.109:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.109:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.109:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-08T14:34:05.229561Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"56.088634ms"} {"level":"info","ts":"2026-05-08T14:34:05.243698Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d71a1778b31d4ce","cluster-id":"aaca35652fabf9b2"} {"level":"info","ts":"2026-05-08T14:34:05.243797Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce switched to configuration voters=()"} {"level":"info","ts":"2026-05-08T14:34:05.243825Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became follower at term 0"} {"level":"info","ts":"2026-05-08T14:34:05.24384Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d71a1778b31d4ce [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-08T14:34:05.243854Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became follower at term 1"} {"level":"info","ts":"2026-05-08T14:34:05.243892Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce switched to configuration voters=(968732929669846222)"} {"level":"warn","ts":"2026-05-08T14:34:05.256241Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-08T14:34:05.258662Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-08T14:34:05.260095Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-08T14:34:05.263131Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d71a1778b31d4ce","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-08T14:34:05.266262Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-08T14:34:05.266345Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-08T14:34:05.266357Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-08T14:34:05.266157Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d71a1778b31d4ce","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-08T14:34:05.267535Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce switched to configuration voters=(968732929669846222)"} {"level":"info","ts":"2026-05-08T14:34:05.267779Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-08T14:34:05.267973Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"aaca35652fabf9b2","local-member-id":"d71a1778b31d4ce","added-peer-id":"d71a1778b31d4ce","added-peer-peer-urls":["https://199.204.45.109:2380"]} {"level":"info","ts":"2026-05-08T14:34:05.267971Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.109:2380"} {"level":"info","ts":"2026-05-08T14:34:05.26812Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.109:2380"} {"level":"info","ts":"2026-05-08T14:34:05.268301Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d71a1778b31d4ce","initial-advertise-peer-urls":["https://199.204.45.109:2380"],"listen-peer-urls":["https://199.204.45.109:2380"],"advertise-client-urls":["https://199.204.45.109:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.109:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-08T14:34:05.268339Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-08T14:34:05.848687Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce is starting a new election at term 1"} {"level":"info","ts":"2026-05-08T14:34:05.848735Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became pre-candidate at term 1"} {"level":"info","ts":"2026-05-08T14:34:05.848754Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce received MsgPreVoteResp from d71a1778b31d4ce at term 1"} {"level":"info","ts":"2026-05-08T14:34:05.848765Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became candidate at term 2"} {"level":"info","ts":"2026-05-08T14:34:05.84877Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce received MsgVoteResp from d71a1778b31d4ce at term 2"} {"level":"info","ts":"2026-05-08T14:34:05.848779Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d71a1778b31d4ce became leader at term 2"} {"level":"info","ts":"2026-05-08T14:34:05.848789Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d71a1778b31d4ce elected leader d71a1778b31d4ce at term 2"} {"level":"info","ts":"2026-05-08T14:34:05.856563Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d71a1778b31d4ce","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.109:2379]}","request-path":"/0/members/d71a1778b31d4ce/attributes","cluster-id":"aaca35652fabf9b2","publish-timeout":"7s"} {"level":"info","ts":"2026-05-08T14:34:05.8566Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-08T14:34:05.856624Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-08T14:34:05.85669Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-08T14:34:05.85684Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-08T14:34:05.856874Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-08T14:34:05.857786Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"aaca35652fabf9b2","local-member-id":"d71a1778b31d4ce","cluster-version":"3.5"} {"level":"info","ts":"2026-05-08T14:34:05.857914Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-08T14:34:05.857953Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-08T14:34:05.86035Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.109:2379"} {"level":"info","ts":"2026-05-08T14:34:05.860399Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-08T14:34:33.775386Z","caller":"traceutil/trace.go:171","msg":"trace[563445912] transaction","detail":"{read_only:false; response_revision:430; number_of_response:1; }","duration":"245.676457ms","start":"2026-05-08T14:34:33.529679Z","end":"2026-05-08T14:34:33.775356Z","steps":["trace[563445912] 'process raft request' (duration: 245.464302ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T14:34:34.152941Z","caller":"traceutil/trace.go:171","msg":"trace[2137770265] transaction","detail":"{read_only:false; response_revision:431; number_of_response:1; }","duration":"374.006932ms","start":"2026-05-08T14:34:33.778882Z","end":"2026-05-08T14:34:34.152889Z","steps":["trace[2137770265] 'process raft request' (duration: 293.026338ms)","trace[2137770265] 'compare' (duration: 80.323408ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T14:34:34.153567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T14:34:33.778859Z","time spent":"374.173587ms","remote":"127.0.0.1:38074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T14:34:38.000793Z","caller":"traceutil/trace.go:171","msg":"trace[801025678] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"812.72951ms","start":"2026-05-08T14:34:37.188036Z","end":"2026-05-08T14:34:38.000766Z","steps":["trace[801025678] 'process raft request' (duration: 812.573665ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T14:34:38.000941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T14:34:37.188018Z","time spent":"812.854842ms","remote":"127.0.0.1:37860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-08T14:34:38.950568Z","caller":"traceutil/trace.go:171","msg":"trace[1296052950] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"1.756572849s","start":"2026-05-08T14:34:37.193954Z","end":"2026-05-08T14:34:38.950527Z","steps":["trace[1296052950] 'process raft request' (duration: 1.756310892s)"],"step_count":1} {"level":"info","ts":"2026-05-08T14:34:38.950727Z","caller":"traceutil/trace.go:171","msg":"trace[355627720] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"1.754012447s","start":"2026-05-08T14:34:37.196624Z","end":"2026-05-08T14:34:38.950636Z","steps":["trace[355627720] 'process raft request' (duration: 1.753828672s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T14:34:38.950756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T14:34:37.193941Z","time spent":"1.756731543s","remote":"127.0.0.1:37860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-08T14:34:38.950883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T14:34:37.196615Z","time spent":"1.754191752s","remote":"127.0.0.1:37860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-08T14:34:38.950873Z","caller":"traceutil/trace.go:171","msg":"trace[372134804] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"1.7480321s","start":"2026-05-08T14:34:37.202774Z","end":"2026-05-08T14:34:38.950806Z","steps":["trace[372134804] 'process raft request' (duration: 1.747777364s)"],"step_count":1} {"level":"info","ts":"2026-05-08T14:34:38.950995Z","caller":"traceutil/trace.go:171","msg":"trace[979115070] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"1.74637928s","start":"2026-05-08T14:34:37.204593Z","end":"2026-05-08T14:34:38.950972Z","steps":["trace[979115070] 'process raft request' (duration: 1.746008391s)"],"step_count":1} {"level":"info","ts":"2026-05-08T14:34:38.951041Z","caller":"traceutil/trace.go:171","msg":"trace[2112235893] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"1.74434343s","start":"2026-05-08T14:34:37.206686Z","end":"2026-05-08T14:34:38.95103Z","steps":["trace[2112235893] 'process raft request' (duration: 1.744014602s)"],"step_count":1} {"level":"info","ts":"2026-05-08T14:34:38.951005Z","caller":"traceutil/trace.go:171","msg":"trace[149949365] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"1.7459376s","start":"2026-05-08T14:34:37.204985Z","end":"2026-05-08T14:34:38.950922Z","steps":["trace[149949365] 'process raft request' (duration: 1.745664073s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T14:34:38.951089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T14:34:37.20668Z","time spent":"1.744388481s","remote":"127.0.0.1:37860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-08T14:34:38.951094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T14:34:37.202768Z","time spent":"1.748231715s","remote":"127.0.0.1:37860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-08T14:34:38.951126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T14:34:37.204572Z","time spent":"1.746471812s","remote":"127.0.0.1:37860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-08T14:34:38.951199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T14:34:37.204979Z","time spent":"1.746125764s","remote":"127.0.0.1:37860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-08T14:34:38.950887Z","caller":"traceutil/trace.go:171","msg":"trace[137510047] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"1.748107442s","start":"2026-05-08T14:34:37.202727Z","end":"2026-05-08T14:34:38.950835Z","steps":["trace[137510047] 'process raft request' (duration: 1.747777774s)"],"step_count":1} {"level":"info","ts":"2026-05-08T14:34:38.951245Z","caller":"traceutil/trace.go:171","msg":"trace[757994640] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"1.731832503s","start":"2026-05-08T14:34:37.2194Z","end":"2026-05-08T14:34:38.951232Z","steps":["trace[757994640] 'process raft request' (duration: 1.731510785s)"],"step_count":1} {"level":"info","ts":"2026-05-08T14:34:38.951295Z","caller":"traceutil/trace.go:171","msg":"trace[1157603226] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"1.719427569s","start":"2026-05-08T14:34:37.231856Z","end":"2026-05-08T14:34:38.951283Z","steps":["trace[1157603226] 'process raft request' (duration: 1.719202294s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T14:34:38.951304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T14:34:37.219373Z","time spent":"1.731904014s","remote":"127.0.0.1:37860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-08T14:34:38.951307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T14:34:37.202717Z","time spent":"1.748557232s","remote":"127.0.0.1:37860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-08T14:34:38.951345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T14:34:37.231841Z","time spent":"1.7194793s","remote":"127.0.0.1:37860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-08T14:34:38.951343Z","caller":"traceutil/trace.go:171","msg":"trace[742697998] linearizableReadLoop","detail":"{readStateIndex:500; appliedIndex:488; }","duration":"1.158565141s","start":"2026-05-08T14:34:37.792728Z","end":"2026-05-08T14:34:38.951293Z","steps":["trace[742697998] 'read index received' (duration: 207.972205ms)","trace[742697998] 'applied index is now lower than readState.Index' (duration: 950.592036ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T14:34:38.951423Z","caller":"traceutil/trace.go:171","msg":"trace[1370828269] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"1.038230273s","start":"2026-05-08T14:34:37.913183Z","end":"2026-05-08T14:34:38.951414Z","steps":["trace[1370828269] 'process raft request' (duration: 1.038044838s)"],"step_count":1} {"level":"info","ts":"2026-05-08T14:34:38.951425Z","caller":"traceutil/trace.go:171","msg":"trace[1866290633] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"1.732018037s","start":"2026-05-08T14:34:37.219386Z","end":"2026-05-08T14:34:38.951404Z","steps":["trace[1866290633] 'process raft request' (duration: 1.731427203s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T14:34:38.95148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T14:34:37.913156Z","time spent":"1.038288234s","remote":"127.0.0.1:38074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T14:34:38.951486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T14:34:37.219378Z","time spent":"1.732080809s","remote":"127.0.0.1:37860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-08T14:34:38.951601Z","caller":"traceutil/trace.go:171","msg":"trace[1027620705] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"1.592659234s","start":"2026-05-08T14:34:37.358916Z","end":"2026-05-08T14:34:38.951575Z","steps":["trace[1027620705] 'process raft request' (duration: 1.592236394s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T14:34:38.951563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.158820107s","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-05-08T14:34:38.951645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T14:34:37.358886Z","time spent":"1.592737907s","remote":"127.0.0.1:37860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-08T14:34:38.951664Z","caller":"traceutil/trace.go:171","msg":"trace[1459050131] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:485; }","duration":"1.15895677s","start":"2026-05-08T14:34:37.792693Z","end":"2026-05-08T14:34:38.95165Z","steps":["trace[1459050131] 'agreement among raft nodes before linearized reading' (duration: 1.158721275s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T14:34:38.951699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T14:34:37.792678Z","time spent":"1.159012981s","remote":"127.0.0.1:38074","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-08T14:34:39.099408Z","caller":"traceutil/trace.go:171","msg":"trace[1549342696] transaction","detail":"{read_only:false; number_of_response:1; response_revision:487; }","duration":"1.089157841s","start":"2026-05-08T14:34:38.010233Z","end":"2026-05-08T14:34:39.099391Z","steps":["trace[1549342696] 'process raft request' (duration: 1.08912905s)"],"step_count":1} {"level":"info","ts":"2026-05-08T14:34:39.099595Z","caller":"traceutil/trace.go:171","msg":"trace[216878180] linearizableReadLoop","detail":"{readStateIndex:504; appliedIndex:504; }","duration":"148.18092ms","start":"2026-05-08T14:34:38.951392Z","end":"2026-05-08T14:34:39.099573Z","steps":["trace[216878180] 'read index received' (duration: 148.17425ms)","trace[216878180] 'applied index is now lower than readState.Index' (duration: 5.47µs)"],"step_count":2} {"level":"info","ts":"2026-05-08T14:34:39.099621Z","caller":"traceutil/trace.go:171","msg":"trace[303881157] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"1.089825317s","start":"2026-05-08T14:34:38.009781Z","end":"2026-05-08T14:34:39.099607Z","steps":["trace[303881157] 'process raft request' (duration: 1.0895369s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T14:34:39.099736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"933.151839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-08T14:34:39.099766Z","caller":"traceutil/trace.go:171","msg":"trace[1507976918] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:487; }","duration":"933.223641ms","start":"2026-05-08T14:34:38.166535Z","end":"2026-05-08T14:34:39.099759Z","steps":["trace[1507976918] 'agreement among raft nodes before linearized reading' (duration: 933.138429ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T14:34:39.099742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"645.065801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-08T14:34:39.099819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.224449824s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T14:34:39.099841Z","caller":"traceutil/trace.go:171","msg":"trace[1295701977] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:487; }","duration":"1.224507705s","start":"2026-05-08T14:34:37.875329Z","end":"2026-05-08T14:34:39.099837Z","steps":["trace[1295701977] 'agreement among raft nodes before linearized reading' (duration: 1.224475035s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T14:34:39.099833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"649.786038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-08T14:34:39.099887Z","caller":"traceutil/trace.go:171","msg":"trace[1187623311] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:487; }","duration":"645.257526ms","start":"2026-05-08T14:34:38.454601Z","end":"2026-05-08T14:34:39.099859Z","steps":["trace[1187623311] 'agreement among raft nodes before linearized reading' (duration: 645.033911ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T14:34:39.099963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T14:34:38.454582Z","time spent":"645.368108ms","remote":"127.0.0.1:37820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-08T14:34:39.099982Z","caller":"traceutil/trace.go:171","msg":"trace[134365227] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"1.090816261s","start":"2026-05-08T14:34:38.009156Z","end":"2026-05-08T14:34:39.099972Z","steps":["trace[134365227] 'process raft request' (duration: 1.090059912s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T14:34:39.099857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T14:34:37.875305Z","time spent":"1.224548307s","remote":"127.0.0.1:37810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-08T14:34:39.099969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.490664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.109\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-08T14:34:39.100064Z","caller":"traceutil/trace.go:171","msg":"trace[923869894] range","detail":"{range_begin:/registry/masterleases/199.204.45.109; range_end:; response_count:1; response_revision:487; }","duration":"325.627427ms","start":"2026-05-08T14:34:38.774428Z","end":"2026-05-08T14:34:39.100056Z","steps":["trace[923869894] 'agreement among raft nodes before linearized reading' (duration: 325.441162ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T14:34:39.100064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T14:34:38.009136Z","time spent":"1.090888102s","remote":"127.0.0.1:38354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":995,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-08T14:34:39.100136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T14:34:38.774405Z","time spent":"325.720879ms","remote":"127.0.0.1:37844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.109\" "} {"level":"info","ts":"2026-05-08T14:34:39.099906Z","caller":"traceutil/trace.go:171","msg":"trace[617708078] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:487; }","duration":"649.914621ms","start":"2026-05-08T14:34:38.449976Z","end":"2026-05-08T14:34:39.099891Z","steps":["trace[617708078] 'agreement among raft nodes before linearized reading' (duration: 649.694725ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T14:34:39.100187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T14:34:38.01021Z","time spent":"1.089248712s","remote":"127.0.0.1:37860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":102,"response count":0,"response size":7366,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T14:34:39.100227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T14:34:38.449919Z","time spent":"650.284279ms","remote":"127.0.0.1:38074","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-08T14:34:39.099748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T14:34:38.009773Z","time spent":"1.089931429s","remote":"127.0.0.1:37860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7317,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T14:34:39.099788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T14:34:38.16652Z","time spent":"933.262751ms","remote":"127.0.0.1:38074","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-05-08T14:34:39.105025Z","caller":"traceutil/trace.go:171","msg":"trace[1088850759] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"140.79382ms","start":"2026-05-08T14:34:38.964205Z","end":"2026-05-08T14:34:39.104999Z","steps":["trace[1088850759] 'process raft request' (duration: 140.542574ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T14:34:39.105312Z","caller":"traceutil/trace.go:171","msg":"trace[965237965] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"136.805311ms","start":"2026-05-08T14:34:38.968491Z","end":"2026-05-08T14:34:39.105297Z","steps":["trace[965237965] 'process raft request' (duration: 136.494354ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T14:34:39.105342Z","caller":"traceutil/trace.go:171","msg":"trace[1583240486] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"136.892654ms","start":"2026-05-08T14:34:38.968436Z","end":"2026-05-08T14:34:39.105328Z","steps":["trace[1583240486] 'process raft request' (duration: 136.485164ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T14:34:39.105358Z","caller":"traceutil/trace.go:171","msg":"trace[185371924] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"128.127439ms","start":"2026-05-08T14:34:38.977214Z","end":"2026-05-08T14:34:39.105342Z","steps":["trace[185371924] 'process raft request' (duration: 128.001526ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T14:34:39.10551Z","caller":"traceutil/trace.go:171","msg":"trace[638971001] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"132.842285ms","start":"2026-05-08T14:34:38.972654Z","end":"2026-05-08T14:34:39.105497Z","steps":["trace[638971001] 'process raft request' (duration: 132.393774ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T14:34:39.105555Z","caller":"traceutil/trace.go:171","msg":"trace[2040646573] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"135.174021ms","start":"2026-05-08T14:34:38.970372Z","end":"2026-05-08T14:34:39.105546Z","steps":["trace[2040646573] 'process raft request' (duration: 134.650558ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T14:34:39.105517Z","caller":"traceutil/trace.go:171","msg":"trace[1357899238] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"132.430473ms","start":"2026-05-08T14:34:38.973076Z","end":"2026-05-08T14:34:39.105507Z","steps":["trace[1357899238] 'process raft request' (duration: 132.004273ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T14:34:39.105616Z","caller":"traceutil/trace.go:171","msg":"trace[162173468] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"138.882833ms","start":"2026-05-08T14:34:38.966719Z","end":"2026-05-08T14:34:39.105601Z","steps":["trace[162173468] 'process raft request' (duration: 138.165695ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T14:34:39.105534Z","caller":"traceutil/trace.go:171","msg":"trace[1780713856] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"129.936523ms","start":"2026-05-08T14:34:38.975585Z","end":"2026-05-08T14:34:39.105522Z","steps":["trace[1780713856] 'process raft request' (duration: 129.546593ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T14:34:45.581825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.532699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T14:34:45.581905Z","caller":"traceutil/trace.go:171","msg":"trace[493103091] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:585; }","duration":"126.662773ms","start":"2026-05-08T14:34:45.455227Z","end":"2026-05-08T14:34:45.58189Z","steps":["trace[493103091] 'range keys from in-memory index tree' (duration: 126.464048ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T14:34:45.582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.302636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-08T14:34:45.582093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.672091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T14:34:45.582117Z","caller":"traceutil/trace.go:171","msg":"trace[208811322] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:585; }","duration":"113.46065ms","start":"2026-05-08T14:34:45.468619Z","end":"2026-05-08T14:34:45.58208Z","steps":["trace[208811322] 'count revisions from in-memory index tree' (duration: 113.239915ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T14:34:45.582157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.189719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T14:34:45.582179Z","caller":"traceutil/trace.go:171","msg":"trace[2127711230] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:585; }","duration":"199.354764ms","start":"2026-05-08T14:34:45.382819Z","end":"2026-05-08T14:34:45.582173Z","steps":["trace[2127711230] 'count revisions from in-memory index tree' (duration: 199.117918ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T14:34:45.582291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.976754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T14:34:45.582312Z","caller":"traceutil/trace.go:171","msg":"trace[367613152] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:585; }","duration":"199.023605ms","start":"2026-05-08T14:34:45.383282Z","end":"2026-05-08T14:34:45.582305Z","steps":["trace[367613152] 'range keys from in-memory index tree' (duration: 198.918402ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T14:34:45.582386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.531294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T14:34:45.582406Z","caller":"traceutil/trace.go:171","msg":"trace[1824697921] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:585; }","duration":"138.577935ms","start":"2026-05-08T14:34:45.443822Z","end":"2026-05-08T14:34:45.5824Z","steps":["trace[1824697921] 'range keys from in-memory index tree' (duration: 138.486113ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T14:34:45.58252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.494742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T14:34:45.582542Z","caller":"traceutil/trace.go:171","msg":"trace[642845759] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:585; }","duration":"138.554683ms","start":"2026-05-08T14:34:45.44398Z","end":"2026-05-08T14:34:45.582534Z","steps":["trace[642845759] 'count revisions from in-memory index tree' (duration: 138.40617ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T14:34:45.582124Z","caller":"traceutil/trace.go:171","msg":"trace[547291689] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:585; }","duration":"199.763423ms","start":"2026-05-08T14:34:45.382353Z","end":"2026-05-08T14:34:45.582116Z","steps":["trace[547291689] 'range keys from in-memory index tree' (duration: 199.568118ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T14:34:45.583112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.513565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T14:34:45.583138Z","caller":"traceutil/trace.go:171","msg":"trace[1519830838] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:585; }","duration":"114.557336ms","start":"2026-05-08T14:34:45.468573Z","end":"2026-05-08T14:34:45.583131Z","steps":["trace[1519830838] 'range keys from in-memory index tree' (duration: 114.411623ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T14:34:45.583144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.108905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T14:34:45.58317Z","caller":"traceutil/trace.go:171","msg":"trace[1306075843] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:585; }","duration":"114.144347ms","start":"2026-05-08T14:34:45.469018Z","end":"2026-05-08T14:34:45.583163Z","steps":["trace[1306075843] 'range keys from in-memory index tree' (duration: 114.081165ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T14:34:45.932779Z","caller":"traceutil/trace.go:171","msg":"trace[1203544344] linearizableReadLoop","detail":"{readStateIndex:606; appliedIndex:605; }","duration":"409.147952ms","start":"2026-05-08T14:34:45.523607Z","end":"2026-05-08T14:34:45.932755Z","steps":["trace[1203544344] 'read index received' (duration: 408.333352ms)","trace[1203544344] 'applied index is now lower than readState.Index' (duration: 813.69µs)"],"step_count":2} {"level":"info","ts":"2026-05-08T14:34:45.932904Z","caller":"traceutil/trace.go:171","msg":"trace[1240565443] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"425.630685ms","start":"2026-05-08T14:34:45.507263Z","end":"2026-05-08T14:34:45.932894Z","steps":["trace[1240565443] 'process raft request' (duration: 424.938859ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T14:34:45.93303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T14:34:45.50725Z","time spent":"425.681527ms","remote":"127.0.0.1:37908","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036305,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T14:34:45.933092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.464049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T14:34:45.933159Z","caller":"traceutil/trace.go:171","msg":"trace[1534875196] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:586; }","duration":"409.569582ms","start":"2026-05-08T14:34:45.523573Z","end":"2026-05-08T14:34:45.933143Z","steps":["trace[1534875196] 'agreement among raft nodes before linearized reading' (duration: 409.44688ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T14:34:45.933196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T14:34:45.523561Z","time spent":"409.627404ms","remote":"127.0.0.1:42574","response type":"/etcdserverpb.KV/Range","request count":0,"request size":109,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" limit:10000 "} {"level":"warn","ts":"2026-05-08T14:34:45.93323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.805041ms","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":"warn","ts":"2026-05-08T14:34:45.933267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.228114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T14:34:45.933387Z","caller":"traceutil/trace.go:171","msg":"trace[58592986] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:586; }","duration":"409.48742ms","start":"2026-05-08T14:34:45.52388Z","end":"2026-05-08T14:34:45.933367Z","steps":["trace[58592986] 'agreement among raft nodes before linearized reading' (duration: 409.153932ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T14:34:45.933461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.186901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T14:34:45.933514Z","caller":"traceutil/trace.go:171","msg":"trace[1854528109] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:586; }","duration":"273.253783ms","start":"2026-05-08T14:34:45.660246Z","end":"2026-05-08T14:34:45.933499Z","steps":["trace[1854528109] 'agreement among raft nodes before linearized reading' (duration: 273.173011ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T14:34:45.933481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T14:34:45.523861Z","time spent":"409.604783ms","remote":"127.0.0.1:42574","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-08T14:34:45.933692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.57178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T14:34:45.93374Z","caller":"traceutil/trace.go:171","msg":"trace[510764478] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:586; }","duration":"168.63821ms","start":"2026-05-08T14:34:45.765083Z","end":"2026-05-08T14:34:45.933721Z","steps":["trace[510764478] 'agreement among raft nodes before linearized reading' (duration: 168.583369ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T14:34:45.933756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.969682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T14:34:45.933786Z","caller":"traceutil/trace.go:171","msg":"trace[164002561] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:586; }","duration":"123.042164ms","start":"2026-05-08T14:34:45.810736Z","end":"2026-05-08T14:34:45.933778Z","steps":["trace[164002561] 'agreement among raft nodes before linearized reading' (duration: 122.996073ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T14:34:45.93391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.597506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T14:34:45.933948Z","caller":"traceutil/trace.go:171","msg":"trace[466710245] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:586; }","duration":"205.668698ms","start":"2026-05-08T14:34:45.72827Z","end":"2026-05-08T14:34:45.933939Z","steps":["trace[466710245] 'agreement among raft nodes before linearized reading' (duration: 205.609216ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T14:34:45.934107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.068204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-08T14:34:45.934119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.419216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T14:34:45.934138Z","caller":"traceutil/trace.go:171","msg":"trace[773984816] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:586; }","duration":"123.118995ms","start":"2026-05-08T14:34:45.811012Z","end":"2026-05-08T14:34:45.934131Z","steps":["trace[773984816] 'agreement among raft nodes before linearized reading' (duration: 123.078104ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T14:34:45.934152Z","caller":"traceutil/trace.go:171","msg":"trace[617088756] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:586; }","duration":"206.463827ms","start":"2026-05-08T14:34:45.727676Z","end":"2026-05-08T14:34:45.93414Z","steps":["trace[617088756] 'agreement among raft nodes before linearized reading' (duration: 206.417225ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T14:34:45.934196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.363351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-08T14:34:45.934305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.643292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T14:34:45.934335Z","caller":"traceutil/trace.go:171","msg":"trace[337765202] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:586; }","duration":"274.511694ms","start":"2026-05-08T14:34:45.659777Z","end":"2026-05-08T14:34:45.934288Z","steps":["trace[337765202] 'agreement among raft nodes before linearized reading' (duration: 274.350319ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T14:34:45.934379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.133463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T14:34:45.93441Z","caller":"traceutil/trace.go:171","msg":"trace[2118493729] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:586; }","duration":"238.206805ms","start":"2026-05-08T14:34:45.696197Z","end":"2026-05-08T14:34:45.934404Z","steps":["trace[2118493729] 'agreement among raft nodes before linearized reading' (duration: 238.164004ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T14:34:45.934536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.521384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T14:34:45.93457Z","caller":"traceutil/trace.go:171","msg":"trace[1039937973] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:586; }","duration":"238.568595ms","start":"2026-05-08T14:34:45.695993Z","end":"2026-05-08T14:34:45.934562Z","steps":["trace[1039937973] 'agreement among raft nodes before linearized reading' (duration: 238.521044ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T14:34:45.934685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.91097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T14:34:45.934718Z","caller":"traceutil/trace.go:171","msg":"trace[12636103] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:586; }","duration":"273.95478ms","start":"2026-05-08T14:34:45.660756Z","end":"2026-05-08T14:34:45.93471Z","steps":["trace[12636103] 'agreement among raft nodes before linearized reading' (duration: 273.907579ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T14:34:45.934718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.970973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T14:34:45.934791Z","caller":"traceutil/trace.go:171","msg":"trace[2106053792] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:586; }","duration":"328.054366ms","start":"2026-05-08T14:34:45.606724Z","end":"2026-05-08T14:34:45.934779Z","steps":["trace[2106053792] 'agreement among raft nodes before linearized reading' (duration: 327.933923ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T14:34:45.934881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T14:34:45.606717Z","time spent":"328.151028ms","remote":"127.0.0.1:42592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:500 "} {"level":"warn","ts":"2026-05-08T14:34:45.93518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.501576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-08T14:34:45.936411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.001002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T14:34:45.936539Z","caller":"traceutil/trace.go:171","msg":"trace[871789920] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:586; }","duration":"412.163816ms","start":"2026-05-08T14:34:45.524357Z","end":"2026-05-08T14:34:45.936521Z","steps":["trace[871789920] 'agreement among raft nodes before linearized reading' (duration: 412.004172ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T14:34:45.93662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T14:34:45.524346Z","time spent":"412.251088ms","remote":"127.0.0.1:42574","response type":"/etcdserverpb.KV/Range","request count":0,"request size":109,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" limit:500 "} {"level":"warn","ts":"2026-05-08T14:34:45.938486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.608087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T14:34:45.938535Z","caller":"traceutil/trace.go:171","msg":"trace[556725122] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:586; }","duration":"354.755761ms","start":"2026-05-08T14:34:45.583769Z","end":"2026-05-08T14:34:45.938524Z","steps":["trace[556725122] 'agreement among raft nodes before linearized reading' (duration: 354.665398ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T14:34:45.938568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T14:34:45.583752Z","time spent":"354.807281ms","remote":"127.0.0.1:42582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true "} {"level":"warn","ts":"2026-05-08T14:34:45.938729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.410813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T14:34:45.938765Z","caller":"traceutil/trace.go:171","msg":"trace[592017442] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:586; }","duration":"332.481684ms","start":"2026-05-08T14:34:45.606274Z","end":"2026-05-08T14:34:45.938756Z","steps":["trace[592017442] 'agreement among raft nodes before linearized reading' (duration: 332.421883ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T14:34:45.938797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T14:34:45.606257Z","time spent":"332.528586ms","remote":"127.0.0.1:42592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:10000 "} {"level":"warn","ts":"2026-05-08T14:34:45.938893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.090648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T14:34:45.938979Z","caller":"traceutil/trace.go:171","msg":"trace[1532458574] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:586; }","duration":"355.206251ms","start":"2026-05-08T14:34:45.583759Z","end":"2026-05-08T14:34:45.938965Z","steps":["trace[1532458574] 'agreement among raft nodes before linearized reading' (duration: 355.090628ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T14:34:45.939043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T14:34:45.583725Z","time spent":"355.304914ms","remote":"127.0.0.1:42582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:500 "} {"level":"warn","ts":"2026-05-08T14:34:45.939275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.141893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T14:34:45.939357Z","caller":"traceutil/trace.go:171","msg":"trace[42141627] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:586; }","duration":"356.255606ms","start":"2026-05-08T14:34:45.583089Z","end":"2026-05-08T14:34:45.939345Z","steps":["trace[42141627] 'agreement among raft nodes before linearized reading' (duration: 356.133043ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T14:34:45.939434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T14:34:45.583073Z","time spent":"356.338779ms","remote":"127.0.0.1:42582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:10000 "} {"level":"info","ts":"2026-05-08T14:34:45.935226Z","caller":"traceutil/trace.go:171","msg":"trace[137545131] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:586; }","duration":"328.577469ms","start":"2026-05-08T14:34:45.606638Z","end":"2026-05-08T14:34:45.935216Z","steps":["trace[137545131] 'agreement among raft nodes before linearized reading' (duration: 328.498567ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T14:34:45.939691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T14:34:45.606623Z","time spent":"333.052197ms","remote":"127.0.0.1:42592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true "} {"level":"info","ts":"2026-05-08T14:34:45.934342Z","caller":"traceutil/trace.go:171","msg":"trace[2045832708] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:586; }","duration":"206.702103ms","start":"2026-05-08T14:34:45.727631Z","end":"2026-05-08T14:34:45.934334Z","steps":["trace[2045832708] 'agreement among raft nodes before linearized reading' (duration: 206.650052ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T14:34:45.940054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.318845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T14:34:45.940094Z","caller":"traceutil/trace.go:171","msg":"trace[1751710217] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:586; }","duration":"146.381766ms","start":"2026-05-08T14:34:45.793706Z","end":"2026-05-08T14:34:45.940087Z","steps":["trace[1751710217] 'agreement among raft nodes before linearized reading' (duration: 146.327495ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T14:34:45.940099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.358379ms","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-05-08T14:34:45.94013Z","caller":"traceutil/trace.go:171","msg":"trace[1628948926] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:586; }","duration":"129.412831ms","start":"2026-05-08T14:34:45.81071Z","end":"2026-05-08T14:34:45.940123Z","steps":["trace[1628948926] 'agreement among raft nodes before linearized reading' (duration: 129.36506ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T14:34:45.940229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.54933ms","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-05-08T14:34:45.940959Z","caller":"traceutil/trace.go:171","msg":"trace[229890488] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:586; }","duration":"147.282148ms","start":"2026-05-08T14:34:45.793632Z","end":"2026-05-08T14:34:45.940914Z","steps":["trace[229890488] 'agreement among raft nodes before linearized reading' (duration: 146.54269ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T14:34:45.940271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.626742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T14:34:45.941047Z","caller":"traceutil/trace.go:171","msg":"trace[825151728] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:586; }","duration":"147.463192ms","start":"2026-05-08T14:34:45.793573Z","end":"2026-05-08T14:34:45.941036Z","steps":["trace[825151728] 'agreement among raft nodes before linearized reading' (duration: 146.677613ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T14:34:45.933335Z","caller":"traceutil/trace.go:171","msg":"trace[213664967] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:586; }","duration":"167.984245ms","start":"2026-05-08T14:34:45.765331Z","end":"2026-05-08T14:34:45.933315Z","steps":["trace[213664967] 'agreement among raft nodes before linearized reading' (duration: 167.760369ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T14:34:46.265196Z","caller":"traceutil/trace.go:171","msg":"trace[2083823772] linearizableReadLoop","detail":"{readStateIndex:607; appliedIndex:606; }","duration":"295.674094ms","start":"2026-05-08T14:34:45.969497Z","end":"2026-05-08T14:34:46.265171Z","steps":["trace[2083823772] 'read index received' (duration: 295.51278ms)","trace[2083823772] 'applied index is now lower than readState.Index' (duration: 160.134µs)"],"step_count":2} {"level":"warn","ts":"2026-05-08T14:34:46.265422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.887639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T14:34:46.265514Z","caller":"traceutil/trace.go:171","msg":"trace[941080835] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:587; }","duration":"296.041163ms","start":"2026-05-08T14:34:45.969451Z","end":"2026-05-08T14:34:46.265492Z","steps":["trace[941080835] 'agreement among raft nodes before linearized reading' (duration: 295.851028ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T14:34:46.265853Z","caller":"traceutil/trace.go:171","msg":"trace[1555737878] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"310.244941ms","start":"2026-05-08T14:34:45.955591Z","end":"2026-05-08T14:34:46.265836Z","steps":["trace[1555737878] 'process raft request' (duration: 309.36168ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T14:34:46.266007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T14:34:45.955581Z","time spent":"310.326313ms","remote":"127.0.0.1:37972","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T14:34:46.273975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.80913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-08T14:34:46.274011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.374577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T14:34:46.274048Z","caller":"traceutil/trace.go:171","msg":"trace[355731683] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:588; }","duration":"289.880281ms","start":"2026-05-08T14:34:45.984136Z","end":"2026-05-08T14:34:46.274016Z","steps":["trace[355731683] 'agreement among raft nodes before linearized reading' (duration: 288.860566ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T14:34:46.27409Z","caller":"traceutil/trace.go:171","msg":"trace[1128737111] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:588; }","duration":"304.470159ms","start":"2026-05-08T14:34:45.969584Z","end":"2026-05-08T14:34:46.274055Z","steps":["trace[1128737111] 'agreement among raft nodes before linearized reading' (duration: 303.405103ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T14:34:46.274145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T14:34:45.96958Z","time spent":"304.551411ms","remote":"127.0.0.1:42652","response type":"/etcdserverpb.KV/Range","request count":0,"request size":109,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 "} {"level":"warn","ts":"2026-05-08T14:34:46.27438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.490097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T14:34:46.274426Z","caller":"traceutil/trace.go:171","msg":"trace[354092287] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:588; }","duration":"290.550498ms","start":"2026-05-08T14:34:45.983867Z","end":"2026-05-08T14:34:46.274418Z","steps":["trace[354092287] 'agreement among raft nodes before linearized reading' (duration: 290.487996ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T14:34:46.274536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.568458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T14:34:46.274546Z","caller":"traceutil/trace.go:171","msg":"trace[157539117] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"285.34177ms","start":"2026-05-08T14:34:45.989193Z","end":"2026-05-08T14:34:46.274535Z","steps":["trace[157539117] 'process raft request' (duration: 283.71262ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T14:34:46.2746Z","caller":"traceutil/trace.go:171","msg":"trace[1723657807] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:588; }","duration":"290.655061ms","start":"2026-05-08T14:34:45.983917Z","end":"2026-05-08T14:34:46.274573Z","steps":["trace[1723657807] 'agreement among raft nodes before linearized reading' (duration: 290.581839ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T14:34:46.963254Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334367538482790656,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-08T14:34:47.464123Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334367538482790656,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-08T14:34:47.539507Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.193888997s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-08T14:34:47.733122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"925.379079ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T14:34:47.733244Z","caller":"traceutil/trace.go:171","msg":"trace[2040096308] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:588; }","duration":"925.505382ms","start":"2026-05-08T14:34:46.80771Z","end":"2026-05-08T14:34:47.733215Z","steps":["trace[2040096308] 'range keys from in-memory index tree' (duration: 925.364059ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T14:34:47.734022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.892089ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-08T14:34:47.734096Z","caller":"traceutil/trace.go:171","msg":"trace[931325074] linearizableReadLoop","detail":"{readStateIndex:609; appliedIndex:608; }","duration":"1.271513688s","start":"2026-05-08T14:34:46.462569Z","end":"2026-05-08T14:34:47.734083Z","steps":["trace[931325074] 'read index received' (duration: 1.077094706s)","trace[931325074] 'applied index is now lower than readState.Index' (duration: 194.417902ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T14:34:47.734187Z","caller":"traceutil/trace.go:171","msg":"trace[1519534723] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"1.442033475s","start":"2026-05-08T14:34:46.292143Z","end":"2026-05-08T14:34:47.734176Z","steps":["trace[1519534723] 'process raft request' (duration: 1.247700605s)","trace[1519534723] 'compare' (duration: 193.416208ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T14:34:47.734235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T14:34:46.292104Z","time spent":"1.442109317s","remote":"127.0.0.1:37972","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2735,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-08T14:34:47.734449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.271888106s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T14:34:47.734478Z","caller":"traceutil/trace.go:171","msg":"trace[620676031] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:589; }","duration":"1.271954598s","start":"2026-05-08T14:34:46.462514Z","end":"2026-05-08T14:34:47.734469Z","steps":["trace[620676031] 'agreement among raft nodes before linearized reading' (duration: 1.271879136s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T14:34:47.734505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T14:34:46.462489Z","time spent":"1.27201052s","remote":"127.0.0.1:37810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-08T14:34:47.734729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"578.317346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-08T14:34:47.734779Z","caller":"traceutil/trace.go:171","msg":"trace[576144524] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:589; }","duration":"578.407148ms","start":"2026-05-08T14:34:47.15636Z","end":"2026-05-08T14:34:47.734767Z","steps":["trace[576144524] 'agreement among raft nodes before linearized reading' (duration: 578.261235ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T14:34:47.734836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T14:34:47.156335Z","time spent":"578.493611ms","remote":"127.0.0.1:38074","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-08T14:34:47.735145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"481.105725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-08T14:34:47.735185Z","caller":"traceutil/trace.go:171","msg":"trace[327149373] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:589; }","duration":"481.159376ms","start":"2026-05-08T14:34:47.254017Z","end":"2026-05-08T14:34:47.735176Z","steps":["trace[327149373] 'agreement among raft nodes before linearized reading' (duration: 481.034783ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T14:34:47.735157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"573.048498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-08T14:34:47.735265Z","caller":"traceutil/trace.go:171","msg":"trace[1087211374] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:589; }","duration":"573.180381ms","start":"2026-05-08T14:34:47.162056Z","end":"2026-05-08T14:34:47.735236Z","steps":["trace[1087211374] 'agreement among raft nodes before linearized reading' (duration: 572.850783ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T14:34:47.735277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"578.746866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-08T14:34:47.735347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T14:34:47.162045Z","time spent":"573.287003ms","remote":"127.0.0.1:38074","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-08T14:34:47.73539Z","caller":"traceutil/trace.go:171","msg":"trace[804993659] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:589; }","duration":"578.922422ms","start":"2026-05-08T14:34:47.156439Z","end":"2026-05-08T14:34:47.735361Z","steps":["trace[804993659] 'agreement among raft nodes before linearized reading' (duration: 578.512452ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T14:34:47.735451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T14:34:47.156387Z","time spent":"579.054384ms","remote":"127.0.0.1:38074","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-08T14:34:47.735212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T14:34:47.254011Z","time spent":"481.195947ms","remote":"127.0.0.1:38074","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-05-08T14:35:00.098548Z","caller":"traceutil/trace.go:171","msg":"trace[1175914401] transaction","detail":"{read_only:false; response_revision:732; number_of_response:1; }","duration":"293.225174ms","start":"2026-05-08T14:34:59.805295Z","end":"2026-05-08T14:35:00.09852Z","steps":["trace[1175914401] 'process raft request' (duration: 293.025159ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T14:35:00.122344Z","caller":"traceutil/trace.go:171","msg":"trace[686447015] linearizableReadLoop","detail":"{readStateIndex:758; appliedIndex:757; }","duration":"314.823671ms","start":"2026-05-08T14:34:59.807501Z","end":"2026-05-08T14:35:00.122324Z","steps":["trace[686447015] 'read index received' (duration: 291.0583ms)","trace[686447015] 'applied index is now lower than readState.Index' (duration: 23.763921ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T14:35:00.122555Z","caller":"traceutil/trace.go:171","msg":"trace[855714068] transaction","detail":"{read_only:false; response_revision:733; number_of_response:1; }","duration":"316.232786ms","start":"2026-05-08T14:34:59.806176Z","end":"2026-05-08T14:35:00.122409Z","steps":["trace[855714068] 'process raft request' (duration: 316.048452ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T14:35:00.122694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.163199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-08T14:35:00.122734Z","caller":"traceutil/trace.go:171","msg":"trace[1844560893] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:733; }","duration":"315.244242ms","start":"2026-05-08T14:34:59.807477Z","end":"2026-05-08T14:35:00.122722Z","steps":["trace[1844560893] 'agreement among raft nodes before linearized reading' (duration: 315.073678ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T14:35:00.122743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.038427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-08T14:35:00.122801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T14:34:59.806156Z","time spent":"316.474443ms","remote":"127.0.0.1:38074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T14:35:00.122812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T14:34:59.807467Z","time spent":"315.335244ms","remote":"127.0.0.1:38074","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-08T14:35:00.122826Z","caller":"traceutil/trace.go:171","msg":"trace[1330429167] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:733; }","duration":"315.151079ms","start":"2026-05-08T14:34:59.807656Z","end":"2026-05-08T14:35:00.122807Z","steps":["trace[1330429167] 'agreement among raft nodes before linearized reading' (duration: 314.937784ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T14:35:00.122871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T14:34:59.80765Z","time spent":"315.212601ms","remote":"127.0.0.1:38074","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-08T14:35:02.627765Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334367538482790960,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-08T14:35:03.005344Z","caller":"traceutil/trace.go:171","msg":"trace[831644619] transaction","detail":"{read_only:false; response_revision:747; number_of_response:1; }","duration":"898.402121ms","start":"2026-05-08T14:35:02.106903Z","end":"2026-05-08T14:35:03.005305Z","steps":["trace[831644619] 'process raft request' (duration: 843.186356ms)","trace[831644619] 'compare' (duration: 55.025791ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T14:35:03.005466Z","caller":"traceutil/trace.go:171","msg":"trace[629553859] linearizableReadLoop","detail":"{readStateIndex:772; appliedIndex:771; }","duration":"878.212074ms","start":"2026-05-08T14:35:02.127233Z","end":"2026-05-08T14:35:03.005445Z","steps":["trace[629553859] 'read index received' (duration: 822.793245ms)","trace[629553859] 'applied index is now lower than readState.Index' (duration: 55.417529ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T14:35:03.00554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T14:35:02.106875Z","time spent":"898.570075ms","remote":"127.0.0.1:38074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T14:35:03.00571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"878.46943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-08T14:35:03.005754Z","caller":"traceutil/trace.go:171","msg":"trace[1374619244] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:748; }","duration":"878.554842ms","start":"2026-05-08T14:35:02.127189Z","end":"2026-05-08T14:35:03.005744Z","steps":["trace[1374619244] 'agreement among raft nodes before linearized reading' (duration: 878.374628ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T14:35:03.005787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T14:35:02.127161Z","time spent":"878.618204ms","remote":"127.0.0.1:38074","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-08T14:35:03.006049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"874.942378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-08T14:35:03.006112Z","caller":"traceutil/trace.go:171","msg":"trace[861421006] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:748; }","duration":"875.02535ms","start":"2026-05-08T14:35:02.131073Z","end":"2026-05-08T14:35:03.006098Z","steps":["trace[861421006] 'agreement among raft nodes before linearized reading' (duration: 874.893387ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T14:35:03.006158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T14:35:02.131064Z","time spent":"875.083731ms","remote":"127.0.0.1:38074","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-08T14:35:03.006277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"549.81212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T14:35:03.006349Z","caller":"traceutil/trace.go:171","msg":"trace[765791794] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:748; }","duration":"549.915813ms","start":"2026-05-08T14:35:02.45641Z","end":"2026-05-08T14:35:03.006326Z","steps":["trace[765791794] 'agreement among raft nodes before linearized reading' (duration: 549.8045ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T14:35:03.006402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T14:35:02.456387Z","time spent":"550.004465ms","remote":"127.0.0.1:37810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-08T14:35:03.006428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"874.958928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-08T14:35:03.00647Z","caller":"traceutil/trace.go:171","msg":"trace[796239383] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:748; }","duration":"875.01953ms","start":"2026-05-08T14:35:02.131439Z","end":"2026-05-08T14:35:03.006459Z","steps":["trace[796239383] 'agreement among raft nodes before linearized reading' (duration: 874.885887ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T14:35:03.006512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T14:35:02.13143Z","time spent":"875.075361ms","remote":"127.0.0.1:38074","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-08T14:35:38.090542Z","caller":"traceutil/trace.go:171","msg":"trace[1969275820] linearizableReadLoop","detail":"{readStateIndex:1001; appliedIndex:1000; }","duration":"145.962382ms","start":"2026-05-08T14:35:37.944556Z","end":"2026-05-08T14:35:38.090518Z","steps":["trace[1969275820] 'read index received' (duration: 145.685635ms)","trace[1969275820] 'applied index is now lower than readState.Index' (duration: 275.637µs)"],"step_count":2} {"level":"warn","ts":"2026-05-08T14:35:38.090674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.089754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T14:35:38.090711Z","caller":"traceutil/trace.go:171","msg":"trace[1959686506] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:967; }","duration":"146.195718ms","start":"2026-05-08T14:35:37.944509Z","end":"2026-05-08T14:35:38.090704Z","steps":["trace[1959686506] 'agreement among raft nodes before linearized reading' (duration: 146.102926ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T14:35:38.090795Z","caller":"traceutil/trace.go:171","msg":"trace[1898356268] transaction","detail":"{read_only:false; response_revision:967; number_of_response:1; }","duration":"380.001715ms","start":"2026-05-08T14:35:37.710768Z","end":"2026-05-08T14:35:38.09077Z","steps":["trace[1898356268] 'process raft request' (duration: 379.589566ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T14:35:38.090958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T14:35:37.710756Z","time spent":"380.122538ms","remote":"127.0.0.1:38074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T14:35:39.205376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T14:35:38.779063Z","time spent":"426.30116ms","remote":"127.0.0.1:37844","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-05-08T14:35:39.559411Z","caller":"traceutil/trace.go:171","msg":"trace[1051603011] linearizableReadLoop","detail":"{readStateIndex:1004; appliedIndex:1003; }","duration":"333.953625ms","start":"2026-05-08T14:35:39.225436Z","end":"2026-05-08T14:35:39.559389Z","steps":["trace[1051603011] 'read index received' (duration: 299.469751ms)","trace[1051603011] 'applied index is now lower than readState.Index' (duration: 34.483054ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T14:35:39.559454Z","caller":"traceutil/trace.go:171","msg":"trace[1216865831] transaction","detail":"{read_only:false; response_revision:969; number_of_response:1; }","duration":"352.745401ms","start":"2026-05-08T14:35:39.206651Z","end":"2026-05-08T14:35:39.559397Z","steps":["trace[1216865831] 'process raft request' (duration: 318.242915ms)","trace[1216865831] 'compare' (duration: 34.337452ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T14:35:39.559596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.035427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-08T14:35:39.559595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T14:35:39.206619Z","time spent":"352.890184ms","remote":"127.0.0.1:37844","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T14:35:39.559663Z","caller":"traceutil/trace.go:171","msg":"trace[1630274317] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:969; }","duration":"103.153579ms","start":"2026-05-08T14:35:39.456492Z","end":"2026-05-08T14:35:39.559646Z","steps":["trace[1630274317] 'agreement among raft nodes before linearized reading' (duration: 103.022106ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T14:35:39.559715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.218919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-05-08T14:35:39.5597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.244592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-08T14:35:39.559747Z","caller":"traceutil/trace.go:171","msg":"trace[1263963954] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:969; }","duration":"333.267819ms","start":"2026-05-08T14:35:39.226465Z","end":"2026-05-08T14:35:39.559733Z","steps":["trace[1263963954] 'agreement among raft nodes before linearized reading' (duration: 333.220128ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T14:35:39.559772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T14:35:39.226454Z","time spent":"333.31369ms","remote":"127.0.0.1:38074","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-08T14:35:39.559777Z","caller":"traceutil/trace.go:171","msg":"trace[944234048] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:969; }","duration":"334.374185ms","start":"2026-05-08T14:35:39.225382Z","end":"2026-05-08T14:35:39.559757Z","steps":["trace[944234048] 'agreement among raft nodes before linearized reading' (duration: 334.086278ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T14:35:39.559675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.821984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-05-08T14:35:39.559822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T14:35:39.225348Z","time spent":"334.466527ms","remote":"127.0.0.1:38074","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-08T14:35:39.559621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.927185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-08T14:35:39.559903Z","caller":"traceutil/trace.go:171","msg":"trace[451826464] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:969; }","duration":"334.209652ms","start":"2026-05-08T14:35:39.225663Z","end":"2026-05-08T14:35:39.559873Z","steps":["trace[451826464] 'agreement among raft nodes before linearized reading' (duration: 333.842613ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T14:35:39.559825Z","caller":"traceutil/trace.go:171","msg":"trace[645382727] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:969; }","duration":"328.985528ms","start":"2026-05-08T14:35:39.230833Z","end":"2026-05-08T14:35:39.559818Z","steps":["trace[645382727] 'agreement among raft nodes before linearized reading' (duration: 328.804954ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T14:35:39.559952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T14:35:39.230826Z","time spent":"329.116121ms","remote":"127.0.0.1:38074","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-08T14:35:39.559955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T14:35:39.225655Z","time spent":"334.280503ms","remote":"127.0.0.1:38074","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-08T14:35:39.822903Z","caller":"traceutil/trace.go:171","msg":"trace[1981660470] transaction","detail":"{read_only:false; response_revision:971; number_of_response:1; }","duration":"257.795476ms","start":"2026-05-08T14:35:39.565084Z","end":"2026-05-08T14:35:39.822879Z","steps":["trace[1981660470] 'process raft request' (duration: 257.634742ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T14:35:39.823111Z","caller":"traceutil/trace.go:171","msg":"trace[456691783] transaction","detail":"{read_only:false; response_revision:972; number_of_response:1; }","duration":"257.932399ms","start":"2026-05-08T14:35:39.565166Z","end":"2026-05-08T14:35:39.823098Z","steps":["trace[456691783] 'process raft request' (duration: 257.679723ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T14:35:39.823172Z","caller":"traceutil/trace.go:171","msg":"trace[1201290283] linearizableReadLoop","detail":"{readStateIndex:1007; appliedIndex:1004; }","duration":"257.848367ms","start":"2026-05-08T14:35:39.565302Z","end":"2026-05-08T14:35:39.82315Z","steps":["trace[1201290283] 'read index received' (duration: 54.284924ms)","trace[1201290283] 'applied index is now lower than readState.Index' (duration: 203.562613ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T14:35:39.824209Z","caller":"traceutil/trace.go:171","msg":"trace[691826156] transaction","detail":"{read_only:false; response_revision:973; number_of_response:1; }","duration":"255.46116ms","start":"2026-05-08T14:35:39.56826Z","end":"2026-05-08T14:35:39.823721Z","steps":["trace[691826156] 'process raft request' (duration: 254.796515ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T14:35:39.824302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.83314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T14:35:39.824396Z","caller":"traceutil/trace.go:171","msg":"trace[734666335] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:973; }","duration":"255.973863ms","start":"2026-05-08T14:35:39.568407Z","end":"2026-05-08T14:35:39.82438Z","steps":["trace[734666335] 'agreement among raft nodes before linearized reading' (duration: 255.828598ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T14:35:39.824598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.21438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T14:35:39.824716Z","caller":"traceutil/trace.go:171","msg":"trace[979098451] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:973; }","duration":"108.353993ms","start":"2026-05-08T14:35:39.716338Z","end":"2026-05-08T14:35:39.824692Z","steps":["trace[979098451] 'agreement among raft nodes before linearized reading' (duration: 107.925113ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T14:35:39.824913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.585588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-08T14:35:39.824982Z","caller":"traceutil/trace.go:171","msg":"trace[2110243853] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:973; }","duration":"259.67012ms","start":"2026-05-08T14:35:39.565282Z","end":"2026-05-08T14:35:39.824952Z","steps":["trace[2110243853] 'agreement among raft nodes before linearized reading' (duration: 259.163888ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T14:36:04.232916Z","caller":"traceutil/trace.go:171","msg":"trace[1001129075] transaction","detail":"{read_only:false; response_revision:1050; number_of_response:1; }","duration":"415.922245ms","start":"2026-05-08T14:36:03.816946Z","end":"2026-05-08T14:36:04.232869Z","steps":["trace[1001129075] 'process raft request' (duration: 373.971583ms)","trace[1001129075] 'compare' (duration: 41.417838ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T14:36:04.233101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T14:36:03.816916Z","time spent":"416.096459ms","remote":"127.0.0.1:38074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T14:36:04.270786Z","caller":"traceutil/trace.go:171","msg":"trace[738161644] linearizableReadLoop","detail":"{readStateIndex:1090; appliedIndex:1088; }","duration":"300.014194ms","start":"2026-05-08T14:36:03.970738Z","end":"2026-05-08T14:36:04.270752Z","steps":["trace[738161644] 'read index received' (duration: 220.302259ms)","trace[738161644] 'applied index is now lower than readState.Index' (duration: 79.709925ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T14:36:04.271143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.725967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-08T14:36:04.271207Z","caller":"traceutil/trace.go:171","msg":"trace[1994501737] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1050; }","duration":"299.8512ms","start":"2026-05-08T14:36:03.971342Z","end":"2026-05-08T14:36:04.271193Z","steps":["trace[1994501737] 'agreement among raft nodes before linearized reading' (duration: 299.617504ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T14:36:04.271208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.442733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-08T14:36:04.27125Z","caller":"traceutil/trace.go:171","msg":"trace[791145656] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1050; }","duration":"300.509665ms","start":"2026-05-08T14:36:03.970729Z","end":"2026-05-08T14:36:04.271238Z","steps":["trace[791145656] 'agreement among raft nodes before linearized reading' (duration: 300.206718ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T14:36:04.271292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T14:36:03.970653Z","time spent":"300.630958ms","remote":"127.0.0.1:38074","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-08T14:36:04.271406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.890496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-08T14:36:04.271536Z","caller":"traceutil/trace.go:171","msg":"trace[304533051] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1050; }","duration":"299.07812ms","start":"2026-05-08T14:36:03.972433Z","end":"2026-05-08T14:36:04.271511Z","steps":["trace[304533051] 'agreement among raft nodes before linearized reading' (duration: 298.62648ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T14:36:04.450091Z","caller":"traceutil/trace.go:171","msg":"trace[1595104958] transaction","detail":"{read_only:false; response_revision:1055; number_of_response:1; }","duration":"150.312874ms","start":"2026-05-08T14:36:04.29975Z","end":"2026-05-08T14:36:04.450062Z","steps":["trace[1595104958] 'process raft request' (duration: 150.270773ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T14:36:04.450474Z","caller":"traceutil/trace.go:171","msg":"trace[1741684596] transaction","detail":"{read_only:false; response_revision:1052; number_of_response:1; }","duration":"173.83935ms","start":"2026-05-08T14:36:04.276598Z","end":"2026-05-08T14:36:04.450437Z","steps":["trace[1741684596] 'process raft request' (duration: 110.37327ms)","trace[1741684596] 'compare' (duration: 62.858306ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T14:36:04.450547Z","caller":"traceutil/trace.go:171","msg":"trace[1513957764] transaction","detail":"{read_only:false; response_revision:1054; number_of_response:1; }","duration":"173.775648ms","start":"2026-05-08T14:36:04.276754Z","end":"2026-05-08T14:36:04.45053Z","steps":["trace[1513957764] 'process raft request' (duration: 173.226896ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T14:36:04.450598Z","caller":"traceutil/trace.go:171","msg":"trace[489452194] transaction","detail":"{read_only:false; response_revision:1053; number_of_response:1; }","duration":"173.886211ms","start":"2026-05-08T14:36:04.276626Z","end":"2026-05-08T14:36:04.450512Z","steps":["trace[489452194] 'process raft request' (duration: 173.318108ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T14:36:06.418275Z","caller":"traceutil/trace.go:171","msg":"trace[505232776] linearizableReadLoop","detail":"{readStateIndex:1096; appliedIndex:1095; }","duration":"124.543796ms","start":"2026-05-08T14:36:06.293701Z","end":"2026-05-08T14:36:06.418245Z","steps":["trace[505232776] 'read index received' (duration: 124.236239ms)","trace[505232776] 'applied index is now lower than readState.Index' (duration: 306.367µs)"],"step_count":2} {"level":"warn","ts":"2026-05-08T14:36:06.418594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.873533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-08T14:36:06.418644Z","caller":"traceutil/trace.go:171","msg":"trace[936199762] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1056; }","duration":"124.993556ms","start":"2026-05-08T14:36:06.29364Z","end":"2026-05-08T14:36:06.418633Z","steps":["trace[936199762] 'agreement among raft nodes before linearized reading' (duration: 124.72926ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T14:36:06.418692Z","caller":"traceutil/trace.go:171","msg":"trace[781087185] transaction","detail":"{read_only:false; response_revision:1056; number_of_response:1; }","duration":"175.405078ms","start":"2026-05-08T14:36:06.243249Z","end":"2026-05-08T14:36:06.418654Z","steps":["trace[781087185] 'process raft request' (duration: 174.809084ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T14:36:08.548839Z","caller":"traceutil/trace.go:171","msg":"trace[914235396] transaction","detail":"{read_only:false; response_revision:1062; number_of_response:1; }","duration":"116.641124ms","start":"2026-05-08T14:36:08.43216Z","end":"2026-05-08T14:36:08.548801Z","steps":["trace[914235396] 'process raft request' (duration: 115.771954ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T14:36:08.879098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.384113ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-08T14:36:08.87924Z","caller":"traceutil/trace.go:171","msg":"trace[1396344954] linearizableReadLoop","detail":"{readStateIndex:1103; appliedIndex:1102; }","duration":"331.104118ms","start":"2026-05-08T14:36:08.548113Z","end":"2026-05-08T14:36:08.879217Z","steps":["trace[1396344954] 'read index received' (duration: 200.419498ms)","trace[1396344954] 'applied index is now lower than readState.Index' (duration: 130.6831ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T14:36:08.879607Z","caller":"traceutil/trace.go:171","msg":"trace[1804715276] transaction","detail":"{read_only:false; response_revision:1063; number_of_response:1; }","duration":"365.396173ms","start":"2026-05-08T14:36:08.514192Z","end":"2026-05-08T14:36:08.879588Z","steps":["trace[1804715276] 'process raft request' (duration: 234.419866ms)","trace[1804715276] 'compare' (duration: 130.194409ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T14:36:08.879694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T14:36:08.514167Z","time spent":"365.487326ms","remote":"127.0.0.1:38074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T14:36:08.879791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.256162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-08T14:36:08.879947Z","caller":"traceutil/trace.go:171","msg":"trace[1546353291] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1063; }","duration":"378.454436ms","start":"2026-05-08T14:36:08.50146Z","end":"2026-05-08T14:36:08.879915Z","steps":["trace[1546353291] 'agreement among raft nodes before linearized reading' (duration: 378.020057ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T14:36:08.880016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T14:36:08.501439Z","time spent":"378.562528ms","remote":"127.0.0.1:38074","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-08T14:36:08.880244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.547147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-08T14:36:08.880443Z","caller":"traceutil/trace.go:171","msg":"trace[727172651] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1063; }","duration":"246.818773ms","start":"2026-05-08T14:36:08.633595Z","end":"2026-05-08T14:36:08.880414Z","steps":["trace[727172651] 'agreement among raft nodes before linearized reading' (duration: 246.150657ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T14:36:08.880247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.743396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-08T14:36:08.880571Z","caller":"traceutil/trace.go:171","msg":"trace[357405926] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1063; }","duration":"286.119495ms","start":"2026-05-08T14:36:08.594439Z","end":"2026-05-08T14:36:08.880558Z","steps":["trace[357405926] 'agreement among raft nodes before linearized reading' (duration: 285.311536ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T14:36:08.880864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.197772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-08T14:36:08.88099Z","caller":"traceutil/trace.go:171","msg":"trace[403549318] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1063; }","duration":"273.162825ms","start":"2026-05-08T14:36:08.607799Z","end":"2026-05-08T14:36:08.880962Z","steps":["trace[403549318] 'agreement among raft nodes before linearized reading' (duration: 271.984277ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T14:36:09.003426Z","caller":"traceutil/trace.go:171","msg":"trace[1304681992] transaction","detail":"{read_only:false; response_revision:1064; number_of_response:1; }","duration":"118.025926ms","start":"2026-05-08T14:36:08.885339Z","end":"2026-05-08T14:36:09.003365Z","steps":["trace[1304681992] 'process raft request' (duration: 117.593147ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T14:36:09.003829Z","caller":"traceutil/trace.go:171","msg":"trace[1399610242] transaction","detail":"{read_only:false; response_revision:1067; number_of_response:1; }","duration":"116.652925ms","start":"2026-05-08T14:36:08.887146Z","end":"2026-05-08T14:36:09.003799Z","steps":["trace[1399610242] 'process raft request' (duration: 116.553223ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T14:36:09.003945Z","caller":"traceutil/trace.go:171","msg":"trace[900376143] transaction","detail":"{read_only:false; response_revision:1065; number_of_response:1; }","duration":"118.285233ms","start":"2026-05-08T14:36:08.885647Z","end":"2026-05-08T14:36:09.003932Z","steps":["trace[900376143] 'process raft request' (duration: 117.553676ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T14:36:09.004035Z","caller":"traceutil/trace.go:171","msg":"trace[436115841] transaction","detail":"{read_only:false; response_revision:1066; number_of_response:1; }","duration":"118.020326ms","start":"2026-05-08T14:36:08.886005Z","end":"2026-05-08T14:36:09.004026Z","steps":["trace[436115841] 'process raft request' (duration: 117.635718ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T14:36:20.789236Z","caller":"traceutil/trace.go:171","msg":"trace[513117531] transaction","detail":"{read_only:false; response_revision:1153; number_of_response:1; }","duration":"167.668948ms","start":"2026-05-08T14:36:20.62154Z","end":"2026-05-08T14:36:20.789209Z","steps":["trace[513117531] 'process raft request' (duration: 167.500224ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T14:36:23.291338Z","caller":"traceutil/trace.go:171","msg":"trace[136170438] linearizableReadLoop","detail":"{readStateIndex:1205; appliedIndex:1204; }","duration":"413.51698ms","start":"2026-05-08T14:36:22.877788Z","end":"2026-05-08T14:36:23.291305Z","steps":["trace[136170438] 'read index received' (duration: 413.355486ms)","trace[136170438] 'applied index is now lower than readState.Index' (duration: 159.994µs)"],"step_count":2} {"level":"info","ts":"2026-05-08T14:36:23.29152Z","caller":"traceutil/trace.go:171","msg":"trace[1352400781] transaction","detail":"{read_only:false; response_revision:1160; number_of_response:1; }","duration":"494.043406ms","start":"2026-05-08T14:36:22.79744Z","end":"2026-05-08T14:36:23.291484Z","steps":["trace[1352400781] 'process raft request' (duration: 493.664728ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T14:36:23.291679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T14:36:22.797414Z","time spent":"494.155709ms","remote":"127.0.0.1:38074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T14:36:23.291734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.909649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T14:36:23.291821Z","caller":"traceutil/trace.go:171","msg":"trace[573019235] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:1160; }","duration":"414.054272ms","start":"2026-05-08T14:36:22.877745Z","end":"2026-05-08T14:36:23.291799Z","steps":["trace[573019235] 'agreement among raft nodes before linearized reading' (duration: 413.890989ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T14:36:23.291867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T14:36:22.877727Z","time spent":"414.129154ms","remote":"127.0.0.1:42542","response type":"/etcdserverpb.KV/Range","request count":0,"request size":130,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true "} {"level":"info","ts":"2026-05-08T14:36:23.30974Z","caller":"traceutil/trace.go:171","msg":"trace[1444830210] transaction","detail":"{read_only:false; response_revision:1161; number_of_response:1; }","duration":"358.652677ms","start":"2026-05-08T14:36:22.951036Z","end":"2026-05-08T14:36:23.309689Z","steps":["trace[1444830210] 'process raft request' (duration: 358.315289ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T14:36:23.309781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.720565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-08T14:36:23.309858Z","caller":"traceutil/trace.go:171","msg":"trace[279868091] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1161; }","duration":"216.826527ms","start":"2026-05-08T14:36:23.093011Z","end":"2026-05-08T14:36:23.309838Z","steps":["trace[279868091] 'agreement among raft nodes before linearized reading' (duration: 216.587292ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T14:36:23.309886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.9155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-05-08T14:36:23.309749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.652784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-08T14:36:23.309936Z","caller":"traceutil/trace.go:171","msg":"trace[320614135] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1161; }","duration":"216.995672ms","start":"2026-05-08T14:36:23.09292Z","end":"2026-05-08T14:36:23.309916Z","steps":["trace[320614135] 'agreement among raft nodes before linearized reading' (duration: 216.588733ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T14:36:23.309994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T14:36:22.951006Z","time spent":"358.826711ms","remote":"127.0.0.1:38074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T14:36:23.309971Z","caller":"traceutil/trace.go:171","msg":"trace[103991712] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1161; }","duration":"216.894099ms","start":"2026-05-08T14:36:23.093037Z","end":"2026-05-08T14:36:23.309931Z","steps":["trace[103991712] 'agreement among raft nodes before linearized reading' (duration: 216.533351ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T14:36:23.310545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.732728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-08T14:36:23.310623Z","caller":"traceutil/trace.go:171","msg":"trace[1563863169] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1161; }","duration":"213.84333ms","start":"2026-05-08T14:36:23.096768Z","end":"2026-05-08T14:36:23.310611Z","steps":["trace[1563863169] 'agreement among raft nodes before linearized reading' (duration: 212.785195ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T14:36:39.948894Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15334367538482792067,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-08T14:36:40.480395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.599ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-08T14:36:40.480491Z","caller":"traceutil/trace.go:171","msg":"trace[34893927] linearizableReadLoop","detail":"{readStateIndex:1290; appliedIndex:1289; }","duration":"1.032149636s","start":"2026-05-08T14:36:39.448326Z","end":"2026-05-08T14:36:40.480475Z","steps":["trace[34893927] 'read index received' (duration: 697.316831ms)","trace[34893927] 'applied index is now lower than readState.Index' (duration: 334.831665ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T14:36:40.480693Z","caller":"traceutil/trace.go:171","msg":"trace[107741340] transaction","detail":"{read_only:false; response_revision:1241; number_of_response:1; }","duration":"1.040162882s","start":"2026-05-08T14:36:39.440518Z","end":"2026-05-08T14:36:40.480681Z","steps":["trace[107741340] 'process raft request' (duration: 705.196693ms)","trace[107741340] 'compare' (duration: 334.480468ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T14:36:40.480735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T14:36:39.440496Z","time spent":"1.040220842s","remote":"127.0.0.1:38074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T14:36:40.481726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.026124536s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T14:36:40.481841Z","caller":"traceutil/trace.go:171","msg":"trace[853059928] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1241; }","duration":"1.02630247s","start":"2026-05-08T14:36:39.455514Z","end":"2026-05-08T14:36:40.481816Z","steps":["trace[853059928] 'agreement among raft nodes before linearized reading' (duration: 1.026100135s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T14:36:40.481955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.025831569s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-08T14:36:40.482018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.026034164s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-08T14:36:40.482057Z","caller":"traceutil/trace.go:171","msg":"trace[804658946] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1241; }","duration":"1.025965253s","start":"2026-05-08T14:36:39.456067Z","end":"2026-05-08T14:36:40.482032Z","steps":["trace[804658946] 'agreement among raft nodes before linearized reading' (duration: 1.025646895s)"],"step_count":1} {"level":"info","ts":"2026-05-08T14:36:40.482077Z","caller":"traceutil/trace.go:171","msg":"trace[600346068] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1241; }","duration":"1.026134546s","start":"2026-05-08T14:36:39.455928Z","end":"2026-05-08T14:36:40.482062Z","steps":["trace[600346068] 'agreement among raft nodes before linearized reading' (duration: 1.025947302s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T14:36:40.482088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T14:36:39.455489Z","time spent":"1.026389602s","remote":"127.0.0.1:37810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-08T14:36:40.482128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T14:36:39.455909Z","time spent":"1.026207137s","remote":"127.0.0.1:38074","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-05-08T14:36:40.48216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.033838645s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-08T14:36:40.482133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T14:36:39.456061Z","time spent":"1.026056934s","remote":"127.0.0.1:38074","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-08T14:36:40.482203Z","caller":"traceutil/trace.go:171","msg":"trace[1714499164] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1241; }","duration":"1.033902657s","start":"2026-05-08T14:36:39.448276Z","end":"2026-05-08T14:36:40.482179Z","steps":["trace[1714499164] 'agreement among raft nodes before linearized reading' (duration: 1.033240492s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T14:36:40.482382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T14:36:39.448256Z","time spent":"1.034110661s","remote":"127.0.0.1:38074","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-08T14:36:40.482635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.025521773s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-08T14:36:40.482756Z","caller":"traceutil/trace.go:171","msg":"trace[382676292] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1241; }","duration":"1.025681017s","start":"2026-05-08T14:36:39.457056Z","end":"2026-05-08T14:36:40.482737Z","steps":["trace[382676292] 'agreement among raft nodes before linearized reading' (duration: 1.024671383s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T14:36:40.482866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T14:36:39.456997Z","time spent":"1.02585982s","remote":"127.0.0.1:38074","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-08T14:36:40.68557Z","caller":"traceutil/trace.go:171","msg":"trace[905573018] transaction","detail":"{read_only:false; response_revision:1242; number_of_response:1; }","duration":"199.402934ms","start":"2026-05-08T14:36:40.486133Z","end":"2026-05-08T14:36:40.685535Z","steps":["trace[905573018] 'process raft request' (duration: 199.21783ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T14:36:40.696406Z","caller":"traceutil/trace.go:171","msg":"trace[675634134] linearizableReadLoop","detail":"{readStateIndex:1292; appliedIndex:1290; }","duration":"205.420183ms","start":"2026-05-08T14:36:40.490959Z","end":"2026-05-08T14:36:40.696379Z","steps":["trace[675634134] 'read index received' (duration: 194.371017ms)","trace[675634134] 'applied index is now lower than readState.Index' (duration: 11.047876ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T14:36:40.697007Z","caller":"traceutil/trace.go:171","msg":"trace[1764330027] transaction","detail":"{read_only:false; response_revision:1243; number_of_response:1; }","duration":"208.055054ms","start":"2026-05-08T14:36:40.488938Z","end":"2026-05-08T14:36:40.696993Z","steps":["trace[1764330027] 'process raft request' (duration: 207.218615ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T14:36:40.697345Z","caller":"traceutil/trace.go:171","msg":"trace[2096604924] transaction","detail":"{read_only:false; response_revision:1244; number_of_response:1; }","duration":"206.249413ms","start":"2026-05-08T14:36:40.491084Z","end":"2026-05-08T14:36:40.697333Z","steps":["trace[2096604924] 'process raft request' (duration: 205.231169ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T14:36:40.697433Z","caller":"traceutil/trace.go:171","msg":"trace[788290227] transaction","detail":"{read_only:false; response_revision:1245; number_of_response:1; }","duration":"206.348535ms","start":"2026-05-08T14:36:40.491077Z","end":"2026-05-08T14:36:40.697426Z","steps":["trace[788290227] 'process raft request' (duration: 205.26888ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T14:36:40.697538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.5678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T14:36:40.697581Z","caller":"traceutil/trace.go:171","msg":"trace[531711151] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1245; }","duration":"206.644681ms","start":"2026-05-08T14:36:40.490913Z","end":"2026-05-08T14:36:40.697558Z","steps":["trace[531711151] 'agreement among raft nodes before linearized reading' (duration: 206.574619ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T14:36:40.697734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.893359ms","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-05-08T14:36:40.697763Z","caller":"traceutil/trace.go:171","msg":"trace[788489560] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:1245; }","duration":"153.95457ms","start":"2026-05-08T14:36:40.543798Z","end":"2026-05-08T14:36:40.697752Z","steps":["trace[788489560] 'agreement among raft nodes before linearized reading' (duration: 153.910179ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T14:36:42.079722Z","caller":"traceutil/trace.go:171","msg":"trace[931829618] transaction","detail":"{read_only:false; response_revision:1247; number_of_response:1; }","duration":"332.294076ms","start":"2026-05-08T14:36:41.747398Z","end":"2026-05-08T14:36:42.079692Z","steps":["trace[931829618] 'process raft request' (duration: 332.086901ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T14:36:42.079895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T14:36:41.747377Z","time spent":"332.42948ms","remote":"127.0.0.1:38074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T14:36:42.594508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.850571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-08T14:36:42.594607Z","caller":"traceutil/trace.go:171","msg":"trace[375631133] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1247; }","duration":"102.017125ms","start":"2026-05-08T14:36:42.492567Z","end":"2026-05-08T14:36:42.594584Z","steps":["trace[375631133] 'range keys from in-memory index tree' (duration: 101.516804ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T14:36:42.595058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.676467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T14:36:42.595185Z","caller":"traceutil/trace.go:171","msg":"trace[783533000] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1247; }","duration":"138.866541ms","start":"2026-05-08T14:36:42.456297Z","end":"2026-05-08T14:36:42.595163Z","steps":["trace[783533000] 'range keys from in-memory index tree' (duration: 138.500062ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T14:36:42.81248Z","caller":"traceutil/trace.go:171","msg":"trace[61257703] linearizableReadLoop","detail":"{readStateIndex:1297; appliedIndex:1296; }","duration":"121.270332ms","start":"2026-05-08T14:36:42.691174Z","end":"2026-05-08T14:36:42.812445Z","steps":["trace[61257703] 'read index received' (duration: 120.943785ms)","trace[61257703] 'applied index is now lower than readState.Index' (duration: 325.187µs)"],"step_count":2} {"level":"info","ts":"2026-05-08T14:36:42.812579Z","caller":"traceutil/trace.go:171","msg":"trace[1955903812] transaction","detail":"{read_only:false; response_revision:1248; number_of_response:1; }","duration":"213.302586ms","start":"2026-05-08T14:36:42.599235Z","end":"2026-05-08T14:36:42.812538Z","steps":["trace[1955903812] 'process raft request' (duration: 212.970419ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T14:36:42.812831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.649081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-08T14:36:42.812873Z","caller":"traceutil/trace.go:171","msg":"trace[1164343195] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1248; }","duration":"121.804964ms","start":"2026-05-08T14:36:42.691057Z","end":"2026-05-08T14:36:42.812862Z","steps":["trace[1164343195] 'agreement among raft nodes before linearized reading' (duration: 121.570498ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T14:36:42.812877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.463501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-08T14:36:42.813438Z","caller":"traceutil/trace.go:171","msg":"trace[1041765485] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1248; }","duration":"111.074106ms","start":"2026-05-08T14:36:42.702343Z","end":"2026-05-08T14:36:42.813417Z","steps":["trace[1041765485] 'agreement among raft nodes before linearized reading' (duration: 110.377459ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T14:36:42.812994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.491512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-08T14:36:42.813614Z","caller":"traceutil/trace.go:171","msg":"trace[1706465026] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1248; }","duration":"111.189059ms","start":"2026-05-08T14:36:42.702413Z","end":"2026-05-08T14:36:42.813602Z","steps":["trace[1706465026] 'agreement among raft nodes before linearized reading' (duration: 110.331568ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T14:36:42.813119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.669366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-08T14:36:42.813783Z","caller":"traceutil/trace.go:171","msg":"trace[144257912] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1248; }","duration":"111.354622ms","start":"2026-05-08T14:36:42.702411Z","end":"2026-05-08T14:36:42.813766Z","steps":["trace[144257912] 'agreement among raft nodes before linearized reading' (duration: 110.559573ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T14:36:43.533288Z","caller":"traceutil/trace.go:171","msg":"trace[919859491] transaction","detail":"{read_only:false; response_revision:1253; number_of_response:1; }","duration":"112.49747ms","start":"2026-05-08T14:36:43.420769Z","end":"2026-05-08T14:36:43.533267Z","steps":["trace[919859491] 'process raft request' (duration: 112.005138ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T14:36:43.978969Z","caller":"traceutil/trace.go:171","msg":"trace[1960635631] transaction","detail":"{read_only:false; response_revision:1254; number_of_response:1; }","duration":"255.895305ms","start":"2026-05-08T14:36:43.723044Z","end":"2026-05-08T14:36:43.978939Z","steps":["trace[1960635631] 'process raft request' (duration: 255.715781ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T14:36:44.574564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"572.112007ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-08T14:36:44.576068Z","caller":"traceutil/trace.go:171","msg":"trace[1559916471] linearizableReadLoop","detail":"{readStateIndex:1304; appliedIndex:1303; }","duration":"512.382393ms","start":"2026-05-08T14:36:44.063658Z","end":"2026-05-08T14:36:44.576041Z","steps":["trace[1559916471] 'read index received' (duration: 34.691µs)","trace[1559916471] 'applied index is now lower than readState.Index' (duration: 512.345162ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T14:36:44.576327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"512.651869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:471"} {"level":"info","ts":"2026-05-08T14:36:44.576373Z","caller":"traceutil/trace.go:171","msg":"trace[556298272] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1254; }","duration":"512.747651ms","start":"2026-05-08T14:36:44.063612Z","end":"2026-05-08T14:36:44.57636Z","steps":["trace[556298272] 'agreement among raft nodes before linearized reading' (duration: 512.554117ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T14:36:44.576407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T14:36:44.063595Z","time spent":"512.804932ms","remote":"127.0.0.1:38074","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":494,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-05-08T14:36:44.629734Z","caller":"traceutil/trace.go:171","msg":"trace[1268402418] transaction","detail":"{read_only:false; response_revision:1255; number_of_response:1; }","duration":"159.017737ms","start":"2026-05-08T14:36:44.470608Z","end":"2026-05-08T14:36:44.629625Z","steps":["trace[1268402418] 'process raft request' (duration: 158.68746ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T14:36:44.629774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.639927ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T14:36:44.62986Z","caller":"traceutil/trace.go:171","msg":"trace[61121155] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1255; }","duration":"173.79267ms","start":"2026-05-08T14:36:44.456048Z","end":"2026-05-08T14:36:44.629841Z","steps":["trace[61121155] 'agreement among raft nodes before linearized reading' (duration: 173.589176ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T14:36:53.061849Z","caller":"traceutil/trace.go:171","msg":"trace[152128940] linearizableReadLoop","detail":"{readStateIndex:1372; appliedIndex:1371; }","duration":"177.887016ms","start":"2026-05-08T14:36:52.883927Z","end":"2026-05-08T14:36:53.061814Z","steps":["trace[152128940] 'read index received' (duration: 177.536998ms)","trace[152128940] 'applied index is now lower than readState.Index' (duration: 348.598µs)"],"step_count":2} {"level":"info","ts":"2026-05-08T14:36:53.061938Z","caller":"traceutil/trace.go:171","msg":"trace[1153228565] transaction","detail":"{read_only:false; response_revision:1321; number_of_response:1; }","duration":"215.362054ms","start":"2026-05-08T14:36:52.84653Z","end":"2026-05-08T14:36:53.061892Z","steps":["trace[1153228565] 'process raft request' (duration: 215.051397ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T14:36:53.062272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.440951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-08T14:36:53.062266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.75434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-08T14:36:53.062347Z","caller":"traceutil/trace.go:171","msg":"trace[364494816] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1321; }","duration":"157.555034ms","start":"2026-05-08T14:36:52.904775Z","end":"2026-05-08T14:36:53.06233Z","steps":["trace[364494816] 'agreement among raft nodes before linearized reading' (duration: 157.357859ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T14:36:53.06224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.282085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-08T14:36:53.062373Z","caller":"traceutil/trace.go:171","msg":"trace[432172395] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1321; }","duration":"151.902653ms","start":"2026-05-08T14:36:52.910453Z","end":"2026-05-08T14:36:53.062356Z","steps":["trace[432172395] 'agreement among raft nodes before linearized reading' (duration: 151.620826ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T14:36:53.062442Z","caller":"traceutil/trace.go:171","msg":"trace[1440100259] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1321; }","duration":"178.557601ms","start":"2026-05-08T14:36:52.883863Z","end":"2026-05-08T14:36:53.06242Z","steps":["trace[1440100259] 'agreement among raft nodes before linearized reading' (duration: 178.140631ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T14:36:53.062425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.019653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-08T14:36:53.062506Z","caller":"traceutil/trace.go:171","msg":"trace[1874811196] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1321; }","duration":"164.139036ms","start":"2026-05-08T14:36:52.89835Z","end":"2026-05-08T14:36:53.062489Z","steps":["trace[1874811196] 'agreement among raft nodes before linearized reading' (duration: 163.937001ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T14:36:53.063085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.811447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-08T14:36:53.063148Z","caller":"traceutil/trace.go:171","msg":"trace[816787449] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1321; }","duration":"175.899698ms","start":"2026-05-08T14:36:52.887234Z","end":"2026-05-08T14:36:53.063134Z","steps":["trace[816787449] 'agreement among raft nodes before linearized reading' (duration: 174.794103ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T14:36:54.688567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.005314974s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-08T14:36:54.688776Z","caller":"traceutil/trace.go:171","msg":"trace[19483613] transaction","detail":"{read_only:false; response_revision:1322; number_of_response:1; }","duration":"1.619798165s","start":"2026-05-08T14:36:53.068925Z","end":"2026-05-08T14:36:54.688724Z","steps":["trace[19483613] 'process raft request' (duration: 612.861023ms)","trace[19483613] 'compare' (duration: 1.004839193s)"],"step_count":2} {"level":"warn","ts":"2026-05-08T14:36:54.688869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T14:36:53.068897Z","time spent":"1.619911887s","remote":"127.0.0.1:38074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T14:36:54.696196Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.014359323s","expected-duration":"1s"} {"level":"info","ts":"2026-05-08T14:36:54.696523Z","caller":"traceutil/trace.go:171","msg":"trace[614853213] transaction","detail":"{read_only:false; response_revision:1323; number_of_response:1; }","duration":"1.627417079s","start":"2026-05-08T14:36:53.069094Z","end":"2026-05-08T14:36:54.696511Z","steps":["trace[614853213] 'process raft request' (duration: 1.627254126s)"],"step_count":1} {"level":"info","ts":"2026-05-08T14:36:54.696719Z","caller":"traceutil/trace.go:171","msg":"trace[1623050998] transaction","detail":"{read_only:false; response_revision:1324; number_of_response:1; }","duration":"1.62738508s","start":"2026-05-08T14:36:53.069319Z","end":"2026-05-08T14:36:54.696704Z","steps":["trace[1623050998] 'process raft request' (duration: 1.627132703s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T14:36:54.69677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T14:36:53.069291Z","time spent":"1.627457751s","remote":"127.0.0.1:38074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T14:36:54.696984Z","caller":"traceutil/trace.go:171","msg":"trace[1389826335] transaction","detail":"{read_only:false; response_revision:1325; number_of_response:1; }","duration":"1.627447651s","start":"2026-05-08T14:36:53.069471Z","end":"2026-05-08T14:36:54.696919Z","steps":["trace[1389826335] 'process raft request' (duration: 1.627178705s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T14:36:54.697118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T14:36:53.069459Z","time spent":"1.627595874s","remote":"127.0.0.1:38074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T14:36:54.697175Z","caller":"traceutil/trace.go:171","msg":"trace[60758871] linearizableReadLoop","detail":"{readStateIndex:1377; appliedIndex:1372; }","duration":"1.23971795s","start":"2026-05-08T14:36:53.45744Z","end":"2026-05-08T14:36:54.697157Z","steps":["trace[60758871] 'read index received' (duration: 224.377593ms)","trace[60758871] 'applied index is now lower than readState.Index' (duration: 1.015339487s)"],"step_count":2} {"level":"info","ts":"2026-05-08T14:36:54.697208Z","caller":"traceutil/trace.go:171","msg":"trace[1283241976] transaction","detail":"{read_only:false; response_revision:1326; number_of_response:1; }","duration":"1.627240436s","start":"2026-05-08T14:36:53.069951Z","end":"2026-05-08T14:36:54.697191Z","steps":["trace[1283241976] 'process raft request' (duration: 1.626904638s)"],"step_count":1} {"level":"info","ts":"2026-05-08T14:36:54.697241Z","caller":"traceutil/trace.go:171","msg":"trace[546409294] transaction","detail":"{read_only:false; response_revision:1327; number_of_response:1; }","duration":"1.122629344s","start":"2026-05-08T14:36:53.574603Z","end":"2026-05-08T14:36:54.697232Z","steps":["trace[546409294] 'process raft request' (duration: 1.122509081s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T14:36:54.697266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T14:36:53.069939Z","time spent":"1.627298617s","remote":"127.0.0.1:38074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T14:36:54.697294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T14:36:53.574582Z","time spent":"1.122677846s","remote":"127.0.0.1:38074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T14:36:54.698501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T14:36:53.069064Z","time spent":"1.627521161s","remote":"127.0.0.1:38074","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T14:36:54.700758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.243294613s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T14:36:54.700906Z","caller":"traceutil/trace.go:171","msg":"trace[1666249791] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1327; }","duration":"1.243479217s","start":"2026-05-08T14:36:53.457399Z","end":"2026-05-08T14:36:54.700878Z","steps":["trace[1666249791] 'agreement among raft nodes before linearized reading' (duration: 1.239871664s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T14:36:54.701122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T14:36:53.457283Z","time spent":"1.243771073s","remote":"127.0.0.1:37810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-08T14:36:54.703553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"710.482736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-05-08T14:36:54.70359Z","caller":"traceutil/trace.go:171","msg":"trace[986485942] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1327; }","duration":"710.568679ms","start":"2026-05-08T14:36:53.99301Z","end":"2026-05-08T14:36:54.703578Z","steps":["trace[986485942] 'agreement among raft nodes before linearized reading' (duration: 710.389945ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T14:36:54.70361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T14:36:53.992989Z","time spent":"710.616919ms","remote":"127.0.0.1:38074","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":"warn","ts":"2026-05-08T14:36:54.70326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"501.457539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T14:36:54.703831Z","caller":"traceutil/trace.go:171","msg":"trace[1869429054] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:1327; }","duration":"502.073553ms","start":"2026-05-08T14:36:54.201748Z","end":"2026-05-08T14:36:54.703821Z","steps":["trace[1869429054] 'agreement among raft nodes before linearized reading' (duration: 501.48435ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T14:36:54.70399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T14:36:54.201723Z","time spent":"502.122544ms","remote":"127.0.0.1:43296","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true "} {"level":"info","ts":"2026-05-08T14:40:08.003378Z","caller":"traceutil/trace.go:171","msg":"trace[539371579] linearizableReadLoop","detail":"{readStateIndex:2332; appliedIndex:2331; }","duration":"122.999082ms","start":"2026-05-08T14:40:07.880351Z","end":"2026-05-08T14:40:08.00335Z","steps":["trace[539371579] 'read index received' (duration: 112.209135ms)","trace[539371579] 'applied index is now lower than readState.Index' (duration: 10.788917ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T14:40:08.003515Z","caller":"traceutil/trace.go:171","msg":"trace[964305437] transaction","detail":"{read_only:false; response_revision:2241; number_of_response:1; }","duration":"152.311302ms","start":"2026-05-08T14:40:07.851193Z","end":"2026-05-08T14:40:08.003505Z","steps":["trace[964305437] 'process raft request' (duration: 141.334061ms)","trace[964305437] 'compare' (duration: 10.700375ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T14:40:08.00386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.36875ms","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-05-08T14:40:08.003896Z","caller":"traceutil/trace.go:171","msg":"trace[1131995856] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2241; }","duration":"123.440692ms","start":"2026-05-08T14:40:07.880446Z","end":"2026-05-08T14:40:08.003886Z","steps":["trace[1131995856] 'agreement among raft nodes before linearized reading' (duration: 123.272898ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T14:40:08.003864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.501924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-05-08T14:40:08.003944Z","caller":"traceutil/trace.go:171","msg":"trace[1997694638] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2241; }","duration":"123.648207ms","start":"2026-05-08T14:40:07.880276Z","end":"2026-05-08T14:40:08.003925Z","steps":["trace[1997694638] 'agreement among raft nodes before linearized reading' (duration: 123.454282ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T14:40:08.00402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.288952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-05-08T14:40:08.004106Z","caller":"traceutil/trace.go:171","msg":"trace[228329773] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2241; }","duration":"114.418145ms","start":"2026-05-08T14:40:07.889667Z","end":"2026-05-08T14:40:08.004085Z","steps":["trace[228329773] 'agreement among raft nodes before linearized reading' (duration: 114.19691ms)"],"step_count":1}