{"level":"warn","ts":"2026-07-03T11:51:49.51772Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-03T11:51:49.517824Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.210: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.210:2380","--initial-cluster=instance=https://199.204.45.210:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.210:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.210:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-07-03T11:51:49.51794Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-03T11:51:49.518023Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.210:2380"]} {"level":"info","ts":"2026-07-03T11:51:49.518046Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-03T11:51:49.518917Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.210:2379"]} {"level":"info","ts":"2026-07-03T11:51:49.519111Z","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.210:2380"],"listen-peer-urls":["https://199.204.45.210:2380"],"advertise-client-urls":["https://199.204.45.210:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.210:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.210:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-07-03T11:51:50.431306Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"911.870805ms"} {"level":"warn","ts":"2026-07-03T11:51:51.836788Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.396039604s","expected-duration":"1s"} {"level":"info","ts":"2026-07-03T11:51:51.84971Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"1cac722af8b2b8a6","cluster-id":"ba0a336ca61ee41d"} {"level":"info","ts":"2026-07-03T11:51:51.849951Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 switched to configuration voters=()"} {"level":"info","ts":"2026-07-03T11:51:51.84999Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became follower at term 0"} {"level":"info","ts":"2026-07-03T11:51:51.850965Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 1cac722af8b2b8a6 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-03T11:51:51.851081Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became follower at term 1"} {"level":"info","ts":"2026-07-03T11:51:51.851186Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 switched to configuration voters=(2066151857942870182)"} {"level":"warn","ts":"2026-07-03T11:51:51.852913Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-03T11:51:51.854669Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-03T11:51:51.856664Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-07-03T11:51:51.859589Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"1cac722af8b2b8a6","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-03T11:51:51.860036Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"1cac722af8b2b8a6","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-03T11:51:51.86012Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-03T11:51:51.860248Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-03T11:51:51.86028Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-03T11:51:51.862016Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 switched to configuration voters=(2066151857942870182)"} {"level":"info","ts":"2026-07-03T11:51:51.862821Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ba0a336ca61ee41d","local-member-id":"1cac722af8b2b8a6","added-peer-id":"1cac722af8b2b8a6","added-peer-peer-urls":["https://199.204.45.210:2380"]} {"level":"info","ts":"2026-07-03T11:51:51.867137Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-03T11:51:51.86725Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.210:2380"} {"level":"info","ts":"2026-07-03T11:51:51.867303Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.210:2380"} {"level":"info","ts":"2026-07-03T11:51:51.867604Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"1cac722af8b2b8a6","initial-advertise-peer-urls":["https://199.204.45.210:2380"],"listen-peer-urls":["https://199.204.45.210:2380"],"advertise-client-urls":["https://199.204.45.210:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.210:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-03T11:51:51.867655Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-03T11:51:52.852Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 is starting a new election at term 1"} {"level":"info","ts":"2026-07-03T11:51:52.852082Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-03T11:51:52.852109Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 received MsgPreVoteResp from 1cac722af8b2b8a6 at term 1"} {"level":"info","ts":"2026-07-03T11:51:52.852131Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became candidate at term 2"} {"level":"info","ts":"2026-07-03T11:51:52.852142Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 received MsgVoteResp from 1cac722af8b2b8a6 at term 2"} {"level":"info","ts":"2026-07-03T11:51:52.85216Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1cac722af8b2b8a6 became leader at term 2"} {"level":"info","ts":"2026-07-03T11:51:52.852175Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 1cac722af8b2b8a6 elected leader 1cac722af8b2b8a6 at term 2"} {"level":"info","ts":"2026-07-03T11:51:52.858211Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-03T11:51:52.876449Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-03T11:51:52.876468Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"1cac722af8b2b8a6","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.210:2379]}","request-path":"/0/members/1cac722af8b2b8a6/attributes","cluster-id":"ba0a336ca61ee41d","publish-timeout":"7s"} {"level":"info","ts":"2026-07-03T11:51:52.876524Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-03T11:51:52.876979Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-03T11:51:52.877023Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-03T11:51:52.8791Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.210:2379"} {"level":"info","ts":"2026-07-03T11:51:52.880458Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-03T11:51:52.886851Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ba0a336ca61ee41d","local-member-id":"1cac722af8b2b8a6","cluster-version":"3.5"} {"level":"info","ts":"2026-07-03T11:51:52.887009Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-03T11:51:52.887051Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-03T11:51:58.098176Z","caller":"traceutil/trace.go:171","msg":"trace[1782468130] transaction","detail":"{read_only:false; response_revision:294; number_of_response:1; }","duration":"214.961896ms","start":"2026-07-03T11:51:57.883189Z","end":"2026-07-03T11:51:58.098151Z","steps":["trace[1782468130] 'process raft request' (duration: 214.823574ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T11:51:58.104414Z","caller":"traceutil/trace.go:171","msg":"trace[1830093297] transaction","detail":"{read_only:false; response_revision:295; number_of_response:1; }","duration":"200.498356ms","start":"2026-07-03T11:51:57.903901Z","end":"2026-07-03T11:51:58.104399Z","steps":["trace[1830093297] 'process raft request' (duration: 200.399615ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T11:52:28.187353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.743779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-03T11:52:28.18745Z","caller":"traceutil/trace.go:171","msg":"trace[1014500370] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:520; }","duration":"166.893427ms","start":"2026-07-03T11:52:28.020542Z","end":"2026-07-03T11:52:28.187436Z","steps":["trace[1014500370] 'range keys from in-memory index tree' (duration: 166.630695ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T11:52:29.026705Z","caller":"traceutil/trace.go:171","msg":"trace[338011599] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"534.024274ms","start":"2026-07-03T11:52:28.492639Z","end":"2026-07-03T11:52:29.026663Z","steps":["trace[338011599] 'process raft request' (duration: 533.812494ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T11:52:29.02772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T11:52:28.492619Z","time spent":"534.215383ms","remote":"127.0.0.1:56108","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-07-03T11:52:29.513847Z","caller":"traceutil/trace.go:171","msg":"trace[828943124] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"1.01843296s","start":"2026-07-03T11:52:28.495371Z","end":"2026-07-03T11:52:29.513804Z","steps":["trace[828943124] 'process raft request' (duration: 1.018262162s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T11:52:29.514029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T11:52:28.495363Z","time spent":"1.018587076s","remote":"127.0.0.1:56108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-03T11:52:29.514176Z","caller":"traceutil/trace.go:171","msg":"trace[625714316] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"1.004710645s","start":"2026-07-03T11:52:28.509453Z","end":"2026-07-03T11:52:29.514164Z","steps":["trace[625714316] 'process raft request' (duration: 1.004252024s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T11:52:29.514229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T11:52:28.509432Z","time spent":"1.004771928s","remote":"127.0.0.1:56108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-03T11:52:29.514846Z","caller":"traceutil/trace.go:171","msg":"trace[1243727715] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"1.002672359s","start":"2026-07-03T11:52:28.512167Z","end":"2026-07-03T11:52:29.51484Z","steps":["trace[1243727715] 'process raft request' (duration: 1.001596979s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T11:52:29.514898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T11:52:28.512159Z","time spent":"1.002714751s","remote":"127.0.0.1:56108","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-07-03T11:52:29.514807Z","caller":"traceutil/trace.go:171","msg":"trace[982198406] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"1.020292636s","start":"2026-07-03T11:52:28.494494Z","end":"2026-07-03T11:52:29.514786Z","steps":["trace[982198406] 'process raft request' (duration: 1.018931813s)"],"step_count":1} {"level":"info","ts":"2026-07-03T11:52:29.515012Z","caller":"traceutil/trace.go:171","msg":"trace[446235771] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"1.002783244s","start":"2026-07-03T11:52:28.512209Z","end":"2026-07-03T11:52:29.514992Z","steps":["trace[446235771] 'process raft request' (duration: 1.002377425s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T11:52:29.515021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T11:52:28.494482Z","time spent":"1.020518516s","remote":"127.0.0.1:56108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-03T11:52:29.515091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T11:52:28.512205Z","time spent":"1.002841316s","remote":"127.0.0.1:56108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-03T11:52:29.515135Z","caller":"traceutil/trace.go:171","msg":"trace[1832813758] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"998.537435ms","start":"2026-07-03T11:52:28.516588Z","end":"2026-07-03T11:52:29.515126Z","steps":["trace[1832813758] 'process raft request' (duration: 998.339366ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T11:52:29.515177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T11:52:28.516582Z","time spent":"998.576336ms","remote":"127.0.0.1:56108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-03T11:52:29.515311Z","caller":"traceutil/trace.go:171","msg":"trace[1602926304] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"998.675571ms","start":"2026-07-03T11:52:28.516624Z","end":"2026-07-03T11:52:29.515299Z","steps":["trace[1602926304] 'process raft request' (duration: 998.379317ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T11:52:29.515354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T11:52:28.516618Z","time spent":"998.714403ms","remote":"127.0.0.1:56108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-03T11:52:29.515465Z","caller":"traceutil/trace.go:171","msg":"trace[64807228] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"949.751113ms","start":"2026-07-03T11:52:28.565705Z","end":"2026-07-03T11:52:29.515456Z","steps":["trace[64807228] 'process raft request' (duration: 949.517692ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T11:52:29.515453Z","caller":"traceutil/trace.go:171","msg":"trace[110151437] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"986.72325ms","start":"2026-07-03T11:52:28.528704Z","end":"2026-07-03T11:52:29.515428Z","steps":["trace[110151437] 'process raft request' (duration: 986.403265ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T11:52:29.515513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T11:52:28.565687Z","time spent":"949.807086ms","remote":"127.0.0.1:56108","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-07-03T11:52:29.515568Z","caller":"traceutil/trace.go:171","msg":"trace[1886957053] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"924.600382ms","start":"2026-07-03T11:52:28.590958Z","end":"2026-07-03T11:52:29.515559Z","steps":["trace[1886957053] 'process raft request' (duration: 924.34572ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T11:52:29.515576Z","caller":"traceutil/trace.go:171","msg":"trace[1345165032] linearizableReadLoop","detail":"{readStateIndex:551; appliedIndex:540; }","duration":"918.769479ms","start":"2026-07-03T11:52:28.596763Z","end":"2026-07-03T11:52:29.515533Z","steps":["trace[1345165032] 'read index received' (duration: 429.839552ms)","trace[1345165032] 'applied index is now lower than readState.Index' (duration: 488.928227ms)"],"step_count":2} {"level":"warn","ts":"2026-07-03T11:52:29.515644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T11:52:28.59093Z","time spent":"924.696107ms","remote":"127.0.0.1:56108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-03T11:52:29.515796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"919.032201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-07-03T11:52:29.515782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T11:52:28.52869Z","time spent":"986.815214ms","remote":"127.0.0.1:56108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-03T11:52:29.515859Z","caller":"traceutil/trace.go:171","msg":"trace[317798126] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:532; }","duration":"919.123686ms","start":"2026-07-03T11:52:28.596724Z","end":"2026-07-03T11:52:29.515848Z","steps":["trace[317798126] 'agreement among raft nodes before linearized reading' (duration: 918.973209ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T11:52:29.515909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T11:52:28.596713Z","time spent":"919.168417ms","remote":"127.0.0.1:56328","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-07-03T11:52:29.515731Z","caller":"traceutil/trace.go:171","msg":"trace[1070588290] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"836.148679ms","start":"2026-07-03T11:52:28.679575Z","end":"2026-07-03T11:52:29.515723Z","steps":["trace[1070588290] 'process raft request' (duration: 835.853435ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T11:52:29.516235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T11:52:28.679549Z","time spent":"836.487174ms","remote":"127.0.0.1:56108","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-07-03T11:52:29.959369Z","caller":"traceutil/trace.go:171","msg":"trace[712620148] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"925.597166ms","start":"2026-07-03T11:52:29.03373Z","end":"2026-07-03T11:52:29.959327Z","steps":["trace[712620148] 'process raft request' (duration: 925.322053ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T11:52:29.959399Z","caller":"traceutil/trace.go:171","msg":"trace[385137518] linearizableReadLoop","detail":"{readStateIndex:555; appliedIndex:552; }","duration":"443.687465ms","start":"2026-07-03T11:52:29.51568Z","end":"2026-07-03T11:52:29.959368Z","steps":["trace[385137518] 'read index received' (duration: 443.389461ms)","trace[385137518] 'applied index is now lower than readState.Index' (duration: 296.664µs)"],"step_count":2} {"level":"info","ts":"2026-07-03T11:52:29.959503Z","caller":"traceutil/trace.go:171","msg":"trace[196036586] transaction","detail":"{read_only:false; number_of_response:1; response_revision:534; }","duration":"924.630861ms","start":"2026-07-03T11:52:29.034831Z","end":"2026-07-03T11:52:29.959462Z","steps":["trace[196036586] 'process raft request' (duration: 924.505065ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T11:52:29.959594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.305885127s","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-07-03T11:52:29.959596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T11:52:29.033702Z","time spent":"925.758483ms","remote":"127.0.0.1:56590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":995,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-03T11:52:29.959629Z","caller":"traceutil/trace.go:171","msg":"trace[1633159856] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:534; }","duration":"1.305988672s","start":"2026-07-03T11:52:28.653633Z","end":"2026-07-03T11:52:29.959622Z","steps":["trace[1633159856] 'agreement among raft nodes before linearized reading' (duration: 1.305875147s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T11:52:29.959658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T11:52:28.653612Z","time spent":"1.306039974s","remote":"127.0.0.1:56328","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-07-03T11:52:29.959679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"733.305616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-03T11:52:29.959809Z","caller":"traceutil/trace.go:171","msg":"trace[1630386347] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"925.244419ms","start":"2026-07-03T11:52:29.034539Z","end":"2026-07-03T11:52:29.959783Z","steps":["trace[1630386347] 'process raft request' (duration: 924.697114ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T11:52:29.959894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.120652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-03T11:52:29.959927Z","caller":"traceutil/trace.go:171","msg":"trace[281656287] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:534; }","duration":"445.179454ms","start":"2026-07-03T11:52:29.51474Z","end":"2026-07-03T11:52:29.959919Z","steps":["trace[281656287] 'agreement among raft nodes before linearized reading' (duration: 445.098361ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T11:52:29.959809Z","caller":"traceutil/trace.go:171","msg":"trace[616685141] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:534; }","duration":"733.492624ms","start":"2026-07-03T11:52:29.226291Z","end":"2026-07-03T11:52:29.959784Z","steps":["trace[616685141] 'agreement among raft nodes before linearized reading' (duration: 733.238312ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T11:52:29.959969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T11:52:29.514723Z","time spent":"445.237727ms","remote":"127.0.0.1:56328","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-03T11:52:29.960004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T11:52:29.226273Z","time spent":"733.694454ms","remote":"127.0.0.1:60176","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-07-03T11:52:29.960053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T11:52:29.034821Z","time spent":"924.768677ms","remote":"127.0.0.1:56108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":102,"response count":0,"response size":6937,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-03T11:52:29.960173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T11:52:29.034527Z","time spent":"925.316263ms","remote":"127.0.0.1:56108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6888,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-03T11:52:30.658876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"466.518769ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-03T11:52:30.659708Z","caller":"traceutil/trace.go:171","msg":"trace[1910424438] linearizableReadLoop","detail":"{readStateIndex:565; appliedIndex:555; }","duration":"700.127883ms","start":"2026-07-03T11:52:29.959479Z","end":"2026-07-03T11:52:30.659607Z","steps":["trace[1910424438] 'read index received' (duration: 232.606007ms)","trace[1910424438] 'applied index is now lower than readState.Index' (duration: 467.520796ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T11:52:30.659795Z","caller":"traceutil/trace.go:171","msg":"trace[809363411] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"1.121595627s","start":"2026-07-03T11:52:29.538159Z","end":"2026-07-03T11:52:30.659754Z","steps":["trace[809363411] 'process raft request' (duration: 1.121098364s)"],"step_count":1} {"level":"info","ts":"2026-07-03T11:52:30.659813Z","caller":"traceutil/trace.go:171","msg":"trace[1580832279] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"1.132320084s","start":"2026-07-03T11:52:29.527452Z","end":"2026-07-03T11:52:30.659772Z","steps":["trace[1580832279] 'process raft request' (duration: 664.551098ms)","trace[1580832279] 'compare' (duration: 466.190854ms)"],"step_count":2} {"level":"warn","ts":"2026-07-03T11:52:30.659944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T11:52:29.538156Z","time spent":"1.121713482s","remote":"127.0.0.1:56590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-03T11:52:30.660023Z","caller":"traceutil/trace.go:171","msg":"trace[2118482075] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"1.114918926s","start":"2026-07-03T11:52:29.54509Z","end":"2026-07-03T11:52:30.660009Z","steps":["trace[2118482075] 'process raft request' (duration: 1.114270936s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T11:52:30.66001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T11:52:29.527426Z","time spent":"1.132475621s","remote":"127.0.0.1:56108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10063,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-03T11:52:30.660114Z","caller":"traceutil/trace.go:171","msg":"trace[492850079] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"1.126855421s","start":"2026-07-03T11:52:29.533251Z","end":"2026-07-03T11:52:30.660107Z","steps":["trace[492850079] 'process raft request' (duration: 1.125831254s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T11:52:30.660154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T11:52:29.533247Z","time spent":"1.126889702s","remote":"127.0.0.1:56590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":980,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-03T11:52:30.660184Z","caller":"traceutil/trace.go:171","msg":"trace[524112386] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"1.114602571s","start":"2026-07-03T11:52:29.545571Z","end":"2026-07-03T11:52:30.660173Z","steps":["trace[524112386] 'process raft request' (duration: 1.113889268s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T11:52:30.660246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T11:52:29.545553Z","time spent":"1.114668874s","remote":"127.0.0.1:56108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30393,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-03T11:52:30.660436Z","caller":"traceutil/trace.go:171","msg":"trace[500967176] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"1.126027921s","start":"2026-07-03T11:52:29.534395Z","end":"2026-07-03T11:52:30.660423Z","steps":["trace[500967176] 'process raft request' (duration: 1.124722991s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T11:52:30.660496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T11:52:29.534388Z","time spent":"1.126074554s","remote":"127.0.0.1:56328","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-07-03T11:52:30.660547Z","caller":"traceutil/trace.go:171","msg":"trace[1873356916] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"1.122443445s","start":"2026-07-03T11:52:29.538098Z","end":"2026-07-03T11:52:30.660542Z","steps":["trace[1873356916] 'process raft request' (duration: 1.121118004s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T11:52:30.660578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T11:52:29.538078Z","time spent":"1.122488007s","remote":"127.0.0.1:56590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-03T11:52:30.660137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T11:52:29.545073Z","time spent":"1.115018181s","remote":"127.0.0.1:56108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30374,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-03T11:52:30.660737Z","caller":"traceutil/trace.go:171","msg":"trace[796566250] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"1.063726268s","start":"2026-07-03T11:52:29.597002Z","end":"2026-07-03T11:52:30.660729Z","steps":["trace[796566250] 'process raft request' (duration: 1.062574075s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T11:52:30.660767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T11:52:29.596971Z","time spent":"1.06378279s","remote":"127.0.0.1:56108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17062,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-03T11:52:30.660821Z","caller":"traceutil/trace.go:171","msg":"trace[382503056] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"1.106602739s","start":"2026-07-03T11:52:29.554208Z","end":"2026-07-03T11:52:30.660811Z","steps":["trace[382503056] 'process raft request' (duration: 1.105305949s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T11:52:30.660877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T11:52:29.554189Z","time spent":"1.106666772s","remote":"127.0.0.1:56108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28739,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-03T11:52:30.660994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"976.695036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-03T11:52:30.661019Z","caller":"traceutil/trace.go:171","msg":"trace[888052854] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:544; }","duration":"976.77614ms","start":"2026-07-03T11:52:29.684237Z","end":"2026-07-03T11:52:30.661013Z","steps":["trace[888052854] 'agreement among raft nodes before linearized reading' (duration: 976.723368ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T11:52:30.661036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T11:52:29.684214Z","time spent":"976.818012ms","remote":"127.0.0.1:56328","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-03T11:52:30.661759Z","caller":"traceutil/trace.go:171","msg":"trace[155544334] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"1.128543937s","start":"2026-07-03T11:52:29.533191Z","end":"2026-07-03T11:52:30.661735Z","steps":["trace[155544334] 'process raft request' (duration: 1.125786861s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T11:52:30.662878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T11:52:29.533172Z","time spent":"1.129647268s","remote":"127.0.0.1:56590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1004,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-03T11:52:30.663171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.83427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-03T11:52:30.663194Z","caller":"traceutil/trace.go:171","msg":"trace[570790711] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"690.694094ms","start":"2026-07-03T11:52:29.972484Z","end":"2026-07-03T11:52:30.663178Z","steps":["trace[570790711] 'process raft request' (duration: 690.480674ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T11:52:30.663234Z","caller":"traceutil/trace.go:171","msg":"trace[1112548483] transaction","detail":"{read_only:false; number_of_response:1; response_revision:546; }","duration":"690.486474ms","start":"2026-07-03T11:52:29.972742Z","end":"2026-07-03T11:52:30.663228Z","steps":["trace[1112548483] 'process raft request' (duration: 690.253773ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T11:52:30.663274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T11:52:29.972728Z","time spent":"690.519895ms","remote":"127.0.0.1:56108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":101,"response count":0,"response size":10112,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-03T11:52:30.663276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T11:52:29.972431Z","time spent":"690.797918ms","remote":"127.0.0.1:56328","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-03T11:52:30.663382Z","caller":"traceutil/trace.go:171","msg":"trace[1409135270] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"686.95926ms","start":"2026-07-03T11:52:29.976417Z","end":"2026-07-03T11:52:30.663376Z","steps":["trace[1409135270] 'process raft request' (duration: 686.638815ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T11:52:30.663412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T11:52:29.976378Z","time spent":"687.018353ms","remote":"127.0.0.1:56108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":64462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-03T11:52:30.663479Z","caller":"traceutil/trace.go:171","msg":"trace[268110838] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"691.109792ms","start":"2026-07-03T11:52:29.972359Z","end":"2026-07-03T11:52:30.663469Z","steps":["trace[268110838] 'process raft request' (duration: 690.534016ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T11:52:30.663725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T11:52:29.972332Z","time spent":"691.370735ms","remote":"127.0.0.1:56328","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-07-03T11:52:30.663209Z","caller":"traceutil/trace.go:171","msg":"trace[391491225] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:547; }","duration":"435.917614ms","start":"2026-07-03T11:52:30.227281Z","end":"2026-07-03T11:52:30.663199Z","steps":["trace[391491225] 'agreement among raft nodes before linearized reading' (duration: 435.852871ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T11:52:30.66376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T11:52:30.22725Z","time spent":"436.50496ms","remote":"127.0.0.1:60178","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-07-03T11:52:31.168232Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305497142491033517,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-03T11:52:31.226237Z","caller":"traceutil/trace.go:171","msg":"trace[129648894] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"558.814327ms","start":"2026-07-03T11:52:30.667388Z","end":"2026-07-03T11:52:31.226202Z","steps":["trace[129648894] 'process raft request' (duration: 545.813627ms)","trace[129648894] 'compare' (duration: 12.756329ms)"],"step_count":2} {"level":"warn","ts":"2026-07-03T11:52:31.227267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T11:52:30.667363Z","time spent":"559.595072ms","remote":"127.0.0.1:56328","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-07-03T11:52:31.227464Z","caller":"traceutil/trace.go:171","msg":"trace[612153212] transaction","detail":"{read_only:false; number_of_response:0; response_revision:548; }","duration":"559.606753ms","start":"2026-07-03T11:52:30.667839Z","end":"2026-07-03T11:52:31.227446Z","steps":["trace[612153212] 'process raft request' (duration: 559.494208ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T11:52:31.227464Z","caller":"traceutil/trace.go:171","msg":"trace[1142294019] transaction","detail":"{read_only:false; number_of_response:0; response_revision:548; }","duration":"559.587222ms","start":"2026-07-03T11:52:30.667842Z","end":"2026-07-03T11:52:31.227429Z","steps":["trace[1142294019] 'process raft request' (duration: 559.454506ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T11:52:31.227601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T11:52:30.66782Z","time spent":"559.713278ms","remote":"127.0.0.1:56590","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-03T11:52:31.227602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T11:52:30.667827Z","time spent":"559.709378ms","remote":"127.0.0.1:56590","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-03T11:52:31.22778Z","caller":"traceutil/trace.go:171","msg":"trace[1989986998] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"550.469373ms","start":"2026-07-03T11:52:30.677294Z","end":"2026-07-03T11:52:31.227763Z","steps":["trace[1989986998] 'process raft request' (duration: 550.066865ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T11:52:31.227869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T11:52:30.677271Z","time spent":"550.564047ms","remote":"127.0.0.1:56590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":986,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-03T11:52:31.227946Z","caller":"traceutil/trace.go:171","msg":"trace[997441337] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"547.201533ms","start":"2026-07-03T11:52:30.680735Z","end":"2026-07-03T11:52:31.227936Z","steps":["trace[997441337] 'process raft request' (duration: 546.741062ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T11:52:31.228031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T11:52:30.680725Z","time spent":"547.253745ms","remote":"127.0.0.1:56108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9125,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-03T11:52:31.228154Z","caller":"traceutil/trace.go:171","msg":"trace[230132927] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"539.675447ms","start":"2026-07-03T11:52:30.688442Z","end":"2026-07-03T11:52:31.228117Z","steps":["trace[230132927] 'process raft request' (duration: 539.188065ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T11:52:31.22822Z","caller":"traceutil/trace.go:171","msg":"trace[1549565442] transaction","detail":"{read_only:false; number_of_response:0; response_revision:548; }","duration":"559.597703ms","start":"2026-07-03T11:52:30.667791Z","end":"2026-07-03T11:52:31.227388Z","steps":["trace[1549565442] 'process raft request' (duration: 559.385963ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T11:52:31.228279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T11:52:30.667778Z","time spent":"560.465382ms","remote":"127.0.0.1:56590","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-03T11:52:31.228361Z","caller":"traceutil/trace.go:171","msg":"trace[142155318] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"539.758831ms","start":"2026-07-03T11:52:30.688587Z","end":"2026-07-03T11:52:31.228346Z","steps":["trace[142155318] 'process raft request' (duration: 539.257208ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T11:52:31.228396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T11:52:30.688567Z","time spent":"539.809353ms","remote":"127.0.0.1:56108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58844,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-03T11:52:31.228461Z","caller":"traceutil/trace.go:171","msg":"trace[2090066681] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"509.49613ms","start":"2026-07-03T11:52:30.718954Z","end":"2026-07-03T11:52:31.22845Z","steps":["trace[2090066681] 'process raft request' (duration: 509.04635ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T11:52:31.228433Z","caller":"traceutil/trace.go:171","msg":"trace[1647511842] linearizableReadLoop","detail":"{readStateIndex:579; appliedIndex:569; }","duration":"560.890501ms","start":"2026-07-03T11:52:30.667516Z","end":"2026-07-03T11:52:31.228407Z","steps":["trace[1647511842] 'read index received' (duration: 545.741063ms)","trace[1647511842] 'applied index is now lower than readState.Index' (duration: 15.148328ms)"],"step_count":2} {"level":"warn","ts":"2026-07-03T11:52:31.228496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T11:52:30.718924Z","time spent":"509.554974ms","remote":"127.0.0.1:56108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":150480,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-03T11:52:31.2285Z","caller":"traceutil/trace.go:171","msg":"trace[2068219377] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"475.862895ms","start":"2026-07-03T11:52:30.752605Z","end":"2026-07-03T11:52:31.228468Z","steps":["trace[2068219377] 'process raft request' (duration: 475.663906ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T11:52:31.228577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"561.043889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-03T11:52:31.228488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T11:52:30.688408Z","time spent":"539.797582ms","remote":"127.0.0.1:56108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79947,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-03T11:52:31.228601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T11:52:30.752574Z","time spent":"475.981781ms","remote":"127.0.0.1:56108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":97665,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-03T11:52:31.228627Z","caller":"traceutil/trace.go:171","msg":"trace[1248440126] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:554; }","duration":"561.168594ms","start":"2026-07-03T11:52:30.667447Z","end":"2026-07-03T11:52:31.228615Z","steps":["trace[1248440126] 'agreement among raft nodes before linearized reading' (duration: 561.057399ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T11:52:31.228667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T11:52:30.667418Z","time spent":"561.240308ms","remote":"127.0.0.1:60176","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-07-03T11:52:31.96075Z","caller":"traceutil/trace.go:171","msg":"trace[1959479683] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"128.880475ms","start":"2026-07-03T11:52:31.831828Z","end":"2026-07-03T11:52:31.960709Z","steps":["trace[1959479683] 'process raft request' (duration: 60.134502ms)","trace[1959479683] 'compare' (duration: 68.506802ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T11:52:32.407874Z","caller":"traceutil/trace.go:171","msg":"trace[187801382] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"104.12744ms","start":"2026-07-03T11:52:32.303625Z","end":"2026-07-03T11:52:32.407752Z","steps":["trace[187801382] 'process raft request' (duration: 103.644238ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T11:52:32.728014Z","caller":"traceutil/trace.go:171","msg":"trace[429145417] linearizableReadLoop","detail":"{readStateIndex:621; appliedIndex:620; }","duration":"145.268639ms","start":"2026-07-03T11:52:32.582716Z","end":"2026-07-03T11:52:32.727984Z","steps":["trace[429145417] 'read index received' (duration: 85.109815ms)","trace[429145417] 'applied index is now lower than readState.Index' (duration: 60.157474ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T11:52:32.728577Z","caller":"traceutil/trace.go:171","msg":"trace[1129316005] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"152.150208ms","start":"2026-07-03T11:52:32.576418Z","end":"2026-07-03T11:52:32.728568Z","steps":["trace[1129316005] 'process raft request' (duration: 91.529114ms)","trace[1129316005] 'compare' (duration: 59.900152ms)"],"step_count":2} {"level":"warn","ts":"2026-07-03T11:52:32.728772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.903061ms","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":"info","ts":"2026-07-03T11:52:32.728924Z","caller":"traceutil/trace.go:171","msg":"trace[1223421684] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:595; }","duration":"171.038557ms","start":"2026-07-03T11:52:32.557755Z","end":"2026-07-03T11:52:32.728794Z","steps":["trace[1223421684] 'agreement among raft nodes before linearized reading' (duration: 170.971284ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T11:52:32.728942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.643108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-03T11:52:32.72915Z","caller":"traceutil/trace.go:171","msg":"trace[1335181148] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:595; }","duration":"190.779974ms","start":"2026-07-03T11:52:32.538242Z","end":"2026-07-03T11:52:32.729022Z","steps":["trace[1335181148] 'agreement among raft nodes before linearized reading' (duration: 190.560804ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T11:52:32.72988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.224141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-03T11:52:32.729912Z","caller":"traceutil/trace.go:171","msg":"trace[1898474910] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:595; }","duration":"172.270223ms","start":"2026-07-03T11:52:32.557635Z","end":"2026-07-03T11:52:32.729905Z","steps":["trace[1898474910] 'agreement among raft nodes before linearized reading' (duration: 172.227881ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T11:52:32.729919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.21823ms","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-07-03T11:52:32.729953Z","caller":"traceutil/trace.go:171","msg":"trace[68348556] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:595; }","duration":"172.271443ms","start":"2026-07-03T11:52:32.557671Z","end":"2026-07-03T11:52:32.729943Z","steps":["trace[68348556] 'agreement among raft nodes before linearized reading' (duration: 172.20791ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T11:52:32.730159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.594437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/httproutes.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:151236"} {"level":"info","ts":"2026-07-03T11:52:32.730182Z","caller":"traceutil/trace.go:171","msg":"trace[662142445] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/httproutes.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:595; }","duration":"172.648409ms","start":"2026-07-03T11:52:32.557527Z","end":"2026-07-03T11:52:32.730176Z","steps":["trace[662142445] 'agreement among raft nodes before linearized reading' (duration: 172.541924ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T11:52:32.99675Z","caller":"traceutil/trace.go:171","msg":"trace[336547300] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"261.906451ms","start":"2026-07-03T11:52:32.734819Z","end":"2026-07-03T11:52:32.996726Z","steps":["trace[336547300] 'process raft request' (duration: 199.159671ms)","trace[336547300] 'compare' (duration: 62.556192ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T11:52:32.997136Z","caller":"traceutil/trace.go:171","msg":"trace[914657824] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"262.075939ms","start":"2026-07-03T11:52:32.73502Z","end":"2026-07-03T11:52:32.997096Z","steps":["trace[914657824] 'process raft request' (duration: 261.672111ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T11:52:32.997195Z","caller":"traceutil/trace.go:171","msg":"trace[572020067] linearizableReadLoop","detail":"{readStateIndex:625; appliedIndex:622; }","duration":"220.27718ms","start":"2026-07-03T11:52:32.776891Z","end":"2026-07-03T11:52:32.997168Z","steps":["trace[572020067] 'read index received' (duration: 8.955812ms)","trace[572020067] 'applied index is now lower than readState.Index' (duration: 211.320278ms)"],"step_count":2} {"level":"warn","ts":"2026-07-03T11:52:32.997432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.515151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/referencegrants.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:7708"} {"level":"info","ts":"2026-07-03T11:52:32.997466Z","caller":"traceutil/trace.go:171","msg":"trace[1185004825] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/referencegrants.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:599; }","duration":"220.608925ms","start":"2026-07-03T11:52:32.776847Z","end":"2026-07-03T11:52:32.997455Z","steps":["trace[1185004825] 'agreement among raft nodes before linearized reading' (duration: 220.423037ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T11:52:32.997525Z","caller":"traceutil/trace.go:171","msg":"trace[1213262564] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"254.290039ms","start":"2026-07-03T11:52:32.743226Z","end":"2026-07-03T11:52:32.997516Z","steps":["trace[1213262564] 'process raft request' (duration: 253.804057ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T11:52:38.009907Z","caller":"traceutil/trace.go:171","msg":"trace[108791803] linearizableReadLoop","detail":"{readStateIndex:656; appliedIndex:655; }","duration":"200.362712ms","start":"2026-07-03T11:52:37.809506Z","end":"2026-07-03T11:52:38.009869Z","steps":["trace[108791803] 'read index received' (duration: 199.12563ms)","trace[108791803] 'applied index is now lower than readState.Index' (duration: 1.235682ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T11:52:38.010087Z","caller":"traceutil/trace.go:171","msg":"trace[1105934440] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"602.482374ms","start":"2026-07-03T11:52:37.407593Z","end":"2026-07-03T11:52:38.010075Z","steps":["trace[1105934440] 'process raft request' (duration: 601.415919ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T11:52:38.010235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T11:52:37.407577Z","time spent":"602.544767ms","remote":"127.0.0.1:56140","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036257,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-03T11:52:38.728923Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305497142491033701,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-03T11:52:38.794113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"984.565122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-fl5kq\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-07-03T11:52:38.794226Z","caller":"traceutil/trace.go:171","msg":"trace[30582295] range","detail":"{range_begin:/registry/pods/kube-system/cilium-fl5kq; range_end:; response_count:1; response_revision:629; }","duration":"984.74514ms","start":"2026-07-03T11:52:37.809451Z","end":"2026-07-03T11:52:38.794196Z","steps":["trace[30582295] 'agreement among raft nodes before linearized reading' (duration: 200.587202ms)","trace[30582295] 'range keys from in-memory index tree' (duration: 783.967169ms)"],"step_count":2} {"level":"warn","ts":"2026-07-03T11:52:38.794265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T11:52:37.809426Z","time spent":"984.830963ms","remote":"127.0.0.1:56260","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-fl5kq\" "} {"level":"info","ts":"2026-07-03T11:52:38.794371Z","caller":"traceutil/trace.go:171","msg":"trace[672398619] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"718.680137ms","start":"2026-07-03T11:52:38.075658Z","end":"2026-07-03T11:52:38.794338Z","steps":["trace[672398619] 'process raft request' (duration: 712.928954ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T11:52:38.794489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T11:52:38.07561Z","time spent":"718.823593ms","remote":"127.0.0.1:56410","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-03T11:52:38.875822Z","caller":"traceutil/trace.go:171","msg":"trace[1704039021] linearizableReadLoop","detail":"{readStateIndex:658; appliedIndex:656; }","duration":"647.430044ms","start":"2026-07-03T11:52:38.228373Z","end":"2026-07-03T11:52:38.875803Z","steps":["trace[1704039021] 'read index received' (duration: 560.252869ms)","trace[1704039021] 'applied index is now lower than readState.Index' (duration: 87.175895ms)"],"step_count":2} {"level":"warn","ts":"2026-07-03T11:52:38.875963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"647.568159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-03T11:52:38.875984Z","caller":"traceutil/trace.go:171","msg":"trace[59664716] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:631; }","duration":"647.689414ms","start":"2026-07-03T11:52:38.228287Z","end":"2026-07-03T11:52:38.875977Z","steps":["trace[59664716] 'agreement among raft nodes before linearized reading' (duration: 647.603171ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T11:52:38.876002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T11:52:38.228263Z","time spent":"647.734556ms","remote":"127.0.0.1:60176","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-07-03T11:52:38.875968Z","caller":"traceutil/trace.go:171","msg":"trace[316239294] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"653.143804ms","start":"2026-07-03T11:52:38.222776Z","end":"2026-07-03T11:52:38.87592Z","steps":["trace[316239294] 'process raft request' (duration: 652.877033ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T11:52:38.876165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T11:52:38.222748Z","time spent":"653.307412ms","remote":"127.0.0.1:56328","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-03T11:52:39.066223Z","caller":"traceutil/trace.go:171","msg":"trace[1243932128] linearizableReadLoop","detail":"{readStateIndex:659; appliedIndex:658; }","duration":"190.325135ms","start":"2026-07-03T11:52:38.875868Z","end":"2026-07-03T11:52:39.066193Z","steps":["trace[1243932128] 'read index received' (duration: 190.129237ms)","trace[1243932128] 'applied index is now lower than readState.Index' (duration: 194.368µs)"],"step_count":2} {"level":"info","ts":"2026-07-03T11:52:39.066334Z","caller":"traceutil/trace.go:171","msg":"trace[2128281352] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"256.978856ms","start":"2026-07-03T11:52:38.809321Z","end":"2026-07-03T11:52:39.0663Z","steps":["trace[2128281352] 'process raft request' (duration: 256.658343ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T11:52:39.066434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.089598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-07-03T11:52:39.066456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"710.947303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-03T11:52:39.066735Z","caller":"traceutil/trace.go:171","msg":"trace[1203509081] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:632; }","duration":"251.39534ms","start":"2026-07-03T11:52:38.815272Z","end":"2026-07-03T11:52:39.066668Z","steps":["trace[1203509081] 'agreement among raft nodes before linearized reading' (duration: 250.950142ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T11:52:39.0668Z","caller":"traceutil/trace.go:171","msg":"trace[1033890046] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:632; }","duration":"711.316359ms","start":"2026-07-03T11:52:38.355464Z","end":"2026-07-03T11:52:39.066781Z","steps":["trace[1033890046] 'agreement among raft nodes before linearized reading' (duration: 710.959494ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T11:52:39.066855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T11:52:38.355439Z","time spent":"711.399532ms","remote":"127.0.0.1:60178","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-07-03T11:52:39.066339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.860219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-03T11:52:39.067003Z","caller":"traceutil/trace.go:171","msg":"trace[2139577382] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system; range_end:; response_count:0; response_revision:632; }","duration":"261.554418ms","start":"2026-07-03T11:52:38.805401Z","end":"2026-07-03T11:52:39.066955Z","steps":["trace[2139577382] 'agreement among raft nodes before linearized reading' (duration: 260.861099ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T11:52:39.730873Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305497142491033707,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-03T11:52:40.174948Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.103086314s","expected-duration":"1s"} {"level":"warn","ts":"2026-07-03T11:52:41.132457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"957.233474ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-03T11:52:41.132705Z","caller":"traceutil/trace.go:171","msg":"trace[558845902] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"2.060904822s","start":"2026-07-03T11:52:39.071771Z","end":"2026-07-03T11:52:41.132676Z","steps":["trace[558845902] 'process raft request' (duration: 1.103364906s)","trace[558845902] 'compare' (duration: 947.173142ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T11:52:41.132942Z","caller":"traceutil/trace.go:171","msg":"trace[1034094938] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"2.059517204s","start":"2026-07-03T11:52:39.073397Z","end":"2026-07-03T11:52:41.132914Z","steps":["trace[1034094938] 'process raft request' (duration: 2.059323806s)"],"step_count":1} {"level":"info","ts":"2026-07-03T11:52:41.1328Z","caller":"traceutil/trace.go:171","msg":"trace[404405937] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"2.060273696s","start":"2026-07-03T11:52:39.072513Z","end":"2026-07-03T11:52:41.132787Z","steps":["trace[404405937] 'process raft request' (duration: 2.060032846s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T11:52:41.133139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T11:52:39.071751Z","time spent":"2.06135002s","remote":"127.0.0.1:56328","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-03T11:52:41.133192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T11:52:39.073387Z","time spent":"2.059582176s","remote":"127.0.0.1:56328","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-07-03T11:52:41.133229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T11:52:39.072467Z","time spent":"2.060572068s","remote":"127.0.0.1:56328","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-07-03T11:52:41.134094Z","caller":"traceutil/trace.go:171","msg":"trace[1297553103] linearizableReadLoop","detail":"{readStateIndex:663; appliedIndex:659; }","duration":"1.903373588s","start":"2026-07-03T11:52:39.230706Z","end":"2026-07-03T11:52:41.134079Z","steps":["trace[1297553103] 'read index received' (duration: 944.475047ms)","trace[1297553103] 'applied index is now lower than readState.Index' (duration: 958.897681ms)"],"step_count":2} {"level":"warn","ts":"2026-07-03T11:52:41.134206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.903497604s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-03T11:52:41.134243Z","caller":"traceutil/trace.go:171","msg":"trace[2031081335] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:636; }","duration":"1.903567877s","start":"2026-07-03T11:52:39.230667Z","end":"2026-07-03T11:52:41.134235Z","steps":["trace[2031081335] 'agreement among raft nodes before linearized reading' (duration: 1.903454952s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T11:52:41.134356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T11:52:39.230649Z","time spent":"1.903610129s","remote":"127.0.0.1:60176","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-07-03T11:52:41.134375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.848231658s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-03T11:52:41.134416Z","caller":"traceutil/trace.go:171","msg":"trace[1048549921] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:636; }","duration":"1.848290251s","start":"2026-07-03T11:52:39.2861Z","end":"2026-07-03T11:52:41.13439Z","steps":["trace[1048549921] 'agreement among raft nodes before linearized reading' (duration: 1.848170616s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T11:52:41.134504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T11:52:39.286082Z","time spent":"1.848356053s","remote":"127.0.0.1:56328","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-07-03T11:52:41.134671Z","caller":"traceutil/trace.go:171","msg":"trace[1709666977] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"1.672298958s","start":"2026-07-03T11:52:39.46236Z","end":"2026-07-03T11:52:41.134659Z","steps":["trace[1709666977] 'process raft request' (duration: 1.67088852s)"],"step_count":1} {"level":"warn","ts":"2026-07-03T11:52:41.134719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T11:52:39.462334Z","time spent":"1.67236118s","remote":"127.0.0.1:56140","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036289,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-03T11:52:42.114295Z","caller":"traceutil/trace.go:171","msg":"trace[635451696] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"190.79542ms","start":"2026-07-03T11:52:41.923457Z","end":"2026-07-03T11:52:42.114252Z","steps":["trace[635451696] 'process raft request' (duration: 190.489448ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T11:52:42.114358Z","caller":"traceutil/trace.go:171","msg":"trace[1373355922] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"189.410323ms","start":"2026-07-03T11:52:41.924913Z","end":"2026-07-03T11:52:42.114324Z","steps":["trace[1373355922] 'process raft request' (duration: 189.238026ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T11:52:42.114362Z","caller":"traceutil/trace.go:171","msg":"trace[734281519] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"188.383531ms","start":"2026-07-03T11:52:41.925951Z","end":"2026-07-03T11:52:42.114335Z","steps":["trace[734281519] 'process raft request' (duration: 188.276667ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T11:52:42.114498Z","caller":"traceutil/trace.go:171","msg":"trace[738259528] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"183.619187ms","start":"2026-07-03T11:52:41.930844Z","end":"2026-07-03T11:52:42.114463Z","steps":["trace[738259528] 'process raft request' (duration: 183.439299ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T11:52:43.831357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.417865ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-03T11:52:43.831632Z","caller":"traceutil/trace.go:171","msg":"trace[1489101357] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"661.546109ms","start":"2026-07-03T11:52:43.170036Z","end":"2026-07-03T11:52:43.831582Z","steps":["trace[1489101357] 'process raft request' (duration: 661.429244ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T11:52:43.831704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T11:52:43.170024Z","time spent":"661.654714ms","remote":"127.0.0.1:56328","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-03T11:52:43.831834Z","caller":"traceutil/trace.go:171","msg":"trace[1348940384] linearizableReadLoop","detail":"{readStateIndex:684; appliedIndex:681; }","duration":"622.147522ms","start":"2026-07-03T11:52:43.209655Z","end":"2026-07-03T11:52:43.831803Z","steps":["trace[1348940384] 'read index received' (duration: 295.310389ms)","trace[1348940384] 'applied index is now lower than readState.Index' (duration: 326.835863ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T11:52:43.831892Z","caller":"traceutil/trace.go:171","msg":"trace[146435937] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"662.829121ms","start":"2026-07-03T11:52:43.169051Z","end":"2026-07-03T11:52:43.83188Z","steps":["trace[146435937] 'process raft request' (duration: 335.764609ms)","trace[146435937] 'compare' (duration: 326.234288ms)"],"step_count":2} {"level":"warn","ts":"2026-07-03T11:52:43.831938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T11:52:43.16902Z","time spent":"662.896453ms","remote":"127.0.0.1:56328","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-03T11:52:43.83209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"622.414123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-03T11:52:43.832121Z","caller":"traceutil/trace.go:171","msg":"trace[331774551] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:656; }","duration":"622.516537ms","start":"2026-07-03T11:52:43.209594Z","end":"2026-07-03T11:52:43.83211Z","steps":["trace[331774551] 'agreement among raft nodes before linearized reading' (duration: 622.313329ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T11:52:43.832148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T11:52:43.209579Z","time spent":"622.562318ms","remote":"127.0.0.1:56328","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-03T11:52:43.832253Z","caller":"traceutil/trace.go:171","msg":"trace[1223405657] transaction","detail":"{read_only:false; response_revision:656; number_of_response:1; }","duration":"647.225756ms","start":"2026-07-03T11:52:43.185016Z","end":"2026-07-03T11:52:43.832241Z","steps":["trace[1223405657] 'process raft request' (duration: 646.519298ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T11:52:43.832304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T11:52:43.184992Z","time spent":"647.280878ms","remote":"127.0.0.1:56130","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":722,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-03T11:52:51.988285Z","caller":"traceutil/trace.go:171","msg":"trace[1497632560] linearizableReadLoop","detail":"{readStateIndex:786; appliedIndex:785; }","duration":"113.366889ms","start":"2026-07-03T11:52:51.874878Z","end":"2026-07-03T11:52:51.988245Z","steps":["trace[1497632560] 'read index received' (duration: 113.059008ms)","trace[1497632560] 'applied index is now lower than readState.Index' (duration: 306.221µs)"],"step_count":2} {"level":"info","ts":"2026-07-03T11:52:51.98853Z","caller":"traceutil/trace.go:171","msg":"trace[903379456] transaction","detail":"{read_only:false; response_revision:755; number_of_response:1; }","duration":"117.081816ms","start":"2026-07-03T11:52:51.871346Z","end":"2026-07-03T11:52:51.988428Z","steps":["trace[903379456] 'process raft request' (duration: 116.597958ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T11:52:51.98868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.769014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-03T11:52:51.988727Z","caller":"traceutil/trace.go:171","msg":"trace[1092106313] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:755; }","duration":"113.859717ms","start":"2026-07-03T11:52:51.874857Z","end":"2026-07-03T11:52:51.988717Z","steps":["trace[1092106313] 'agreement among raft nodes before linearized reading' (duration: 113.65979ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T11:52:54.220331Z","caller":"traceutil/trace.go:171","msg":"trace[2074858320] linearizableReadLoop","detail":"{readStateIndex:800; appliedIndex:799; }","duration":"208.017195ms","start":"2026-07-03T11:52:54.012264Z","end":"2026-07-03T11:52:54.220281Z","steps":["trace[2074858320] 'read index received' (duration: 207.86291ms)","trace[2074858320] 'applied index is now lower than readState.Index' (duration: 152.975µs)"],"step_count":2} {"level":"warn","ts":"2026-07-03T11:52:54.220572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.590346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-03T11:52:54.220602Z","caller":"traceutil/trace.go:171","msg":"trace[1009133824] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:769; }","duration":"181.6732ms","start":"2026-07-03T11:52:54.038918Z","end":"2026-07-03T11:52:54.220591Z","steps":["trace[1009133824] 'agreement among raft nodes before linearized reading' (duration: 181.539736ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T11:52:54.220698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.372799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-03T11:52:54.220828Z","caller":"traceutil/trace.go:171","msg":"trace[855049118] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:769; }","duration":"208.583506ms","start":"2026-07-03T11:52:54.012215Z","end":"2026-07-03T11:52:54.220799Z","steps":["trace[855049118] 'agreement among raft nodes before linearized reading' (duration: 208.199912ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T11:52:54.221034Z","caller":"traceutil/trace.go:171","msg":"trace[1614170917] transaction","detail":"{read_only:false; response_revision:769; number_of_response:1; }","duration":"222.887422ms","start":"2026-07-03T11:52:53.997479Z","end":"2026-07-03T11:52:54.220366Z","steps":["trace[1614170917] 'process raft request' (duration: 222.638053ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T11:52:54.745067Z","caller":"traceutil/trace.go:171","msg":"trace[20619544] transaction","detail":"{read_only:false; response_revision:772; number_of_response:1; }","duration":"116.20536ms","start":"2026-07-03T11:52:54.628829Z","end":"2026-07-03T11:52:54.745034Z","steps":["trace[20619544] 'process raft request' (duration: 116.010443ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T11:52:54.928073Z","caller":"traceutil/trace.go:171","msg":"trace[901319294] transaction","detail":"{read_only:false; response_revision:774; number_of_response:1; }","duration":"109.695106ms","start":"2026-07-03T11:52:54.818346Z","end":"2026-07-03T11:52:54.928041Z","steps":["trace[901319294] 'process raft request' (duration: 88.136072ms)","trace[901319294] 'compare' (duration: 21.361977ms)"],"step_count":2} {"level":"warn","ts":"2026-07-03T11:52:56.463089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.255834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-03T11:52:56.463388Z","caller":"traceutil/trace.go:171","msg":"trace[2136141487] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:787; }","duration":"235.608597ms","start":"2026-07-03T11:52:56.22774Z","end":"2026-07-03T11:52:56.463348Z","steps":["trace[2136141487] 'range keys from in-memory index tree' (duration: 234.989434ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T11:52:56.462623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.21503ms","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-07-03T11:52:56.464774Z","caller":"traceutil/trace.go:171","msg":"trace[972585562] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:787; }","duration":"152.473829ms","start":"2026-07-03T11:52:56.31227Z","end":"2026-07-03T11:52:56.464744Z","steps":["trace[972585562] 'range keys from in-memory index tree' (duration: 149.97026ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T11:52:56.462965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.864829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-03T11:52:56.464984Z","caller":"traceutil/trace.go:171","msg":"trace[663117039] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:787; }","duration":"235.489082ms","start":"2026-07-03T11:52:56.229442Z","end":"2026-07-03T11:52:56.464931Z","steps":["trace[663117039] 'range keys from in-memory index tree' (duration: 232.787288ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T11:52:56.46387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.622899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-03T11:52:56.465127Z","caller":"traceutil/trace.go:171","msg":"trace[1343887917] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:787; }","duration":"152.935735ms","start":"2026-07-03T11:52:56.312153Z","end":"2026-07-03T11:52:56.465089Z","steps":["trace[1343887917] 'range keys from in-memory index tree' (duration: 150.112616ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T11:52:59.438335Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305497142491034044,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-03T11:52:59.655471Z","caller":"traceutil/trace.go:171","msg":"trace[359349981] transaction","detail":"{read_only:false; response_revision:804; number_of_response:1; }","duration":"815.749558ms","start":"2026-07-03T11:52:58.839692Z","end":"2026-07-03T11:52:59.655442Z","steps":["trace[359349981] 'process raft request' (duration: 815.570702ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T11:52:59.655665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T11:52:58.839662Z","time spent":"815.909723ms","remote":"127.0.0.1:56328","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-03T11:52:59.761096Z","caller":"traceutil/trace.go:171","msg":"trace[295265194] transaction","detail":"{read_only:false; response_revision:805; number_of_response:1; }","duration":"830.612847ms","start":"2026-07-03T11:52:58.93045Z","end":"2026-07-03T11:52:59.761063Z","steps":["trace[295265194] 'process raft request' (duration: 830.376969ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T11:52:59.761218Z","caller":"traceutil/trace.go:171","msg":"trace[48121234] linearizableReadLoop","detail":"{readStateIndex:839; appliedIndex:836; }","duration":"823.46028ms","start":"2026-07-03T11:52:58.93773Z","end":"2026-07-03T11:52:59.76119Z","steps":["trace[48121234] 'read index received' (duration: 717.443001ms)","trace[48121234] 'applied index is now lower than readState.Index' (duration: 106.006598ms)"],"step_count":2} {"level":"warn","ts":"2026-07-03T11:52:59.761255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T11:52:58.9304Z","time spent":"830.771373ms","remote":"127.0.0.1:56248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4905,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-03T11:52:59.761341Z","caller":"traceutil/trace.go:171","msg":"trace[1641967134] transaction","detail":"{read_only:false; response_revision:806; number_of_response:1; }","duration":"417.514952ms","start":"2026-07-03T11:52:59.343798Z","end":"2026-07-03T11:52:59.761313Z","steps":["trace[1641967134] 'process raft request' (duration: 417.194441ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T11:52:59.761485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"640.07601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-03T11:52:59.761819Z","caller":"traceutil/trace.go:171","msg":"trace[1689873696] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:806; }","duration":"640.455854ms","start":"2026-07-03T11:52:59.121347Z","end":"2026-07-03T11:52:59.761803Z","steps":["trace[1689873696] 'agreement among raft nodes before linearized reading' (duration: 640.092221ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T11:52:59.761858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T11:52:59.121323Z","time spent":"640.521615ms","remote":"127.0.0.1:56378","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":28,"request content":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true "} {"level":"warn","ts":"2026-07-03T11:52:59.76196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T11:52:59.343769Z","time spent":"418.006699ms","remote":"127.0.0.1:56328","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-03T11:52:59.761525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"823.797511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-t7vjx\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-07-03T11:52:59.762113Z","caller":"traceutil/trace.go:171","msg":"trace[1621504606] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-t7vjx; range_end:; response_count:1; response_revision:806; }","duration":"824.443283ms","start":"2026-07-03T11:52:58.937639Z","end":"2026-07-03T11:52:59.762082Z","steps":["trace[1621504606] 'agreement among raft nodes before linearized reading' (duration: 823.674017ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T11:52:59.76226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T11:52:58.937594Z","time spent":"824.62781ms","remote":"127.0.0.1:56260","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3988,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-t7vjx\" "} {"level":"warn","ts":"2026-07-03T11:52:59.761571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"534.62343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-03T11:52:59.762454Z","caller":"traceutil/trace.go:171","msg":"trace[817129665] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:806; }","duration":"535.535561ms","start":"2026-07-03T11:52:59.226905Z","end":"2026-07-03T11:52:59.762441Z","steps":["trace[817129665] 'agreement among raft nodes before linearized reading' (duration: 534.63181ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T11:52:59.76163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.048677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-03T11:52:59.762645Z","caller":"traceutil/trace.go:171","msg":"trace[1351416800] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:806; }","duration":"468.067112ms","start":"2026-07-03T11:52:59.294541Z","end":"2026-07-03T11:52:59.762608Z","steps":["trace[1351416800] 'agreement among raft nodes before linearized reading' (duration: 466.983425ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T11:52:59.76269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T11:52:59.294525Z","time spent":"468.152085ms","remote":"127.0.0.1:56328","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-07-03T11:52:59.762518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T11:52:59.226885Z","time spent":"535.617875ms","remote":"127.0.0.1:60176","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-07-03T11:53:06.032067Z","caller":"traceutil/trace.go:171","msg":"trace[1300523279] transaction","detail":"{read_only:false; response_revision:869; number_of_response:1; }","duration":"230.603646ms","start":"2026-07-03T11:53:05.801439Z","end":"2026-07-03T11:53:06.032042Z","steps":["trace[1300523279] 'process raft request' (duration: 230.43615ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T11:53:06.03274Z","caller":"traceutil/trace.go:171","msg":"trace[825845785] transaction","detail":"{read_only:false; response_revision:870; number_of_response:1; }","duration":"142.546823ms","start":"2026-07-03T11:53:05.890177Z","end":"2026-07-03T11:53:06.032724Z","steps":["trace[825845785] 'process raft request' (duration: 142.202182ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T11:53:14.334273Z","caller":"traceutil/trace.go:171","msg":"trace[1494068275] transaction","detail":"{read_only:false; response_revision:977; number_of_response:1; }","duration":"183.432809ms","start":"2026-07-03T11:53:14.150808Z","end":"2026-07-03T11:53:14.334241Z","steps":["trace[1494068275] 'process raft request' (duration: 183.253254ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T11:53:14.335309Z","caller":"traceutil/trace.go:171","msg":"trace[549838735] linearizableReadLoop","detail":"{readStateIndex:1014; appliedIndex:1012; }","duration":"107.439601ms","start":"2026-07-03T11:53:14.227858Z","end":"2026-07-03T11:53:14.335297Z","steps":["trace[549838735] 'read index received' (duration: 106.212454ms)","trace[549838735] 'applied index is now lower than readState.Index' (duration: 1.226237ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T11:53:14.335468Z","caller":"traceutil/trace.go:171","msg":"trace[1056151615] transaction","detail":"{read_only:false; response_revision:978; number_of_response:1; }","duration":"183.490211ms","start":"2026-07-03T11:53:14.151916Z","end":"2026-07-03T11:53:14.335406Z","steps":["trace[1056151615] 'process raft request' (duration: 183.319206ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T11:53:14.335618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.754711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-03T11:53:14.335674Z","caller":"traceutil/trace.go:171","msg":"trace[256016177] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:978; }","duration":"107.859194ms","start":"2026-07-03T11:53:14.227805Z","end":"2026-07-03T11:53:14.335664Z","steps":["trace[256016177] 'agreement among raft nodes before linearized reading' (duration: 107.671038ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T11:53:17.401899Z","caller":"traceutil/trace.go:171","msg":"trace[1934360834] transaction","detail":"{read_only:false; response_revision:992; number_of_response:1; }","duration":"495.073139ms","start":"2026-07-03T11:53:16.906793Z","end":"2026-07-03T11:53:17.401866Z","steps":["trace[1934360834] 'process raft request' (duration: 494.306527ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T11:53:17.402016Z","caller":"traceutil/trace.go:171","msg":"trace[1056424684] transaction","detail":"{read_only:false; response_revision:993; number_of_response:1; }","duration":"493.510663ms","start":"2026-07-03T11:53:16.908483Z","end":"2026-07-03T11:53:17.401994Z","steps":["trace[1056424684] 'process raft request' (duration: 493.39957ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T11:53:17.402081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T11:53:16.906785Z","time spent":"495.203373ms","remote":"127.0.0.1:56328","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-07-03T11:53:17.402099Z","caller":"traceutil/trace.go:171","msg":"trace[1211094777] linearizableReadLoop","detail":"{readStateIndex:1032; appliedIndex:1029; }","duration":"173.553805ms","start":"2026-07-03T11:53:17.228528Z","end":"2026-07-03T11:53:17.402082Z","steps":["trace[1211094777] 'read index received' (duration: 172.582826ms)","trace[1211094777] 'applied index is now lower than readState.Index' (duration: 969.608µs)"],"step_count":2} {"level":"warn","ts":"2026-07-03T11:53:17.402169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.638257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-03T11:53:17.402165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T11:53:16.908477Z","time spent":"493.612036ms","remote":"127.0.0.1:56328","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-07-03T11:53:17.402202Z","caller":"traceutil/trace.go:171","msg":"trace[628096771] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:994; }","duration":"173.706279ms","start":"2026-07-03T11:53:17.228488Z","end":"2026-07-03T11:53:17.402195Z","steps":["trace[628096771] 'agreement among raft nodes before linearized reading' (duration: 173.640077ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T11:53:17.402332Z","caller":"traceutil/trace.go:171","msg":"trace[1300038364] transaction","detail":"{read_only:false; response_revision:994; number_of_response:1; }","duration":"493.325628ms","start":"2026-07-03T11:53:16.909Z","end":"2026-07-03T11:53:17.402326Z","steps":["trace[1300038364] 'process raft request' (duration: 492.962507ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T11:53:17.402374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T11:53:16.908985Z","time spent":"493.362679ms","remote":"127.0.0.1:56328","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-03T11:54:35.940023Z","caller":"traceutil/trace.go:171","msg":"trace[1680943456] transaction","detail":"{read_only:false; response_revision:1255; number_of_response:1; }","duration":"110.914319ms","start":"2026-07-03T11:54:35.829076Z","end":"2026-07-03T11:54:35.939991Z","steps":["trace[1680943456] 'process raft request' (duration: 110.673105ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T11:54:46.145991Z","caller":"traceutil/trace.go:171","msg":"trace[1790753162] linearizableReadLoop","detail":"{readStateIndex:1374; appliedIndex:1373; }","duration":"195.981196ms","start":"2026-07-03T11:54:45.949992Z","end":"2026-07-03T11:54:46.145974Z","steps":["trace[1790753162] 'read index received' (duration: 195.873794ms)","trace[1790753162] 'applied index is now lower than readState.Index' (duration: 106.222µs)"],"step_count":2} {"level":"info","ts":"2026-07-03T11:54:46.146055Z","caller":"traceutil/trace.go:171","msg":"trace[1830760045] transaction","detail":"{read_only:false; response_revision:1317; number_of_response:1; }","duration":"250.756882ms","start":"2026-07-03T11:54:45.895261Z","end":"2026-07-03T11:54:46.146018Z","steps":["trace[1830760045] 'process raft request' (duration: 250.60802ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T11:54:46.146148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.150178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.210\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-03T11:54:46.146173Z","caller":"traceutil/trace.go:171","msg":"trace[1028341101] range","detail":"{range_begin:/registry/masterleases/199.204.45.210; range_end:; response_count:1; response_revision:1317; }","duration":"196.22584ms","start":"2026-07-03T11:54:45.949941Z","end":"2026-07-03T11:54:46.146167Z","steps":["trace[1028341101] 'agreement among raft nodes before linearized reading' (duration: 196.123128ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T11:54:46.14642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.835685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-03T11:54:46.146542Z","caller":"traceutil/trace.go:171","msg":"trace[969962267] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1317; }","duration":"172.018338ms","start":"2026-07-03T11:54:45.974505Z","end":"2026-07-03T11:54:46.146523Z","steps":["trace[969962267] 'agreement among raft nodes before linearized reading' (duration: 171.722293ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T11:54:46.146563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.902334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-03T11:54:46.146718Z","caller":"traceutil/trace.go:171","msg":"trace[561902137] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1317; }","duration":"187.128958ms","start":"2026-07-03T11:54:45.959571Z","end":"2026-07-03T11:54:46.1467Z","steps":["trace[561902137] 'agreement among raft nodes before linearized reading' (duration: 186.547197ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T11:54:46.336337Z","caller":"traceutil/trace.go:171","msg":"trace[1357021810] linearizableReadLoop","detail":"{readStateIndex:1377; appliedIndex:1374; }","duration":"152.312686ms","start":"2026-07-03T11:54:46.183991Z","end":"2026-07-03T11:54:46.336304Z","steps":["trace[1357021810] 'read index received' (duration: 150.400962ms)","trace[1357021810] 'applied index is now lower than readState.Index' (duration: 1.911244ms)"],"step_count":2} {"level":"info","ts":"2026-07-03T11:54:46.336525Z","caller":"traceutil/trace.go:171","msg":"trace[291642076] transaction","detail":"{read_only:false; response_revision:1318; number_of_response:1; }","duration":"186.812889ms","start":"2026-07-03T11:54:46.149697Z","end":"2026-07-03T11:54:46.33651Z","steps":["trace[291642076] 'process raft request' (duration: 186.493873ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T11:54:46.336607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.55858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-03T11:54:46.336668Z","caller":"traceutil/trace.go:171","msg":"trace[1321043608] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1319; }","duration":"152.707574ms","start":"2026-07-03T11:54:46.183952Z","end":"2026-07-03T11:54:46.33666Z","steps":["trace[1321043608] 'agreement among raft nodes before linearized reading' (duration: 152.48778ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T11:54:46.336764Z","caller":"traceutil/trace.go:171","msg":"trace[2132264459] transaction","detail":"{read_only:false; response_revision:1319; number_of_response:1; }","duration":"186.088926ms","start":"2026-07-03T11:54:46.150643Z","end":"2026-07-03T11:54:46.336732Z","steps":["trace[2132264459] 'process raft request' (duration: 185.639228ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T11:54:55.893289Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13305497142491035385,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-03T11:54:56.048077Z","caller":"traceutil/trace.go:171","msg":"trace[996090443] transaction","detail":"{read_only:false; response_revision:1354; number_of_response:1; }","duration":"879.115141ms","start":"2026-07-03T11:54:55.168943Z","end":"2026-07-03T11:54:56.048058Z","steps":["trace[996090443] 'process raft request' (duration: 879.006749ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T11:54:56.048203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T11:54:55.168923Z","time spent":"879.205752ms","remote":"127.0.0.1:56328","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-03T11:54:56.120563Z","caller":"traceutil/trace.go:171","msg":"trace[437475636] linearizableReadLoop","detail":"{readStateIndex:1414; appliedIndex:1412; }","duration":"727.802914ms","start":"2026-07-03T11:54:55.392739Z","end":"2026-07-03T11:54:56.120542Z","steps":["trace[437475636] 'read index received' (duration: 655.153083ms)","trace[437475636] 'applied index is now lower than readState.Index' (duration: 72.648751ms)"],"step_count":2} {"level":"warn","ts":"2026-07-03T11:54:56.120707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"649.707876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-03T11:54:56.120736Z","caller":"traceutil/trace.go:171","msg":"trace[982520443] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:1355; }","duration":"649.788597ms","start":"2026-07-03T11:54:55.470938Z","end":"2026-07-03T11:54:56.120727Z","steps":["trace[982520443] 'agreement among raft nodes before linearized reading' (duration: 649.727736ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T11:54:56.121311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T11:54:55.470917Z","time spent":"650.378387ms","remote":"127.0.0.1:56376","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true "} {"level":"warn","ts":"2026-07-03T11:54:56.120777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.0129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.210\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-03T11:54:56.121581Z","caller":"traceutil/trace.go:171","msg":"trace[1175846728] range","detail":"{range_begin:/registry/masterleases/199.204.45.210; range_end:; response_count:1; response_revision:1355; }","duration":"170.852925ms","start":"2026-07-03T11:54:55.950714Z","end":"2026-07-03T11:54:56.121567Z","steps":["trace[1175846728] 'agreement among raft nodes before linearized reading' (duration: 169.97345ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T11:54:56.12068Z","caller":"traceutil/trace.go:171","msg":"trace[566207184] transaction","detail":"{read_only:false; response_revision:1355; number_of_response:1; }","duration":"599.576319ms","start":"2026-07-03T11:54:55.521062Z","end":"2026-07-03T11:54:56.120639Z","steps":["trace[566207184] 'process raft request' (duration: 578.803432ms)","trace[566207184] 'compare' (duration: 20.572754ms)"],"step_count":2} {"level":"warn","ts":"2026-07-03T11:54:56.121939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T11:54:55.521036Z","time spent":"600.77375ms","remote":"127.0.0.1:56328","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-07-03T11:54:56.120767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"728.020039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-03T11:54:56.122084Z","caller":"traceutil/trace.go:171","msg":"trace[1879135375] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:1355; }","duration":"729.415372ms","start":"2026-07-03T11:54:55.392661Z","end":"2026-07-03T11:54:56.122077Z","steps":["trace[1879135375] 'agreement among raft nodes before linearized reading' (duration: 727.996357ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T11:54:56.122111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-03T11:54:55.392618Z","time spent":"729.485453ms","remote":"127.0.0.1:56248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":30,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true "} {"level":"warn","ts":"2026-07-03T11:54:56.120869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.164448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-03T11:54:56.122204Z","caller":"traceutil/trace.go:171","msg":"trace[2127269466] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:1355; }","duration":"191.570713ms","start":"2026-07-03T11:54:55.930625Z","end":"2026-07-03T11:54:56.122196Z","steps":["trace[2127269466] 'agreement among raft nodes before linearized reading' (duration: 190.144818ms)"],"step_count":1} {"level":"info","ts":"2026-07-03T11:55:02.631834Z","caller":"traceutil/trace.go:171","msg":"trace[827246527] linearizableReadLoop","detail":"{readStateIndex:1444; appliedIndex:1443; }","duration":"112.094248ms","start":"2026-07-03T11:55:02.519719Z","end":"2026-07-03T11:55:02.631814Z","steps":["trace[827246527] 'read index received' (duration: 111.938975ms)","trace[827246527] 'applied index is now lower than readState.Index' (duration: 154.403µs)"],"step_count":2} {"level":"info","ts":"2026-07-03T11:55:02.631973Z","caller":"traceutil/trace.go:171","msg":"trace[154057472] transaction","detail":"{read_only:false; response_revision:1383; number_of_response:1; }","duration":"115.135209ms","start":"2026-07-03T11:55:02.51683Z","end":"2026-07-03T11:55:02.631965Z","steps":["trace[154057472] 'process raft request' (duration: 114.844414ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T11:55:02.63213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.512955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:5548"} {"level":"info","ts":"2026-07-03T11:55:02.632194Z","caller":"traceutil/trace.go:171","msg":"trace[344636869] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:1383; }","duration":"109.593956ms","start":"2026-07-03T11:55:02.522581Z","end":"2026-07-03T11:55:02.632175Z","steps":["trace[344636869] 'agreement among raft nodes before linearized reading' (duration: 109.437293ms)"],"step_count":1} {"level":"warn","ts":"2026-07-03T11:55:02.632259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.536625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/admin\" ","response":"range_response_count:1 size:3548"} {"level":"info","ts":"2026-07-03T11:55:02.632287Z","caller":"traceutil/trace.go:171","msg":"trace[1321864467] range","detail":"{range_begin:/registry/clusterroles/admin; range_end:; response_count:1; response_revision:1383; }","duration":"112.606167ms","start":"2026-07-03T11:55:02.519672Z","end":"2026-07-03T11:55:02.632278Z","steps":["trace[1321864467] 'agreement among raft nodes before linearized reading' (duration: 112.540935ms)"],"step_count":1}