{"level":"warn","ts":"2026-05-05T18:12:37.158005Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-05T18:12:37.15811Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.250: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.250:2380","--initial-cluster=instance=https://199.204.45.250:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.250:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.250:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-05T18:12:37.158185Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-05T18:12:37.158201Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.250:2380"]} {"level":"info","ts":"2026-05-05T18:12:37.15822Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-05T18:12:37.158894Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.250:2379"]} {"level":"info","ts":"2026-05-05T18:12:37.158971Z","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.250:2380"],"listen-peer-urls":["https://199.204.45.250:2380"],"advertise-client-urls":["https://199.204.45.250:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.250:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.250:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-05T18:12:37.196083Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"36.896884ms"} {"level":"info","ts":"2026-05-05T18:12:37.204084Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d98d5f2c752cc52d","cluster-id":"c785b4aaf4bd3b11"} {"level":"info","ts":"2026-05-05T18:12:37.204171Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d switched to configuration voters=()"} {"level":"info","ts":"2026-05-05T18:12:37.204258Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d became follower at term 0"} {"level":"info","ts":"2026-05-05T18:12:37.204311Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d98d5f2c752cc52d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-05T18:12:37.204343Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d became follower at term 1"} {"level":"info","ts":"2026-05-05T18:12:37.204388Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d switched to configuration voters=(15676290522495632685)"} {"level":"warn","ts":"2026-05-05T18:12:37.210137Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-05T18:12:37.211403Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-05T18:12:37.212538Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-05T18:12:37.214857Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d98d5f2c752cc52d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-05T18:12:37.218955Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d98d5f2c752cc52d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-05T18:12:37.218836Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-05T18:12:37.219128Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-05T18:12:37.219147Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-05T18:12:37.222483Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d switched to configuration voters=(15676290522495632685)"} {"level":"info","ts":"2026-05-05T18:12:37.222674Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"c785b4aaf4bd3b11","local-member-id":"d98d5f2c752cc52d","added-peer-id":"d98d5f2c752cc52d","added-peer-peer-urls":["https://199.204.45.250:2380"]} {"level":"info","ts":"2026-05-05T18:12:37.223042Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-05T18:12:37.223172Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.250:2380"} {"level":"info","ts":"2026-05-05T18:12:37.223193Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.250:2380"} {"level":"info","ts":"2026-05-05T18:12:37.223353Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d98d5f2c752cc52d","initial-advertise-peer-urls":["https://199.204.45.250:2380"],"listen-peer-urls":["https://199.204.45.250:2380"],"advertise-client-urls":["https://199.204.45.250:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.250:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-05T18:12:37.223407Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-05T18:12:37.304602Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d is starting a new election at term 1"} {"level":"info","ts":"2026-05-05T18:12:37.304657Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d became pre-candidate at term 1"} {"level":"info","ts":"2026-05-05T18:12:37.304674Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d received MsgPreVoteResp from d98d5f2c752cc52d at term 1"} {"level":"info","ts":"2026-05-05T18:12:37.304688Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d became candidate at term 2"} {"level":"info","ts":"2026-05-05T18:12:37.304696Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d received MsgVoteResp from d98d5f2c752cc52d at term 2"} {"level":"info","ts":"2026-05-05T18:12:37.304706Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d became leader at term 2"} {"level":"info","ts":"2026-05-05T18:12:37.304715Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d98d5f2c752cc52d elected leader d98d5f2c752cc52d at term 2"} {"level":"info","ts":"2026-05-05T18:12:37.305855Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T18:12:37.306831Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-05T18:12:37.306823Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d98d5f2c752cc52d","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.250:2379]}","request-path":"/0/members/d98d5f2c752cc52d/attributes","cluster-id":"c785b4aaf4bd3b11","publish-timeout":"7s"} {"level":"info","ts":"2026-05-05T18:12:37.306872Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-05T18:12:37.307251Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-05T18:12:37.307367Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-05T18:12:37.307381Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"c785b4aaf4bd3b11","local-member-id":"d98d5f2c752cc52d","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T18:12:37.307654Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T18:12:37.307828Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T18:12:37.311878Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.250:2379"} {"level":"info","ts":"2026-05-05T18:12:37.312391Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-05-05T18:12:51.281998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.137062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-05T18:12:51.282172Z","caller":"traceutil/trace.go:171","msg":"trace[209369638] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:356; }","duration":"322.360749ms","start":"2026-05-05T18:12:50.959791Z","end":"2026-05-05T18:12:51.282152Z","steps":["trace[209369638] 'range keys from in-memory index tree' (duration: 322.023918ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:12:51.282223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:12:50.959773Z","time spent":"322.439241ms","remote":"127.0.0.1:59714","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-05T18:12:51.917129Z","caller":"traceutil/trace.go:171","msg":"trace[1179710093] transaction","detail":"{read_only:false; response_revision:360; number_of_response:1; }","duration":"162.925496ms","start":"2026-05-05T18:12:51.754185Z","end":"2026-05-05T18:12:51.917111Z","steps":["trace[1179710093] 'process raft request' (duration: 162.812031ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T18:12:54.397502Z","caller":"traceutil/trace.go:171","msg":"trace[894952006] transaction","detail":"{read_only:false; response_revision:367; number_of_response:1; }","duration":"204.35555ms","start":"2026-05-05T18:12:54.193114Z","end":"2026-05-05T18:12:54.397469Z","steps":["trace[894952006] 'process raft request' (duration: 203.585558ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T18:12:54.759024Z","caller":"traceutil/trace.go:171","msg":"trace[396609866] linearizableReadLoop","detail":"{readStateIndex:380; appliedIndex:379; }","duration":"247.500565ms","start":"2026-05-05T18:12:54.511496Z","end":"2026-05-05T18:12:54.758997Z","steps":["trace[396609866] 'read index received' (duration: 214.491034ms)","trace[396609866] 'applied index is now lower than readState.Index' (duration: 33.008521ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T18:12:54.759225Z","caller":"traceutil/trace.go:171","msg":"trace[1278278257] transaction","detail":"{read_only:false; response_revision:368; number_of_response:1; }","duration":"354.53586ms","start":"2026-05-05T18:12:54.404597Z","end":"2026-05-05T18:12:54.759133Z","steps":["trace[1278278257] 'process raft request' (duration: 321.349584ms)","trace[1278278257] 'compare' (duration: 32.897008ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T18:12:54.759236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.714641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-05T18:12:54.759905Z","caller":"traceutil/trace.go:171","msg":"trace[64355618] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:368; }","duration":"248.429102ms","start":"2026-05-05T18:12:54.511452Z","end":"2026-05-05T18:12:54.759881Z","steps":["trace[64355618] 'agreement among raft nodes before linearized reading' (duration: 247.701331ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:12:54.761317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:12:54.404575Z","time spent":"355.117577ms","remote":"127.0.0.1:59614","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4119,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T18:12:56.929868Z","caller":"traceutil/trace.go:171","msg":"trace[1433244061] transaction","detail":"{read_only:false; response_revision:376; number_of_response:1; }","duration":"151.66039ms","start":"2026-05-05T18:12:56.778191Z","end":"2026-05-05T18:12:56.929852Z","steps":["trace[1433244061] 'process raft request' (duration: 151.576177ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:12:59.542064Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14208186093664917388,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-05T18:12:59.782879Z","caller":"traceutil/trace.go:171","msg":"trace[496300449] transaction","detail":"{read_only:false; response_revision:409; number_of_response:1; }","duration":"846.229811ms","start":"2026-05-05T18:12:58.936632Z","end":"2026-05-05T18:12:59.782862Z","steps":["trace[496300449] 'process raft request' (duration: 846.116588ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:12:59.783009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:12:58.936616Z","time spent":"846.323395ms","remote":"127.0.0.1:59714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T18:12:59.795735Z","caller":"traceutil/trace.go:171","msg":"trace[540770000] linearizableReadLoop","detail":"{readStateIndex:424; appliedIndex:422; }","duration":"754.484008ms","start":"2026-05-05T18:12:59.041234Z","end":"2026-05-05T18:12:59.795718Z","steps":["trace[540770000] 'read index received' (duration: 741.504933ms)","trace[540770000] 'applied index is now lower than readState.Index' (duration: 12.978125ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T18:12:59.795839Z","caller":"traceutil/trace.go:171","msg":"trace[393685230] transaction","detail":"{read_only:false; response_revision:410; number_of_response:1; }","duration":"431.771136ms","start":"2026-05-05T18:12:59.364038Z","end":"2026-05-05T18:12:59.795809Z","steps":["trace[393685230] 'process raft request' (duration: 431.586571ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:12:59.795942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"754.695884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-05T18:12:59.795971Z","caller":"traceutil/trace.go:171","msg":"trace[256363014] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:410; }","duration":"754.769777ms","start":"2026-05-05T18:12:59.041192Z","end":"2026-05-05T18:12:59.795962Z","steps":["trace[256363014] 'agreement among raft nodes before linearized reading' (duration: 754.709184ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:12:59.795995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:12:59.041172Z","time spent":"754.817618ms","remote":"127.0.0.1:59714","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-05T18:12:59.795993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:12:59.364009Z","time spent":"431.91059ms","remote":"127.0.0.1:59714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T18:12:59.796026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.189268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T18:12:59.796068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.554165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-05T18:12:59.796093Z","caller":"traceutil/trace.go:171","msg":"trace[1422333673] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:410; }","duration":"425.300951ms","start":"2026-05-05T18:12:59.370775Z","end":"2026-05-05T18:12:59.796076Z","steps":["trace[1422333673] 'agreement among raft nodes before linearized reading' (duration: 425.195618ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T18:12:59.796126Z","caller":"traceutil/trace.go:171","msg":"trace[570985868] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:410; }","duration":"427.611137ms","start":"2026-05-05T18:12:59.368499Z","end":"2026-05-05T18:12:59.796111Z","steps":["trace[570985868] 'agreement among raft nodes before linearized reading' (duration: 427.493924ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:12:59.796117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.087117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-05T18:12:59.796222Z","caller":"traceutil/trace.go:171","msg":"trace[1542513446] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:410; }","duration":"441.319634ms","start":"2026-05-05T18:12:59.354888Z","end":"2026-05-05T18:12:59.796207Z","steps":["trace[1542513446] 'agreement among raft nodes before linearized reading' (duration: 441.059556ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:12:59.796264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:12:59.354861Z","time spent":"441.393986ms","remote":"127.0.0.1:59714","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-05T18:12:59.796129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:12:59.370753Z","time spent":"425.365272ms","remote":"127.0.0.1:59414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-05T18:12:59.796156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:12:59.368445Z","time spent":"427.70501ms","remote":"127.0.0.1:59714","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-05T18:13:06.065521Z","caller":"traceutil/trace.go:171","msg":"trace[1690660991] transaction","detail":"{read_only:false; response_revision:426; number_of_response:1; }","duration":"207.581067ms","start":"2026-05-05T18:13:05.857911Z","end":"2026-05-05T18:13:06.065492Z","steps":["trace[1690660991] 'process raft request' (duration: 207.113923ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T18:13:06.065785Z","caller":"traceutil/trace.go:171","msg":"trace[2137756089] transaction","detail":"{read_only:false; response_revision:427; number_of_response:1; }","duration":"206.976889ms","start":"2026-05-05T18:13:05.858781Z","end":"2026-05-05T18:13:06.065758Z","steps":["trace[2137756089] 'process raft request' (duration: 206.64389ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:13:06.84257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"492.519019ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-05-05T18:13:06.842672Z","caller":"traceutil/trace.go:171","msg":"trace[1602368885] linearizableReadLoop","detail":"{readStateIndex:443; appliedIndex:442; }","duration":"470.798867ms","start":"2026-05-05T18:13:06.371857Z","end":"2026-05-05T18:13:06.842656Z","steps":["trace[1602368885] 'read index received' (duration: 39.862µs)","trace[1602368885] 'applied index is now lower than readState.Index' (duration: 470.757645ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T18:13:06.842751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"470.893069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T18:13:06.842816Z","caller":"traceutil/trace.go:171","msg":"trace[1342857257] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:427; }","duration":"470.93251ms","start":"2026-05-05T18:13:06.371843Z","end":"2026-05-05T18:13:06.842776Z","steps":["trace[1342857257] 'agreement among raft nodes before linearized reading' (duration: 470.857768ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:13:06.842858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:13:06.371797Z","time spent":"471.053404ms","remote":"127.0.0.1:59414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-05T18:13:10.077128Z","caller":"traceutil/trace.go:171","msg":"trace[951486334] linearizableReadLoop","detail":"{readStateIndex:482; appliedIndex:481; }","duration":"183.063158ms","start":"2026-05-05T18:13:09.894041Z","end":"2026-05-05T18:13:10.077104Z","steps":["trace[951486334] 'read index received' (duration: 182.982326ms)","trace[951486334] 'applied index is now lower than readState.Index' (duration: 79.932µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T18:13:10.077232Z","caller":"traceutil/trace.go:171","msg":"trace[248754931] transaction","detail":"{read_only:false; response_revision:466; number_of_response:1; }","duration":"205.880953ms","start":"2026-05-05T18:13:09.87128Z","end":"2026-05-05T18:13:10.077161Z","steps":["trace[248754931] 'process raft request' (duration: 205.647876ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:13:10.07733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.271844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-05T18:13:10.077373Z","caller":"traceutil/trace.go:171","msg":"trace[17925946] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:466; }","duration":"183.352156ms","start":"2026-05-05T18:13:09.894006Z","end":"2026-05-05T18:13:10.077358Z","steps":["trace[17925946] 'agreement among raft nodes before linearized reading' (duration: 183.249893ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T18:13:10.078459Z","caller":"traceutil/trace.go:171","msg":"trace[1716718892] transaction","detail":"{read_only:false; response_revision:467; number_of_response:1; }","duration":"171.765309ms","start":"2026-05-05T18:13:09.906661Z","end":"2026-05-05T18:13:10.078427Z","steps":["trace[1716718892] 'process raft request' (duration: 171.496701ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:13:11.582839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:13:11.054527Z","time spent":"528.29669ms","remote":"127.0.0.1:54120","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-05-05T18:13:11.582968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.769993ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-05T18:13:11.583252Z","caller":"traceutil/trace.go:171","msg":"trace[1840950726] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"600.166226ms","start":"2026-05-05T18:13:10.983025Z","end":"2026-05-05T18:13:11.583191Z","steps":["trace[1840950726] 'process raft request' (duration: 243.091584ms)","trace[1840950726] 'compare' (duration: 356.589208ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T18:13:11.583351Z","caller":"traceutil/trace.go:171","msg":"trace[130656625] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"598.400134ms","start":"2026-05-05T18:13:10.984934Z","end":"2026-05-05T18:13:11.583334Z","steps":["trace[130656625] 'process raft request' (duration: 598.360823ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T18:13:11.58335Z","caller":"traceutil/trace.go:171","msg":"trace[579161680] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"599.26413ms","start":"2026-05-05T18:13:10.984052Z","end":"2026-05-05T18:13:11.583317Z","steps":["trace[579161680] 'process raft request' (duration: 599.156247ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:13:11.583411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:13:10.984902Z","time spent":"598.487278ms","remote":"127.0.0.1:59454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-05T18:13:11.583494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:13:10.984045Z","time spent":"599.372553ms","remote":"127.0.0.1:59454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-05T18:13:11.583494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:13:10.983017Z","time spent":"600.347521ms","remote":"127.0.0.1:59454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-05T18:13:11.583556Z","caller":"traceutil/trace.go:171","msg":"trace[1620306887] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"599.447076ms","start":"2026-05-05T18:13:10.984102Z","end":"2026-05-05T18:13:11.58355Z","steps":["trace[1620306887] 'process raft request' (duration: 599.153767ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:13:11.583621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:13:10.984097Z","time spent":"599.476087ms","remote":"127.0.0.1:59454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-05T18:13:11.591369Z","caller":"traceutil/trace.go:171","msg":"trace[867634321] transaction","detail":"{read_only:false; number_of_response:1; response_revision:485; }","duration":"601.725892ms","start":"2026-05-05T18:13:10.989607Z","end":"2026-05-05T18:13:11.591333Z","steps":["trace[867634321] 'process raft request' (duration: 601.395742ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:13:11.591508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:13:10.989602Z","time spent":"601.821946ms","remote":"127.0.0.1:59454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":101,"response count":0,"response size":10113,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T18:13:11.591558Z","caller":"traceutil/trace.go:171","msg":"trace[1045169934] linearizableReadLoop","detail":"{readStateIndex:510; appliedIndex:502; }","duration":"219.723903ms","start":"2026-05-05T18:13:11.371819Z","end":"2026-05-05T18:13:11.591543Z","steps":["trace[1045169934] 'read index received' (duration: 219.061554ms)","trace[1045169934] 'applied index is now lower than readState.Index' (duration: 661.449µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T18:13:11.591639Z","caller":"traceutil/trace.go:171","msg":"trace[1791412098] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"595.018906ms","start":"2026-05-05T18:13:10.996594Z","end":"2026-05-05T18:13:11.591613Z","steps":["trace[1791412098] 'process raft request' (duration: 594.567203ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:13:11.591669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.843067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T18:13:11.591703Z","caller":"traceutil/trace.go:171","msg":"trace[1332202475] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:489; }","duration":"219.930949ms","start":"2026-05-05T18:13:11.371764Z","end":"2026-05-05T18:13:11.591695Z","steps":["trace[1332202475] 'agreement among raft nodes before linearized reading' (duration: 219.837047ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:13:11.59172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:13:10.996571Z","time spent":"595.113219ms","remote":"127.0.0.1:59454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-05T18:13:11.59204Z","caller":"traceutil/trace.go:171","msg":"trace[1862410322] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"592.672249ms","start":"2026-05-05T18:13:10.999357Z","end":"2026-05-05T18:13:11.592029Z","steps":["trace[1862410322] 'process raft request' (duration: 591.905366ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:13:11.592254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:13:10.999349Z","time spent":"592.72171ms","remote":"127.0.0.1:59454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-05T18:13:11.592304Z","caller":"traceutil/trace.go:171","msg":"trace[592937350] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"413.766477ms","start":"2026-05-05T18:13:11.178511Z","end":"2026-05-05T18:13:11.592277Z","steps":["trace[592937350] 'process raft request' (duration: 412.953143ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T18:13:11.59234Z","caller":"traceutil/trace.go:171","msg":"trace[1224328135] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"568.646127ms","start":"2026-05-05T18:13:11.023675Z","end":"2026-05-05T18:13:11.592322Z","steps":["trace[1224328135] 'process raft request' (duration: 567.70188ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:13:11.59243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:13:11.178475Z","time spent":"413.88367ms","remote":"127.0.0.1:59454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-05T18:13:11.592438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:13:11.023647Z","time spent":"568.76004ms","remote":"127.0.0.1:59454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-05T18:13:11.59247Z","caller":"traceutil/trace.go:171","msg":"trace[428260450] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"602.922667ms","start":"2026-05-05T18:13:10.989531Z","end":"2026-05-05T18:13:11.592453Z","steps":["trace[428260450] 'process raft request' (duration: 601.344732ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T18:13:11.592626Z","caller":"traceutil/trace.go:171","msg":"trace[337740562] transaction","detail":"{read_only:false; number_of_response:0; response_revision:485; }","duration":"599.415306ms","start":"2026-05-05T18:13:10.99319Z","end":"2026-05-05T18:13:11.592605Z","steps":["trace[337740562] 'process raft request' (duration: 597.86417ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:13:11.592585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:13:10.989508Z","time spent":"602.990389ms","remote":"127.0.0.1:59454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7326,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T18:13:11.592721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:13:10.993172Z","time spent":"599.507238ms","remote":"127.0.0.1:59966","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":29,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-05T18:13:11.592808Z","caller":"traceutil/trace.go:171","msg":"trace[1947112277] transaction","detail":"{read_only:false; number_of_response:1; response_revision:485; }","duration":"596.85137ms","start":"2026-05-05T18:13:10.995944Z","end":"2026-05-05T18:13:11.592795Z","steps":["trace[1947112277] 'process raft request' (duration: 595.14968ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:13:11.592881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:13:10.995936Z","time spent":"596.906782ms","remote":"127.0.0.1:59454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":102,"response count":0,"response size":7376,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T18:13:18.00429Z","caller":"traceutil/trace.go:171","msg":"trace[1150134753] linearizableReadLoop","detail":"{readStateIndex:599; appliedIndex:598; }","duration":"293.664153ms","start":"2026-05-05T18:13:17.710585Z","end":"2026-05-05T18:13:18.004249Z","steps":["trace[1150134753] 'read index received' (duration: 287.180992ms)","trace[1150134753] 'applied index is now lower than readState.Index' (duration: 6.482471ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T18:13:18.004332Z","caller":"traceutil/trace.go:171","msg":"trace[1310402548] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"382.38499ms","start":"2026-05-05T18:13:17.62193Z","end":"2026-05-05T18:13:18.004315Z","steps":["trace[1310402548] 'process raft request' (duration: 380.455184ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:13:18.004471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:13:17.621909Z","time spent":"382.511674ms","remote":"127.0.0.1:59516","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036289,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T18:13:18.004523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.117161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T18:13:18.004418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.816087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-b8qgs\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-05-05T18:13:18.004601Z","caller":"traceutil/trace.go:171","msg":"trace[675805838] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:577; }","duration":"230.255295ms","start":"2026-05-05T18:13:17.774328Z","end":"2026-05-05T18:13:18.004583Z","steps":["trace[675805838] 'agreement among raft nodes before linearized reading' (duration: 230.110671ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T18:13:18.004613Z","caller":"traceutil/trace.go:171","msg":"trace[1779036934] range","detail":"{range_begin:/registry/pods/kube-system/cilium-b8qgs; range_end:; response_count:1; response_revision:577; }","duration":"294.047853ms","start":"2026-05-05T18:13:17.710551Z","end":"2026-05-05T18:13:18.004599Z","steps":["trace[1779036934] 'agreement among raft nodes before linearized reading' (duration: 293.811337ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:13:18.004695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.743429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T18:13:18.004853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.247964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T18:13:18.004879Z","caller":"traceutil/trace.go:171","msg":"trace[511864604] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:577; }","duration":"230.314756ms","start":"2026-05-05T18:13:17.774557Z","end":"2026-05-05T18:13:18.004872Z","steps":["trace[511864604] 'agreement among raft nodes before linearized reading' (duration: 230.271295ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:13:18.004986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.45003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T18:13:18.005012Z","caller":"traceutil/trace.go:171","msg":"trace[1666287950] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:577; }","duration":"230.496852ms","start":"2026-05-05T18:13:17.774508Z","end":"2026-05-05T18:13:18.005005Z","steps":["trace[1666287950] 'agreement among raft nodes before linearized reading' (duration: 230.45501ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:13:18.005024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.883199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T18:13:18.00506Z","caller":"traceutil/trace.go:171","msg":"trace[322874654] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:577; }","duration":"180.92272ms","start":"2026-05-05T18:13:17.824127Z","end":"2026-05-05T18:13:18.00505Z","steps":["trace[322874654] 'agreement among raft nodes before linearized reading' (duration: 180.867908ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:13:18.005095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.354442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T18:13:18.005139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.092539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T18:13:18.005177Z","caller":"traceutil/trace.go:171","msg":"trace[944258847] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:577; }","duration":"117.142441ms","start":"2026-05-05T18:13:17.888023Z","end":"2026-05-05T18:13:18.005166Z","steps":["trace[944258847] 'agreement among raft nodes before linearized reading' (duration: 117.080039ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:13:18.005197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.180762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T18:13:18.005227Z","caller":"traceutil/trace.go:171","msg":"trace[976873141] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:577; }","duration":"117.206353ms","start":"2026-05-05T18:13:17.888009Z","end":"2026-05-05T18:13:18.005215Z","steps":["trace[976873141] 'agreement among raft nodes before linearized reading' (duration: 117.167181ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:13:18.005004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.26696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T18:13:18.005189Z","caller":"traceutil/trace.go:171","msg":"trace[450577535] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:577; }","duration":"146.029918ms","start":"2026-05-05T18:13:17.858843Z","end":"2026-05-05T18:13:18.004872Z","steps":["trace[450577535] 'agreement among raft nodes before linearized reading' (duration: 145.641186ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T18:13:18.005289Z","caller":"traceutil/trace.go:171","msg":"trace[1306845593] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:577; }","duration":"181.622649ms","start":"2026-05-05T18:13:17.823654Z","end":"2026-05-05T18:13:18.005277Z","steps":["trace[1306845593] 'agreement among raft nodes before linearized reading' (duration: 180.9678ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:13:18.005292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.377128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T18:13:18.005353Z","caller":"traceutil/trace.go:171","msg":"trace[399630033] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:577; }","duration":"146.4713ms","start":"2026-05-05T18:13:17.858862Z","end":"2026-05-05T18:13:18.005333Z","steps":["trace[399630033] 'agreement among raft nodes before linearized reading' (duration: 146.378407ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:13:18.005537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.706408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T18:13:18.005576Z","caller":"traceutil/trace.go:171","msg":"trace[682999527] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:577; }","duration":"116.76234ms","start":"2026-05-05T18:13:17.888805Z","end":"2026-05-05T18:13:18.005567Z","steps":["trace[682999527] 'agreement among raft nodes before linearized reading' (duration: 116.702669ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T18:13:18.00514Z","caller":"traceutil/trace.go:171","msg":"trace[1965149632] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:577; }","duration":"181.430044ms","start":"2026-05-05T18:13:17.823691Z","end":"2026-05-05T18:13:18.005121Z","steps":["trace[1965149632] 'agreement among raft nodes before linearized reading' (duration: 181.355922ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T18:13:18.354117Z","caller":"traceutil/trace.go:171","msg":"trace[145928588] linearizableReadLoop","detail":"{readStateIndex:600; appliedIndex:599; }","duration":"306.338418ms","start":"2026-05-05T18:13:18.047759Z","end":"2026-05-05T18:13:18.354098Z","steps":["trace[145928588] 'read index received' (duration: 306.165973ms)","trace[145928588] 'applied index is now lower than readState.Index' (duration: 171.364µs)"],"step_count":2} {"level":"warn","ts":"2026-05-05T18:13:18.354269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.488843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T18:13:18.354172Z","caller":"traceutil/trace.go:171","msg":"trace[653400330] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"335.291757ms","start":"2026-05-05T18:13:18.018856Z","end":"2026-05-05T18:13:18.354148Z","steps":["trace[653400330] 'process raft request' (duration: 335.07477ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T18:13:18.35434Z","caller":"traceutil/trace.go:171","msg":"trace[1721768259] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:578; }","duration":"306.535094ms","start":"2026-05-05T18:13:18.047755Z","end":"2026-05-05T18:13:18.35429Z","steps":["trace[1721768259] 'agreement among raft nodes before linearized reading' (duration: 306.455852ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:13:18.354377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:13:18.047698Z","time spent":"306.670478ms","remote":"127.0.0.1:39166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:10000 "} {"level":"warn","ts":"2026-05-05T18:13:18.354491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:13:18.018843Z","time spent":"335.566724ms","remote":"127.0.0.1:59614","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T18:13:18.359178Z","caller":"traceutil/trace.go:171","msg":"trace[277248826] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"298.87524ms","start":"2026-05-05T18:13:18.060282Z","end":"2026-05-05T18:13:18.359157Z","steps":["trace[277248826] 'process raft request' (duration: 298.773227ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:13:18.359225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.400327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T18:13:18.359331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.235794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T18:13:18.359376Z","caller":"traceutil/trace.go:171","msg":"trace[43924526] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:579; }","duration":"178.293665ms","start":"2026-05-05T18:13:18.181072Z","end":"2026-05-05T18:13:18.359366Z","steps":["trace[43924526] 'agreement among raft nodes before linearized reading' (duration: 178.218903ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T18:13:18.359343Z","caller":"traceutil/trace.go:171","msg":"trace[1025500196] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:579; }","duration":"311.53852ms","start":"2026-05-05T18:13:18.047787Z","end":"2026-05-05T18:13:18.359326Z","steps":["trace[1025500196] 'agreement among raft nodes before linearized reading' (duration: 311.353825ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:13:18.359486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:13:18.047783Z","time spent":"311.648754ms","remote":"127.0.0.1:39166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"warn","ts":"2026-05-05T18:13:18.359751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.121747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T18:13:18.359804Z","caller":"traceutil/trace.go:171","msg":"trace[1293515017] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:579; }","duration":"243.198188ms","start":"2026-05-05T18:13:18.116587Z","end":"2026-05-05T18:13:18.359785Z","steps":["trace[1293515017] 'agreement among raft nodes before linearized reading' (duration: 243.125975ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:13:18.359779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.497838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T18:13:18.359913Z","caller":"traceutil/trace.go:171","msg":"trace[1756359064] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:579; }","duration":"212.691434ms","start":"2026-05-05T18:13:18.147207Z","end":"2026-05-05T18:13:18.359898Z","steps":["trace[1756359064] 'agreement among raft nodes before linearized reading' (duration: 212.514788ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:13:18.359995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.920075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T18:13:18.360077Z","caller":"traceutil/trace.go:171","msg":"trace[51467977] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:579; }","duration":"178.983866ms","start":"2026-05-05T18:13:18.181055Z","end":"2026-05-05T18:13:18.360039Z","steps":["trace[51467977] 'agreement among raft nodes before linearized reading' (duration: 178.910664ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:13:18.360099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.004596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T18:13:18.360161Z","caller":"traceutil/trace.go:171","msg":"trace[345175016] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:579; }","duration":"179.13093ms","start":"2026-05-05T18:13:18.181015Z","end":"2026-05-05T18:13:18.360146Z","steps":["trace[345175016] 'agreement among raft nodes before linearized reading' (duration: 178.927005ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:13:18.360238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.007371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T18:13:18.360272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.379704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T18:13:18.360288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.394704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T18:13:18.360261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.874949ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T18:13:18.360299Z","caller":"traceutil/trace.go:171","msg":"trace[1297025113] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:579; }","duration":"157.408874ms","start":"2026-05-05T18:13:18.202882Z","end":"2026-05-05T18:13:18.360291Z","steps":["trace[1297025113] 'agreement among raft nodes before linearized reading' (duration: 157.364843ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T18:13:18.360323Z","caller":"traceutil/trace.go:171","msg":"trace[28088883] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:579; }","duration":"157.458895ms","start":"2026-05-05T18:13:18.202854Z","end":"2026-05-05T18:13:18.360313Z","steps":["trace[28088883] 'agreement among raft nodes before linearized reading' (duration: 157.406783ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T18:13:18.360326Z","caller":"traceutil/trace.go:171","msg":"trace[428496311] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:579; }","duration":"244.054763ms","start":"2026-05-05T18:13:18.116216Z","end":"2026-05-05T18:13:18.360271Z","steps":["trace[428496311] 'agreement among raft nodes before linearized reading' (duration: 243.938769ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T18:13:18.360337Z","caller":"traceutil/trace.go:171","msg":"trace[1210863728] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:579; }","duration":"213.155457ms","start":"2026-05-05T18:13:18.147169Z","end":"2026-05-05T18:13:18.360324Z","steps":["trace[1210863728] 'agreement among raft nodes before linearized reading' (duration: 212.856869ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:13:18.360457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.706714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T18:13:18.360499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.317443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T18:13:18.360546Z","caller":"traceutil/trace.go:171","msg":"trace[2072165066] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:579; }","duration":"271.381054ms","start":"2026-05-05T18:13:18.089152Z","end":"2026-05-05T18:13:18.360533Z","steps":["trace[2072165066] 'agreement among raft nodes before linearized reading' (duration: 271.319552ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T18:13:18.360561Z","caller":"traceutil/trace.go:171","msg":"trace[744341677] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:579; }","duration":"212.866799ms","start":"2026-05-05T18:13:18.147648Z","end":"2026-05-05T18:13:18.360515Z","steps":["trace[744341677] 'agreement among raft nodes before linearized reading' (duration: 212.723184ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:13:18.360715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.527287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T18:13:18.360765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.695822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T18:13:18.36079Z","caller":"traceutil/trace.go:171","msg":"trace[1451917925] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:579; }","duration":"128.768854ms","start":"2026-05-05T18:13:18.232014Z","end":"2026-05-05T18:13:18.360783Z","steps":["trace[1451917925] 'agreement among raft nodes before linearized reading' (duration: 128.694582ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:13:18.360801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.752976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-05-05T18:13:18.360842Z","caller":"traceutil/trace.go:171","msg":"trace[1867332134] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:579; }","duration":"225.85102ms","start":"2026-05-05T18:13:18.134981Z","end":"2026-05-05T18:13:18.360832Z","steps":["trace[1867332134] 'agreement among raft nodes before linearized reading' (duration: 225.700436ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:13:18.360888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.803444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-05T18:13:18.36092Z","caller":"traceutil/trace.go:171","msg":"trace[956809350] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:579; }","duration":"245.861696ms","start":"2026-05-05T18:13:18.11505Z","end":"2026-05-05T18:13:18.360912Z","steps":["trace[956809350] 'agreement among raft nodes before linearized reading' (duration: 245.752833ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:13:18.360926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.495826ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T18:13:18.360987Z","caller":"traceutil/trace.go:171","msg":"trace[1505012083] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:579; }","duration":"129.579248ms","start":"2026-05-05T18:13:18.2314Z","end":"2026-05-05T18:13:18.360979Z","steps":["trace[1505012083] 'agreement among raft nodes before linearized reading' (duration: 129.502586ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:13:18.361081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.564624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-05-05T18:13:18.361119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.957982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-05T18:13:18.361123Z","caller":"traceutil/trace.go:171","msg":"trace[1176962513] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:579; }","duration":"249.624666ms","start":"2026-05-05T18:13:18.111488Z","end":"2026-05-05T18:13:18.361113Z","steps":["trace[1176962513] 'agreement among raft nodes before linearized reading' (duration: 249.488262ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:13:18.361133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.686331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T18:13:18.36115Z","caller":"traceutil/trace.go:171","msg":"trace[1445594879] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:579; }","duration":"226.990314ms","start":"2026-05-05T18:13:18.134151Z","end":"2026-05-05T18:13:18.361141Z","steps":["trace[1445594879] 'agreement among raft nodes before linearized reading' (duration: 226.900621ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T18:13:18.361174Z","caller":"traceutil/trace.go:171","msg":"trace[180790077] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:579; }","duration":"129.751144ms","start":"2026-05-05T18:13:18.231399Z","end":"2026-05-05T18:13:18.36115Z","steps":["trace[180790077] 'agreement among raft nodes before linearized reading' (duration: 129.692452ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:13:18.36129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.455805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T18:13:18.361314Z","caller":"traceutil/trace.go:171","msg":"trace[2139440056] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:579; }","duration":"157.493697ms","start":"2026-05-05T18:13:18.203814Z","end":"2026-05-05T18:13:18.361308Z","steps":["trace[2139440056] 'agreement among raft nodes before linearized reading' (duration: 157.453856ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:13:18.361386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.814157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T18:13:18.361428Z","caller":"traceutil/trace.go:171","msg":"trace[1989345860] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:579; }","duration":"272.883168ms","start":"2026-05-05T18:13:18.088532Z","end":"2026-05-05T18:13:18.361415Z","steps":["trace[1989345860] 'agreement among raft nodes before linearized reading' (duration: 272.824806ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T18:13:18.360784Z","caller":"traceutil/trace.go:171","msg":"trace[1254368618] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:579; }","duration":"244.64554ms","start":"2026-05-05T18:13:18.116128Z","end":"2026-05-05T18:13:18.360774Z","steps":["trace[1254368618] 'agreement among raft nodes before linearized reading' (duration: 244.558527ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:13:18.361577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.96179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T18:13:18.361618Z","caller":"traceutil/trace.go:171","msg":"trace[692588103] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:579; }","duration":"273.037572ms","start":"2026-05-05T18:13:18.088571Z","end":"2026-05-05T18:13:18.361608Z","steps":["trace[692588103] 'agreement among raft nodes before linearized reading' (duration: 272.9805ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T18:13:19.125171Z","caller":"traceutil/trace.go:171","msg":"trace[1129642888] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"758.897965ms","start":"2026-05-05T18:13:18.366248Z","end":"2026-05-05T18:13:19.125146Z","steps":["trace[1129642888] 'process raft request' (duration: 758.442101ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:13:19.125397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:13:18.36624Z","time spent":"759.062569ms","remote":"127.0.0.1:59714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T18:13:19.125185Z","caller":"traceutil/trace.go:171","msg":"trace[1128760894] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"757.557995ms","start":"2026-05-05T18:13:18.367606Z","end":"2026-05-05T18:13:19.125164Z","steps":["trace[1128760894] 'process raft request' (duration: 757.433272ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:13:19.125675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:13:18.367591Z","time spent":"758.052179ms","remote":"127.0.0.1:59714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T18:13:19.125916Z","caller":"traceutil/trace.go:171","msg":"trace[99131238] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"757.426041ms","start":"2026-05-05T18:13:18.368456Z","end":"2026-05-05T18:13:19.125882Z","steps":["trace[99131238] 'process raft request' (duration: 756.670739ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:13:19.126076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:13:18.36845Z","time spent":"757.534905ms","remote":"127.0.0.1:59714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T18:13:19.125881Z","caller":"traceutil/trace.go:171","msg":"trace[1286373203] linearizableReadLoop","detail":"{readStateIndex:605; appliedIndex:601; }","duration":"748.812629ms","start":"2026-05-05T18:13:18.377047Z","end":"2026-05-05T18:13:19.12586Z","steps":["trace[1286373203] 'read index received' (duration: 20.943204ms)","trace[1286373203] 'applied index is now lower than readState.Index' (duration: 727.868255ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T18:13:19.126338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"667.728321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T18:13:19.126402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"685.529885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T18:13:19.126455Z","caller":"traceutil/trace.go:171","msg":"trace[2044871814] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:583; }","duration":"685.589727ms","start":"2026-05-05T18:13:18.440849Z","end":"2026-05-05T18:13:19.126439Z","steps":["trace[2044871814] 'agreement among raft nodes before linearized reading' (duration: 685.531665ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T18:13:19.126423Z","caller":"traceutil/trace.go:171","msg":"trace[1805705191] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:583; }","duration":"667.811515ms","start":"2026-05-05T18:13:18.458596Z","end":"2026-05-05T18:13:19.126407Z","steps":["trace[1805705191] 'agreement among raft nodes before linearized reading' (duration: 667.698151ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:13:19.126554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:13:18.458555Z","time spent":"667.985269ms","remote":"127.0.0.1:39292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:10000 "} {"level":"warn","ts":"2026-05-05T18:13:19.126618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"686.38288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T18:13:19.126653Z","caller":"traceutil/trace.go:171","msg":"trace[1835609781] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:583; }","duration":"686.464462ms","start":"2026-05-05T18:13:18.44018Z","end":"2026-05-05T18:13:19.126645Z","steps":["trace[1835609781] 'agreement among raft nodes before linearized reading' (duration: 686.407521ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:13:19.126683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:13:18.440165Z","time spent":"686.510723ms","remote":"127.0.0.1:39284","response type":"/etcdserverpb.KV/Range","request count":0,"request size":131,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:10000 "} {"level":"warn","ts":"2026-05-05T18:13:19.12666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"644.181132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T18:13:19.126838Z","caller":"traceutil/trace.go:171","msg":"trace[467261396] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:583; }","duration":"644.390408ms","start":"2026-05-05T18:13:18.482429Z","end":"2026-05-05T18:13:19.126819Z","steps":["trace[467261396] 'agreement among raft nodes before linearized reading' (duration: 644.161332ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:13:19.126861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"705.776298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T18:13:19.126887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:13:18.482409Z","time spent":"644.46374ms","remote":"127.0.0.1:39294","response type":"/etcdserverpb.KV/Range","request count":0,"request size":109,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 "} {"level":"info","ts":"2026-05-05T18:13:19.126923Z","caller":"traceutil/trace.go:171","msg":"trace[1108130599] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:583; }","duration":"705.85526ms","start":"2026-05-05T18:13:18.421055Z","end":"2026-05-05T18:13:19.12691Z","steps":["trace[1108130599] 'agreement among raft nodes before linearized reading' (duration: 705.722036ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:13:19.126963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:13:18.421007Z","time spent":"705.946182ms","remote":"127.0.0.1:39276","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" limit:10000 "} {"level":"warn","ts":"2026-05-05T18:13:19.126989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"705.972293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T18:13:19.126986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"644.561443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T18:13:19.127123Z","caller":"traceutil/trace.go:171","msg":"trace[841277527] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:583; }","duration":"644.711498ms","start":"2026-05-05T18:13:18.482392Z","end":"2026-05-05T18:13:19.127103Z","steps":["trace[841277527] 'agreement among raft nodes before linearized reading' (duration: 644.46957ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:13:19.127164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"625.508126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T18:13:19.127233Z","caller":"traceutil/trace.go:171","msg":"trace[938945848] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:583; }","duration":"625.600248ms","start":"2026-05-05T18:13:18.501622Z","end":"2026-05-05T18:13:19.127222Z","steps":["trace[938945848] 'agreement among raft nodes before linearized reading' (duration: 625.447693ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:13:19.127289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:13:18.501601Z","time spent":"625.67236ms","remote":"127.0.0.1:39294","response type":"/etcdserverpb.KV/Range","request count":0,"request size":109,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:500 "} {"level":"warn","ts":"2026-05-05T18:13:19.127275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"626.406761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T18:13:19.126495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:13:18.440838Z","time spent":"685.647858ms","remote":"127.0.0.1:39284","response type":"/etcdserverpb.KV/Range","request count":0,"request size":131,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:500 "} {"level":"info","ts":"2026-05-05T18:13:19.127362Z","caller":"traceutil/trace.go:171","msg":"trace[778128382] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:583; }","duration":"626.502604ms","start":"2026-05-05T18:13:18.50083Z","end":"2026-05-05T18:13:19.127333Z","steps":["trace[778128382] 'agreement among raft nodes before linearized reading' (duration: 626.37913ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:13:19.127352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"705.838529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T18:13:19.127371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"668.111113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T18:13:19.127409Z","caller":"traceutil/trace.go:171","msg":"trace[105977371] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:583; }","duration":"705.916971ms","start":"2026-05-05T18:13:18.421479Z","end":"2026-05-05T18:13:19.127396Z","steps":["trace[105977371] 'agreement among raft nodes before linearized reading' (duration: 705.847209ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:13:19.127189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"736.457356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T18:13:19.127492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:13:18.421466Z","time spent":"706.009074ms","remote":"127.0.0.1:39276","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" limit:500 "} {"level":"warn","ts":"2026-05-05T18:13:19.127487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"626.512465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T18:13:19.127404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:13:18.500817Z","time spent":"626.574726ms","remote":"127.0.0.1:39302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":109,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 "} {"level":"info","ts":"2026-05-05T18:13:19.127555Z","caller":"traceutil/trace.go:171","msg":"trace[186381382] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:583; }","duration":"626.613997ms","start":"2026-05-05T18:13:18.500928Z","end":"2026-05-05T18:13:19.127542Z","steps":["trace[186381382] 'agreement among raft nodes before linearized reading' (duration: 626.531365ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:13:19.127559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"668.65573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T18:13:19.1276Z","caller":"traceutil/trace.go:171","msg":"trace[568021843] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:583; }","duration":"668.724351ms","start":"2026-05-05T18:13:18.458866Z","end":"2026-05-05T18:13:19.127591Z","steps":["trace[568021843] 'agreement among raft nodes before linearized reading' (duration: 668.662649ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:13:19.127628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:13:18.458848Z","time spent":"668.773853ms","remote":"127.0.0.1:39292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true "} {"level":"info","ts":"2026-05-05T18:13:19.127579Z","caller":"traceutil/trace.go:171","msg":"trace[1357098935] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:583; }","duration":"736.863338ms","start":"2026-05-05T18:13:18.390678Z","end":"2026-05-05T18:13:19.127541Z","steps":["trace[1357098935] 'agreement among raft nodes before linearized reading' (duration: 736.484698ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:13:19.12762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:13:18.500901Z","time spent":"626.684579ms","remote":"127.0.0.1:39302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"warn","ts":"2026-05-05T18:13:19.126352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"735.702555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T18:13:19.127695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:13:18.390653Z","time spent":"736.998541ms","remote":"127.0.0.1:39270","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"info","ts":"2026-05-05T18:13:19.127138Z","caller":"traceutil/trace.go:171","msg":"trace[1574595325] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:583; }","duration":"706.083496ms","start":"2026-05-05T18:13:18.420941Z","end":"2026-05-05T18:13:19.127025Z","steps":["trace[1574595325] 'agreement among raft nodes before linearized reading' (duration: 705.984163ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:13:19.128092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:13:18.420927Z","time spent":"707.149897ms","remote":"127.0.0.1:39276","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"warn","ts":"2026-05-05T18:13:19.126532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"686.151172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T18:13:19.128239Z","caller":"traceutil/trace.go:171","msg":"trace[727761990] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:583; }","duration":"687.903713ms","start":"2026-05-05T18:13:18.440316Z","end":"2026-05-05T18:13:19.12822Z","steps":["trace[727761990] 'agreement among raft nodes before linearized reading' (duration: 686.197244ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:13:19.128354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:13:18.440291Z","time spent":"688.044808ms","remote":"127.0.0.1:39284","response type":"/etcdserverpb.KV/Range","request count":0,"request size":130,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-05T18:13:19.127192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:13:18.48238Z","time spent":"644.79828ms","remote":"127.0.0.1:39294","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"warn","ts":"2026-05-05T18:13:19.129107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"748.999455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T18:13:19.129166Z","caller":"traceutil/trace.go:171","msg":"trace[1486116990] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:583; }","duration":"752.131876ms","start":"2026-05-05T18:13:18.377023Z","end":"2026-05-05T18:13:19.129155Z","steps":["trace[1486116990] 'agreement among raft nodes before linearized reading' (duration: 748.941892ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:13:19.12925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:13:18.376995Z","time spent":"752.24445ms","remote":"127.0.0.1:59684","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"info","ts":"2026-05-05T18:13:19.127718Z","caller":"traceutil/trace.go:171","msg":"trace[1141571608] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:583; }","duration":"737.081914ms","start":"2026-05-05T18:13:18.390605Z","end":"2026-05-05T18:13:19.127687Z","steps":["trace[1141571608] 'agreement among raft nodes before linearized reading' (duration: 735.690554ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:13:19.129918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:13:18.390584Z","time spent":"739.30569ms","remote":"127.0.0.1:39270","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" limit:10000 "} {"level":"info","ts":"2026-05-05T18:13:19.127495Z","caller":"traceutil/trace.go:171","msg":"trace[1575421187] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:583; }","duration":"668.217666ms","start":"2026-05-05T18:13:18.459235Z","end":"2026-05-05T18:13:19.127452Z","steps":["trace[1575421187] 'agreement among raft nodes before linearized reading' (duration: 668.025421ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:13:19.130113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:13:18.459227Z","time spent":"670.873614ms","remote":"127.0.0.1:39292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:500 "} {"level":"warn","ts":"2026-05-05T18:13:20.946238Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14208186093664917722,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-05T18:13:21.375505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.002574293s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T18:13:21.375595Z","caller":"traceutil/trace.go:171","msg":"trace[694709543] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:583; }","duration":"2.002714397s","start":"2026-05-05T18:13:19.37286Z","end":"2026-05-05T18:13:21.375575Z","steps":["trace[694709543] 'range keys from in-memory index tree' (duration: 2.002503831s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:13:21.375646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:13:19.372841Z","time spent":"2.002791759s","remote":"127.0.0.1:59414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-05T18:13:21.446489Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14208186093664917722,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-05T18:13:21.513198Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.957758905s","expected-duration":"1s"} {"level":"info","ts":"2026-05-05T18:13:21.514442Z","caller":"traceutil/trace.go:171","msg":"trace[1829952672] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"1.960387532s","start":"2026-05-05T18:13:19.554029Z","end":"2026-05-05T18:13:21.514416Z","steps":["trace[1829952672] 'process raft request' (duration: 1.959684281s)"],"step_count":1} {"level":"info","ts":"2026-05-05T18:13:21.51457Z","caller":"traceutil/trace.go:171","msg":"trace[767434769] linearizableReadLoop","detail":"{readStateIndex:607; appliedIndex:605; }","duration":"1.068366295s","start":"2026-05-05T18:13:20.446185Z","end":"2026-05-05T18:13:21.514551Z","steps":["trace[767434769] 'read index received' (duration: 1.067284192s)","trace[767434769] 'applied index is now lower than readState.Index' (duration: 1.080723ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T18:13:21.514595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:13:19.554002Z","time spent":"1.960517165s","remote":"127.0.0.1:59516","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036309,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T18:13:21.514824Z","caller":"traceutil/trace.go:171","msg":"trace[2043489051] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"1.232174721s","start":"2026-05-05T18:13:20.282641Z","end":"2026-05-05T18:13:21.514815Z","steps":["trace[2043489051] 'process raft request' (duration: 1.231710607s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:13:21.514926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:13:20.2826Z","time spent":"1.232245733s","remote":"127.0.0.1:59714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T18:13:21.515003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.421498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-05T18:13:21.515056Z","caller":"traceutil/trace.go:171","msg":"trace[325394477] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:585; }","duration":"380.492459ms","start":"2026-05-05T18:13:21.134551Z","end":"2026-05-05T18:13:21.515043Z","steps":["trace[325394477] 'agreement among raft nodes before linearized reading' (duration: 380.330115ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:13:21.515081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.155208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-05T18:13:21.515159Z","caller":"traceutil/trace.go:171","msg":"trace[1917639723] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:585; }","duration":"383.290973ms","start":"2026-05-05T18:13:21.13185Z","end":"2026-05-05T18:13:21.515141Z","steps":["trace[1917639723] 'agreement among raft nodes before linearized reading' (duration: 383.036775ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:13:21.515206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:13:21.131835Z","time spent":"383.361535ms","remote":"127.0.0.1:59714","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-05T18:13:21.515209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.069007644s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-05-05T18:13:21.515246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.620373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-05-05T18:13:21.515272Z","caller":"traceutil/trace.go:171","msg":"trace[1562661529] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:585; }","duration":"381.675035ms","start":"2026-05-05T18:13:21.13359Z","end":"2026-05-05T18:13:21.515265Z","steps":["trace[1562661529] 'agreement among raft nodes before linearized reading' (duration: 381.568592ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T18:13:21.515274Z","caller":"traceutil/trace.go:171","msg":"trace[808940954] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:585; }","duration":"1.069085016s","start":"2026-05-05T18:13:20.446172Z","end":"2026-05-05T18:13:21.515257Z","steps":["trace[808940954] 'agreement among raft nodes before linearized reading' (duration: 1.068872219s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:13:21.515298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:13:21.133551Z","time spent":"381.742956ms","remote":"127.0.0.1:59714","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-05T18:13:21.515317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:13:20.446118Z","time spent":"1.069191329s","remote":"127.0.0.1:59714","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-05T18:13:21.515093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:13:21.134542Z","time spent":"380.540031ms","remote":"127.0.0.1:59714","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-05T18:13:21.515399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"809.624803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.250\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-05T18:13:21.515419Z","caller":"traceutil/trace.go:171","msg":"trace[2133969027] range","detail":"{range_begin:/registry/masterleases/199.204.45.250; range_end:; response_count:1; response_revision:585; }","duration":"809.674976ms","start":"2026-05-05T18:13:20.705739Z","end":"2026-05-05T18:13:21.515414Z","steps":["trace[2133969027] 'agreement among raft nodes before linearized reading' (duration: 809.639185ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:13:21.515436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:13:20.705715Z","time spent":"809.717036ms","remote":"127.0.0.1:59432","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/199.204.45.250\" "} {"level":"warn","ts":"2026-05-05T18:13:21.515774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"928.346677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T18:13:21.515823Z","caller":"traceutil/trace.go:171","msg":"trace[1341907615] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:585; }","duration":"928.42415ms","start":"2026-05-05T18:13:20.587387Z","end":"2026-05-05T18:13:21.515811Z","steps":["trace[1341907615] 'agreement among raft nodes before linearized reading' (duration: 927.462211ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:13:21.515852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:13:20.587338Z","time spent":"928.507762ms","remote":"127.0.0.1:59414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-05T18:13:21.515168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.99932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T18:13:21.517864Z","caller":"traceutil/trace.go:171","msg":"trace[1723019116] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:585; }","duration":"143.711388ms","start":"2026-05-05T18:13:21.374132Z","end":"2026-05-05T18:13:21.517843Z","steps":["trace[1723019116] 'agreement among raft nodes before linearized reading' (duration: 140.99582ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:13:23.246797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.676639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T18:13:23.246861Z","caller":"traceutil/trace.go:171","msg":"trace[1231222228] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:0; response_revision:628; }","duration":"375.787922ms","start":"2026-05-05T18:13:22.871059Z","end":"2026-05-05T18:13:23.246847Z","steps":["trace[1231222228] 'range keys from in-memory index tree' (duration: 375.572475ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:13:23.246898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:13:22.871038Z","time spent":"375.853224ms","remote":"127.0.0.1:59454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" "} {"level":"warn","ts":"2026-05-05T18:13:23.246987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.192019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T18:13:23.247058Z","caller":"traceutil/trace.go:171","msg":"trace[226302181] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:0; response_revision:628; }","duration":"175.300721ms","start":"2026-05-05T18:13:23.071739Z","end":"2026-05-05T18:13:23.24704Z","steps":["trace[226302181] 'range keys from in-memory index tree' (duration: 175.082665ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T18:13:26.001528Z","caller":"traceutil/trace.go:171","msg":"trace[2448464] linearizableReadLoop","detail":"{readStateIndex:692; appliedIndex:691; }","duration":"130.956282ms","start":"2026-05-05T18:13:25.87055Z","end":"2026-05-05T18:13:26.001506Z","steps":["trace[2448464] 'read index received' (duration: 130.397076ms)","trace[2448464] 'applied index is now lower than readState.Index' (duration: 558.256µs)"],"step_count":2} {"level":"warn","ts":"2026-05-05T18:13:26.001741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.173409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:1 size:5586"} {"level":"info","ts":"2026-05-05T18:13:26.001809Z","caller":"traceutil/trace.go:171","msg":"trace[968728612] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:668; }","duration":"131.280902ms","start":"2026-05-05T18:13:25.870504Z","end":"2026-05-05T18:13:26.001785Z","steps":["trace[968728612] 'agreement among raft nodes before linearized reading' (duration: 131.095027ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T18:13:26.001765Z","caller":"traceutil/trace.go:171","msg":"trace[672769251] transaction","detail":"{read_only:false; response_revision:668; number_of_response:1; }","duration":"136.97948ms","start":"2026-05-05T18:13:25.864756Z","end":"2026-05-05T18:13:26.001736Z","steps":["trace[672769251] 'process raft request' (duration: 136.400333ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T18:13:27.892298Z","caller":"traceutil/trace.go:171","msg":"trace[631352596] linearizableReadLoop","detail":"{readStateIndex:706; appliedIndex:704; }","duration":"223.11614ms","start":"2026-05-05T18:13:27.669162Z","end":"2026-05-05T18:13:27.892278Z","steps":["trace[631352596] 'read index received' (duration: 65.932451ms)","trace[631352596] 'applied index is now lower than readState.Index' (duration: 157.180859ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T18:13:27.892415Z","caller":"traceutil/trace.go:171","msg":"trace[1795735590] transaction","detail":"{read_only:false; response_revision:682; number_of_response:1; }","duration":"232.060714ms","start":"2026-05-05T18:13:27.660311Z","end":"2026-05-05T18:13:27.892372Z","steps":["trace[1795735590] 'process raft request' (duration: 203.174592ms)","trace[1795735590] 'compare' (duration: 28.619304ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T18:13:27.892478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.281215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T18:13:27.892561Z","caller":"traceutil/trace.go:171","msg":"trace[1855172663] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:682; }","duration":"223.413799ms","start":"2026-05-05T18:13:27.669128Z","end":"2026-05-05T18:13:27.892542Z","steps":["trace[1855172663] 'agreement among raft nodes before linearized reading' (duration: 223.242444ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:13:27.906435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.364492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T18:13:27.906519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.289008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T18:13:27.906561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.663155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T18:13:27.906586Z","caller":"traceutil/trace.go:171","msg":"trace[45126001] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:682; }","duration":"237.35811ms","start":"2026-05-05T18:13:27.669215Z","end":"2026-05-05T18:13:27.906573Z","steps":["trace[45126001] 'agreement among raft nodes before linearized reading' (duration: 237.286968ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T18:13:27.906531Z","caller":"traceutil/trace.go:171","msg":"trace[1836579019] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:682; }","duration":"125.481265ms","start":"2026-05-05T18:13:27.781028Z","end":"2026-05-05T18:13:27.90651Z","steps":["trace[1836579019] 'agreement among raft nodes before linearized reading' (duration: 125.332961ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:13:27.906515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.463664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T18:13:27.906613Z","caller":"traceutil/trace.go:171","msg":"trace[1799169146] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:682; }","duration":"153.746988ms","start":"2026-05-05T18:13:27.752852Z","end":"2026-05-05T18:13:27.906599Z","steps":["trace[1799169146] 'agreement among raft nodes before linearized reading' (duration: 153.621734ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T18:13:27.90667Z","caller":"traceutil/trace.go:171","msg":"trace[1429289805] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:682; }","duration":"125.63971ms","start":"2026-05-05T18:13:27.781005Z","end":"2026-05-05T18:13:27.906645Z","steps":["trace[1429289805] 'agreement among raft nodes before linearized reading' (duration: 125.445004ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:13:27.906732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.217258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" limit:10 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T18:13:27.906868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.654018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T18:13:27.90646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.125152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-b8qgs\" ","response":"range_response_count:1 size:18516"} {"level":"info","ts":"2026-05-05T18:13:27.906925Z","caller":"traceutil/trace.go:171","msg":"trace[546470372] range","detail":"{range_begin:/registry/pods/kube-system/cilium-b8qgs; range_end:; response_count:1; response_revision:682; }","duration":"130.621497ms","start":"2026-05-05T18:13:27.776298Z","end":"2026-05-05T18:13:27.906919Z","steps":["trace[546470372] 'agreement among raft nodes before linearized reading' (duration: 130.049271ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:13:27.906814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.17269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" limit:10 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T18:13:27.907025Z","caller":"traceutil/trace.go:171","msg":"trace[2119963404] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:682; }","duration":"153.406378ms","start":"2026-05-05T18:13:27.753606Z","end":"2026-05-05T18:13:27.907012Z","steps":["trace[2119963404] 'agreement among raft nodes before linearized reading' (duration: 153.185281ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T18:13:27.90687Z","caller":"traceutil/trace.go:171","msg":"trace[884464400] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:682; }","duration":"125.386132ms","start":"2026-05-05T18:13:27.781455Z","end":"2026-05-05T18:13:27.906841Z","steps":["trace[884464400] 'agreement among raft nodes before linearized reading' (duration: 125.031482ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T18:13:27.907274Z","caller":"traceutil/trace.go:171","msg":"trace[205730625] transaction","detail":"{read_only:false; number_of_response:1; response_revision:682; }","duration":"170.356517ms","start":"2026-05-05T18:13:27.735976Z","end":"2026-05-05T18:13:27.906332Z","steps":["trace[205730625] 'process raft request' (duration: 170.237393ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T18:13:27.906895Z","caller":"traceutil/trace.go:171","msg":"trace[324288658] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:682; }","duration":"237.686179ms","start":"2026-05-05T18:13:27.669199Z","end":"2026-05-05T18:13:27.906885Z","steps":["trace[324288658] 'agreement among raft nodes before linearized reading' (duration: 237.122393ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:13:27.906461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.547933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T18:13:27.907521Z","caller":"traceutil/trace.go:171","msg":"trace[1304917148] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:682; }","duration":"154.638434ms","start":"2026-05-05T18:13:27.752867Z","end":"2026-05-05T18:13:27.907505Z","steps":["trace[1304917148] 'agreement among raft nodes before linearized reading' (duration: 153.526522ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T18:13:28.064192Z","caller":"traceutil/trace.go:171","msg":"trace[320031278] transaction","detail":"{read_only:false; response_revision:683; number_of_response:1; }","duration":"160.703092ms","start":"2026-05-05T18:13:27.90346Z","end":"2026-05-05T18:13:28.064164Z","steps":["trace[320031278] 'process raft request' (duration: 96.972835ms)","trace[320031278] 'compare' (duration: 63.605533ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T18:13:28.069566Z","caller":"traceutil/trace.go:171","msg":"trace[247748805] linearizableReadLoop","detail":"{readStateIndex:710; appliedIndex:707; }","duration":"119.618353ms","start":"2026-05-05T18:13:27.949933Z","end":"2026-05-05T18:13:28.069552Z","steps":["trace[247748805] 'read index received' (duration: 50.510107ms)","trace[247748805] 'applied index is now lower than readState.Index' (duration: 69.107336ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T18:13:28.069643Z","caller":"traceutil/trace.go:171","msg":"trace[445704578] transaction","detail":"{read_only:false; response_revision:684; number_of_response:1; }","duration":"150.576734ms","start":"2026-05-05T18:13:27.919057Z","end":"2026-05-05T18:13:28.069634Z","steps":["trace[445704578] 'process raft request' (duration: 150.42906ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:13:28.069832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.878411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T18:13:28.069871Z","caller":"traceutil/trace.go:171","msg":"trace[1361483821] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:684; }","duration":"119.958863ms","start":"2026-05-05T18:13:27.949902Z","end":"2026-05-05T18:13:28.069861Z","steps":["trace[1361483821] 'agreement among raft nodes before linearized reading' (duration: 119.886811ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T18:13:28.069849Z","caller":"traceutil/trace.go:171","msg":"trace[2053800961] transaction","detail":"{read_only:false; number_of_response:1; response_revision:683; }","duration":"158.149358ms","start":"2026-05-05T18:13:27.91166Z","end":"2026-05-05T18:13:28.069809Z","steps":["trace[2053800961] 'process raft request' (duration: 157.767137ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:13:28.078214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.227867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T18:13:28.078251Z","caller":"traceutil/trace.go:171","msg":"trace[1220763348] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:685; }","duration":"128.307209ms","start":"2026-05-05T18:13:27.949936Z","end":"2026-05-05T18:13:28.078244Z","steps":["trace[1220763348] 'agreement among raft nodes before linearized reading' (duration: 128.236247ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:13:28.078339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.722331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T18:13:28.078422Z","caller":"traceutil/trace.go:171","msg":"trace[901277541] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:685; }","duration":"127.838555ms","start":"2026-05-05T18:13:27.950564Z","end":"2026-05-05T18:13:28.078403Z","steps":["trace[901277541] 'agreement among raft nodes before linearized reading' (duration: 127.671661ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T18:14:16.027881Z","caller":"traceutil/trace.go:171","msg":"trace[348996569] linearizableReadLoop","detail":"{readStateIndex:1059; appliedIndex:1058; }","duration":"120.329991ms","start":"2026-05-05T18:14:15.907527Z","end":"2026-05-05T18:14:16.027857Z","steps":["trace[348996569] 'read index received' (duration: 77.847909ms)","trace[348996569] 'applied index is now lower than readState.Index' (duration: 42.481052ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T18:14:16.028092Z","caller":"traceutil/trace.go:171","msg":"trace[1438137333] transaction","detail":"{read_only:false; response_revision:1020; number_of_response:1; }","duration":"121.948389ms","start":"2026-05-05T18:14:15.906125Z","end":"2026-05-05T18:14:16.028073Z","steps":["trace[1438137333] 'process raft request' (duration: 79.227451ms)","trace[1438137333] 'compare' (duration: 42.356178ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T18:14:16.03908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.535187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-05T18:14:16.0392Z","caller":"traceutil/trace.go:171","msg":"trace[1633446404] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1020; }","duration":"130.699431ms","start":"2026-05-05T18:14:15.908475Z","end":"2026-05-05T18:14:16.039175Z","steps":["trace[1633446404] 'agreement among raft nodes before linearized reading' (duration: 130.394922ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:14:16.039077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.509037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-05T18:14:16.039303Z","caller":"traceutil/trace.go:171","msg":"trace[2003933883] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1020; }","duration":"131.803735ms","start":"2026-05-05T18:14:15.907468Z","end":"2026-05-05T18:14:16.039272Z","steps":["trace[2003933883] 'agreement among raft nodes before linearized reading' (duration: 120.785316ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T18:14:34.264094Z","caller":"traceutil/trace.go:171","msg":"trace[1448081056] linearizableReadLoop","detail":"{readStateIndex:1122; appliedIndex:1121; }","duration":"141.8337ms","start":"2026-05-05T18:14:34.122236Z","end":"2026-05-05T18:14:34.264069Z","steps":["trace[1448081056] 'read index received' (duration: 141.547852ms)","trace[1448081056] 'applied index is now lower than readState.Index' (duration: 284.618µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T18:14:34.264158Z","caller":"traceutil/trace.go:171","msg":"trace[467681491] transaction","detail":"{read_only:false; response_revision:1079; number_of_response:1; }","duration":"154.206712ms","start":"2026-05-05T18:14:34.109922Z","end":"2026-05-05T18:14:34.264129Z","steps":["trace[467681491] 'process raft request' (duration: 153.942134ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:14:34.264459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.634276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-05-05T18:14:34.264504Z","caller":"traceutil/trace.go:171","msg":"trace[1895821483] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1079; }","duration":"134.725527ms","start":"2026-05-05T18:14:34.129768Z","end":"2026-05-05T18:14:34.264494Z","steps":["trace[1895821483] 'agreement among raft nodes before linearized reading' (duration: 134.431838ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:14:34.264787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.533961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-05T18:14:34.264903Z","caller":"traceutil/trace.go:171","msg":"trace[1591039622] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1079; }","duration":"142.684286ms","start":"2026-05-05T18:14:34.122202Z","end":"2026-05-05T18:14:34.264886Z","steps":["trace[1591039622] 'agreement among raft nodes before linearized reading' (duration: 142.049397ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T18:14:58.650156Z","caller":"traceutil/trace.go:171","msg":"trace[386267068] transaction","detail":"{read_only:false; response_revision:1160; number_of_response:1; }","duration":"113.681197ms","start":"2026-05-05T18:14:58.536437Z","end":"2026-05-05T18:14:58.650118Z","steps":["trace[386267068] 'process raft request' (duration: 113.536582ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T18:15:23.083563Z","caller":"traceutil/trace.go:171","msg":"trace[1995586105] transaction","detail":"{read_only:false; response_revision:1271; number_of_response:1; }","duration":"318.793141ms","start":"2026-05-05T18:15:22.764746Z","end":"2026-05-05T18:15:23.083539Z","steps":["trace[1995586105] 'process raft request' (duration: 318.656966ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:15:23.083735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T18:15:22.764722Z","time spent":"318.921235ms","remote":"127.0.0.1:59714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T18:15:31.485019Z","caller":"traceutil/trace.go:171","msg":"trace[974767129] linearizableReadLoop","detail":"{readStateIndex:1362; appliedIndex:1361; }","duration":"112.783359ms","start":"2026-05-05T18:15:31.372218Z","end":"2026-05-05T18:15:31.485001Z","steps":["trace[974767129] 'read index received' (duration: 112.661156ms)","trace[974767129] 'applied index is now lower than readState.Index' (duration: 121.483µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T18:15:31.485091Z","caller":"traceutil/trace.go:171","msg":"trace[162869395] transaction","detail":"{read_only:false; response_revision:1305; number_of_response:1; }","duration":"117.848072ms","start":"2026-05-05T18:15:31.367211Z","end":"2026-05-05T18:15:31.485059Z","steps":["trace[162869395] 'process raft request' (duration: 117.689567ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:15:31.485171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.927343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T18:15:31.485265Z","caller":"traceutil/trace.go:171","msg":"trace[1519376398] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1305; }","duration":"113.072517ms","start":"2026-05-05T18:15:31.37217Z","end":"2026-05-05T18:15:31.485243Z","steps":["trace[1519376398] 'agreement among raft nodes before linearized reading' (duration: 112.912593ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T18:15:55.842415Z","caller":"traceutil/trace.go:171","msg":"trace[1270310214] linearizableReadLoop","detail":"{readStateIndex:1451; appliedIndex:1450; }","duration":"190.875528ms","start":"2026-05-05T18:15:55.651516Z","end":"2026-05-05T18:15:55.842391Z","steps":["trace[1270310214] 'read index received' (duration: 190.665321ms)","trace[1270310214] 'applied index is now lower than readState.Index' (duration: 209.277µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T18:15:55.842458Z","caller":"traceutil/trace.go:171","msg":"trace[315088236] transaction","detail":"{read_only:false; response_revision:1390; number_of_response:1; }","duration":"191.536788ms","start":"2026-05-05T18:15:55.650893Z","end":"2026-05-05T18:15:55.842429Z","steps":["trace[315088236] 'process raft request' (duration: 191.368782ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:15:55.842587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.060383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6755"} {"level":"warn","ts":"2026-05-05T18:15:55.842603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.596265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-proxy-764d5\" ","response":"range_response_count:1 size:4482"} {"level":"info","ts":"2026-05-05T18:15:55.842632Z","caller":"traceutil/trace.go:171","msg":"trace[1351119759] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:1390; }","duration":"191.150905ms","start":"2026-05-05T18:15:55.651469Z","end":"2026-05-05T18:15:55.84262Z","steps":["trace[1351119759] 'agreement among raft nodes before linearized reading' (duration: 191.053843ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T18:15:55.842641Z","caller":"traceutil/trace.go:171","msg":"trace[176542146] range","detail":"{range_begin:/registry/pods/kube-system/kube-proxy-764d5; range_end:; response_count:1; response_revision:1390; }","duration":"135.664196ms","start":"2026-05-05T18:15:55.706965Z","end":"2026-05-05T18:15:55.842629Z","steps":["trace[176542146] 'agreement among raft nodes before linearized reading' (duration: 135.572833ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T18:15:55.842655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.563484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-05T18:15:55.842739Z","caller":"traceutil/trace.go:171","msg":"trace[1668364910] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:1390; }","duration":"101.695127ms","start":"2026-05-05T18:15:55.741028Z","end":"2026-05-05T18:15:55.842724Z","steps":["trace[1668364910] 'agreement among raft nodes before linearized reading' (duration: 101.557673ms)"],"step_count":1}