{"level":"warn","ts":"2026-05-01T22:25:23.917447Z","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-01T22:25:23.91753Z","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-01T22:25:23.917599Z","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-01T22:25:23.917609Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.250:2380"]} {"level":"info","ts":"2026-05-01T22:25:23.917629Z","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-01T22:25:23.918262Z","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-01T22:25:23.918336Z","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-01T22:25:23.922284Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.760689ms"} {"level":"info","ts":"2026-05-01T22:25:23.928834Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d98d5f2c752cc52d","cluster-id":"c785b4aaf4bd3b11"} {"level":"info","ts":"2026-05-01T22:25:23.928919Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d switched to configuration voters=()"} {"level":"info","ts":"2026-05-01T22:25:23.928947Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d became follower at term 0"} {"level":"info","ts":"2026-05-01T22:25:23.928959Z","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-01T22:25:23.928974Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d became follower at term 1"} {"level":"info","ts":"2026-05-01T22:25:23.929016Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d switched to configuration voters=(15676290522495632685)"} {"level":"warn","ts":"2026-05-01T22:25:23.935849Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-01T22:25:23.937501Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-01T22:25:23.940516Z","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-01T22:25:23.944052Z","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-01T22:25:23.945359Z","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-01T22:25:23.946399Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d switched to configuration voters=(15676290522495632685)"} {"level":"info","ts":"2026-05-01T22:25:23.94531Z","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-01T22:25:23.946974Z","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-01T22:25:23.94753Z","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-01T22:25:23.947909Z","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-01T22:25:23.949743Z","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-01T22:25:23.949882Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.250:2380"} {"level":"info","ts":"2026-05-01T22:25:23.949914Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.250:2380"} {"level":"info","ts":"2026-05-01T22:25:23.95007Z","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-01T22:25:23.950117Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-01T22:25:24.329877Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d is starting a new election at term 1"} {"level":"info","ts":"2026-05-01T22:25:24.330081Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d became pre-candidate at term 1"} {"level":"info","ts":"2026-05-01T22:25:24.330165Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d received MsgPreVoteResp from d98d5f2c752cc52d at term 1"} {"level":"info","ts":"2026-05-01T22:25:24.330222Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d became candidate at term 2"} {"level":"info","ts":"2026-05-01T22:25:24.330248Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d received MsgVoteResp from d98d5f2c752cc52d at term 2"} {"level":"info","ts":"2026-05-01T22:25:24.330306Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d became leader at term 2"} {"level":"info","ts":"2026-05-01T22:25:24.330329Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d98d5f2c752cc52d elected leader d98d5f2c752cc52d at term 2"} {"level":"info","ts":"2026-05-01T22:25:24.355754Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-01T22:25:24.366478Z","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-01T22:25:24.366537Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-01T22:25:24.36656Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-01T22:25:24.36692Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-01T22:25:24.367028Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-01T22:25:24.370677Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-01T22:25:24.372358Z","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-01T22:25:24.372511Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-01T22:25:24.372555Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-01T22:25:24.375944Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.250:2379"} {"level":"info","ts":"2026-05-01T22:25:26.924719Z","caller":"traceutil/trace.go:171","msg":"trace[1143918719] linearizableReadLoop","detail":"{readStateIndex:75; appliedIndex:74; }","duration":"229.89713ms","start":"2026-05-01T22:25:26.694795Z","end":"2026-05-01T22:25:26.924692Z","steps":["trace[1143918719] 'read index received' (duration: 229.723486ms)","trace[1143918719] 'applied index is now lower than readState.Index' (duration: 172.264µs)"],"step_count":2} {"level":"info","ts":"2026-05-01T22:25:26.924794Z","caller":"traceutil/trace.go:171","msg":"trace[1651870211] transaction","detail":"{read_only:false; response_revision:71; number_of_response:1; }","duration":"230.508354ms","start":"2026-05-01T22:25:26.694238Z","end":"2026-05-01T22:25:26.924746Z","steps":["trace[1651870211] 'process raft request' (duration: 230.299009ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T22:25:26.925038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.197397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-admin\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-01T22:25:26.925153Z","caller":"traceutil/trace.go:171","msg":"trace[1523943692] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-admin; range_end:; response_count:0; response_revision:71; }","duration":"230.392991ms","start":"2026-05-01T22:25:26.694724Z","end":"2026-05-01T22:25:26.925117Z","steps":["trace[1523943692] 'agreement among raft nodes before linearized reading' (duration: 230.149995ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T22:25:26.925203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.236729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-01T22:25:26.92525Z","caller":"traceutil/trace.go:171","msg":"trace[732289128] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:71; }","duration":"129.511366ms","start":"2026-05-01T22:25:26.795725Z","end":"2026-05-01T22:25:26.925236Z","steps":["trace[732289128] 'agreement among raft nodes before linearized reading' (duration: 129.227489ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T22:25:30.827189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.460903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-01T22:25:30.827293Z","caller":"traceutil/trace.go:171","msg":"trace[1324784337] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:252; }","duration":"377.646089ms","start":"2026-05-01T22:25:30.449624Z","end":"2026-05-01T22:25:30.82727Z","steps":["trace[1324784337] 'range keys from in-memory index tree' (duration: 377.371982ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T22:25:30.827343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T22:25:30.449604Z","time spent":"377.72985ms","remote":"127.0.0.1:38860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":29,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-01T22:25:30.827729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"893.421076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-01T22:25:30.827775Z","caller":"traceutil/trace.go:171","msg":"trace[893788483] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:0; response_revision:252; }","duration":"893.466667ms","start":"2026-05-01T22:25:29.934297Z","end":"2026-05-01T22:25:30.827764Z","steps":["trace[893788483] 'range keys from in-memory index tree' (duration: 893.369044ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T22:25:30.82782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T22:25:29.934231Z","time spent":"893.579319ms","remote":"127.0.0.1:38814","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":0,"response size":29,"request content":"key:\"/registry/serviceaccounts/kube-system/node-controller\" "} {"level":"warn","ts":"2026-05-01T22:25:30.828336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"652.407273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-01T22:25:30.828745Z","caller":"traceutil/trace.go:171","msg":"trace[370018494] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:252; }","duration":"652.805022ms","start":"2026-05-01T22:25:30.175919Z","end":"2026-05-01T22:25:30.828724Z","steps":["trace[370018494] 'range keys from in-memory index tree' (duration: 652.332821ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T22:25:30.828824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T22:25:30.175877Z","time spent":"652.913855ms","remote":"127.0.0.1:38860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-01T22:25:30.83419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"556.787732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:3468"} {"level":"info","ts":"2026-05-01T22:25:30.834311Z","caller":"traceutil/trace.go:171","msg":"trace[1344331524] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:252; }","duration":"556.953006ms","start":"2026-05-01T22:25:30.277328Z","end":"2026-05-01T22:25:30.834281Z","steps":["trace[1344331524] 'range keys from in-memory index tree' (duration: 556.658449ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T22:25:30.834375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T22:25:30.277303Z","time spent":"557.061089ms","remote":"127.0.0.1:38788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":3492,"request content":"key:\"/registry/pods/kube-system/kube-vip-instance\" "} {"level":"info","ts":"2026-05-01T22:25:31.247251Z","caller":"traceutil/trace.go:171","msg":"trace[2033015780] transaction","detail":"{read_only:false; response_revision:254; number_of_response:1; }","duration":"396.227679ms","start":"2026-05-01T22:25:30.850992Z","end":"2026-05-01T22:25:31.24722Z","steps":["trace[2033015780] 'process raft request' (duration: 396.187848ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T22:25:31.247343Z","caller":"traceutil/trace.go:171","msg":"trace[1277634376] transaction","detail":"{read_only:false; response_revision:253; number_of_response:1; }","duration":"409.243158ms","start":"2026-05-01T22:25:30.838052Z","end":"2026-05-01T22:25:31.247295Z","steps":["trace[1277634376] 'process raft request' (duration: 408.928831ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T22:25:31.247814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T22:25:30.850969Z","time spent":"396.378963ms","remote":"127.0.0.1:38860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-01T22:25:31.248109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T22:25:30.838028Z","time spent":"409.395212ms","remote":"127.0.0.1:38860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-01T22:25:31.752408Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14208186009073998661,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-01T22:25:32.118974Z","caller":"traceutil/trace.go:171","msg":"trace[753699277] transaction","detail":"{read_only:false; response_revision:256; number_of_response:1; }","duration":"1.265069631s","start":"2026-05-01T22:25:30.85387Z","end":"2026-05-01T22:25:32.118939Z","steps":["trace[753699277] 'process raft request' (duration: 1.2650038s)"],"step_count":1} {"level":"info","ts":"2026-05-01T22:25:32.119016Z","caller":"traceutil/trace.go:171","msg":"trace[364134196] transaction","detail":"{read_only:false; response_revision:255; number_of_response:1; }","duration":"1.267687913s","start":"2026-05-01T22:25:30.851281Z","end":"2026-05-01T22:25:32.118969Z","steps":["trace[364134196] 'process raft request' (duration: 1.267361175s)"],"step_count":1} {"level":"warn","ts":"2026-05-01T22:25:32.119195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T22:25:30.853851Z","time spent":"1.265237215s","remote":"127.0.0.1:38814","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":180,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-01T22:25:32.1192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T22:25:30.851274Z","time spent":"1.267827497s","remote":"127.0.0.1:38788","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4649,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-01T22:25:32.121999Z","caller":"traceutil/trace.go:171","msg":"trace[1584771538] linearizableReadLoop","detail":"{readStateIndex:264; appliedIndex:261; }","duration":"870.553373ms","start":"2026-05-01T22:25:31.251399Z","end":"2026-05-01T22:25:32.121953Z","steps":["trace[1584771538] 'read index received' (duration: 867.214323ms)","trace[1584771538] 'applied index is now lower than readState.Index' (duration: 3.33735ms)"],"step_count":2} {"level":"info","ts":"2026-05-01T22:25:32.122021Z","caller":"traceutil/trace.go:171","msg":"trace[1620098921] transaction","detail":"{read_only:false; response_revision:257; number_of_response:1; }","duration":"868.785441ms","start":"2026-05-01T22:25:31.253215Z","end":"2026-05-01T22:25:32.122Z","steps":["trace[1620098921] 'process raft request' (duration: 868.596406ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T22:25:32.122182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T22:25:31.253202Z","time spent":"868.922484ms","remote":"127.0.0.1:38690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":705,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-01T22:25:32.122253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.853956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-01T22:25:32.122256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"870.832189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-01T22:25:32.122268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.611484ms","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-01T22:25:32.122317Z","caller":"traceutil/trace.go:171","msg":"trace[275581608] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:257; }","duration":"434.977779ms","start":"2026-05-01T22:25:31.687319Z","end":"2026-05-01T22:25:32.122296Z","steps":["trace[275581608] 'agreement among raft nodes before linearized reading' (duration: 434.823455ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T22:25:32.122332Z","caller":"traceutil/trace.go:171","msg":"trace[924424159] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:257; }","duration":"248.714966ms","start":"2026-05-01T22:25:31.8736Z","end":"2026-05-01T22:25:32.122315Z","steps":["trace[924424159] 'agreement among raft nodes before linearized reading' (duration: 248.591343ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T22:25:32.122332Z","caller":"traceutil/trace.go:171","msg":"trace[85543240] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:257; }","duration":"870.941842ms","start":"2026-05-01T22:25:31.251367Z","end":"2026-05-01T22:25:32.122309Z","steps":["trace[85543240] 'agreement among raft nodes before linearized reading' (duration: 870.760988ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T22:25:32.122378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T22:25:31.25135Z","time spent":"871.015943ms","remote":"127.0.0.1:38860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-01T22:25:32.122371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T22:25:31.687292Z","time spent":"435.065541ms","remote":"127.0.0.1:38860","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":"info","ts":"2026-05-01T22:25:55.231347Z","caller":"traceutil/trace.go:171","msg":"trace[787554820] transaction","detail":"{read_only:false; response_revision:469; number_of_response:1; }","duration":"443.337697ms","start":"2026-05-01T22:25:54.78795Z","end":"2026-05-01T22:25:55.231288Z","steps":["trace[787554820] 'process raft request' (duration: 442.988699ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T22:25:55.231838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T22:25:54.787929Z","time spent":"443.756558ms","remote":"127.0.0.1:38654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-01T22:25:55.239374Z","caller":"traceutil/trace.go:171","msg":"trace[915516177] transaction","detail":"{read_only:false; response_revision:470; number_of_response:1; }","duration":"449.723309ms","start":"2026-05-01T22:25:54.789598Z","end":"2026-05-01T22:25:55.239321Z","steps":["trace[915516177] 'process raft request' (duration: 449.514944ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T22:25:55.23962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T22:25:54.789567Z","time spent":"449.943275ms","remote":"127.0.0.1:38654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-01T22:25:55.239724Z","caller":"traceutil/trace.go:171","msg":"trace[35534186] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"433.942694ms","start":"2026-05-01T22:25:54.805722Z","end":"2026-05-01T22:25:55.239665Z","steps":["trace[35534186] 'process raft request' (duration: 433.576946ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T22:25:55.239945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T22:25:54.805715Z","time spent":"434.13813ms","remote":"127.0.0.1:38654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-01T22:25:55.239924Z","caller":"traceutil/trace.go:171","msg":"trace[1842488568] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"441.167156ms","start":"2026-05-01T22:25:54.798716Z","end":"2026-05-01T22:25:55.239883Z","steps":["trace[1842488568] 'process raft request' (duration: 440.519911ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T22:25:55.240089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T22:25:54.798694Z","time spent":"441.311759ms","remote":"127.0.0.1:38654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-01T22:25:55.240127Z","caller":"traceutil/trace.go:171","msg":"trace[1855387149] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"436.310501ms","start":"2026-05-01T22:25:54.803796Z","end":"2026-05-01T22:25:55.240107Z","steps":["trace[1855387149] 'process raft request' (duration: 435.470531ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T22:25:55.240145Z","caller":"traceutil/trace.go:171","msg":"trace[2003166774] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"428.096426ms","start":"2026-05-01T22:25:54.812022Z","end":"2026-05-01T22:25:55.240119Z","steps":["trace[2003166774] 'process raft request' (duration: 427.40969ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T22:25:55.240195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T22:25:54.803784Z","time spent":"436.378293ms","remote":"127.0.0.1:38654","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-01T22:25:55.240243Z","caller":"traceutil/trace.go:171","msg":"trace[1767117528] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"428.083346ms","start":"2026-05-01T22:25:54.812153Z","end":"2026-05-01T22:25:55.240237Z","steps":["trace[1767117528] 'process raft request' (duration: 427.337588ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T22:25:55.240265Z","caller":"traceutil/trace.go:171","msg":"trace[1234109344] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"419.915642ms","start":"2026-05-01T22:25:54.820335Z","end":"2026-05-01T22:25:55.24025Z","steps":["trace[1234109344] 'process raft request' (duration: 419.292037ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T22:25:55.240267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T22:25:54.812015Z","time spent":"428.195528ms","remote":"127.0.0.1:38654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-01T22:25:55.240193Z","caller":"traceutil/trace.go:171","msg":"trace[700286257] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"256.786078ms","start":"2026-05-01T22:25:54.98338Z","end":"2026-05-01T22:25:55.240166Z","steps":["trace[700286257] 'process raft request' (duration: 256.694576ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T22:25:55.240322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T22:25:54.820305Z","time spent":"419.990583ms","remote":"127.0.0.1:38654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-01T22:25:55.240337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T22:25:54.812127Z","time spent":"428.153587ms","remote":"127.0.0.1:38654","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-01T22:25:55.240414Z","caller":"traceutil/trace.go:171","msg":"trace[1742103871] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"381.159422ms","start":"2026-05-01T22:25:54.859246Z","end":"2026-05-01T22:25:55.240405Z","steps":["trace[1742103871] 'process raft request' (duration: 380.455715ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T22:25:55.240466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T22:25:54.859208Z","time spent":"381.235523ms","remote":"127.0.0.1:38654","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-01T22:25:55.240548Z","caller":"traceutil/trace.go:171","msg":"trace[1271032932] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"434.763464ms","start":"2026-05-01T22:25:54.805771Z","end":"2026-05-01T22:25:55.240535Z","steps":["trace[1271032932] 'process raft request' (duration: 433.577346ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T22:25:55.240573Z","caller":"traceutil/trace.go:171","msg":"trace[1852365703] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"432.107741ms","start":"2026-05-01T22:25:54.808454Z","end":"2026-05-01T22:25:55.240562Z","steps":["trace[1852365703] 'process raft request' (duration: 430.930743ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T22:25:55.24061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T22:25:54.805768Z","time spent":"434.816035ms","remote":"127.0.0.1:38654","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-01T22:25:55.240618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T22:25:54.808448Z","time spent":"432.149692ms","remote":"127.0.0.1:38654","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-01T22:26:01.879836Z","caller":"traceutil/trace.go:171","msg":"trace[989937850] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"181.457138ms","start":"2026-05-01T22:26:01.69835Z","end":"2026-05-01T22:26:01.879807Z","steps":["trace[989937850] 'process raft request' (duration: 180.918045ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T22:26:02.512143Z","caller":"traceutil/trace.go:171","msg":"trace[175708378] linearizableReadLoop","detail":"{readStateIndex:599; appliedIndex:598; }","duration":"175.045837ms","start":"2026-05-01T22:26:02.337078Z","end":"2026-05-01T22:26:02.512124Z","steps":["trace[175708378] 'read index received' (duration: 161.138427ms)","trace[175708378] 'applied index is now lower than readState.Index' (duration: 13.90642ms)"],"step_count":2} {"level":"info","ts":"2026-05-01T22:26:02.51218Z","caller":"traceutil/trace.go:171","msg":"trace[690659154] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"568.322756ms","start":"2026-05-01T22:26:01.943822Z","end":"2026-05-01T22:26:02.512145Z","steps":["trace[690659154] 'process raft request' (duration: 554.448966ms)","trace[690659154] 'compare' (duration: 13.616783ms)"],"step_count":2} {"level":"warn","ts":"2026-05-01T22:26:02.512311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T22:26:01.943797Z","time spent":"568.454909ms","remote":"127.0.0.1:38846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-01T22:26:02.512415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.437936ms","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-01T22:26:02.512467Z","caller":"traceutil/trace.go:171","msg":"trace[567589691] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:580; }","duration":"151.518408ms","start":"2026-05-01T22:26:02.360933Z","end":"2026-05-01T22:26:02.512451Z","steps":["trace[567589691] 'agreement among raft nodes before linearized reading' (duration: 151.398725ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T22:26:02.512705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.019251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-01T22:26:02.513038Z","caller":"traceutil/trace.go:171","msg":"trace[877568771] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:580; }","duration":"155.375399ms","start":"2026-05-01T22:26:02.357647Z","end":"2026-05-01T22:26:02.513022Z","steps":["trace[877568771] 'agreement among raft nodes before linearized reading' (duration: 154.770855ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T22:26:02.512915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.827986ms","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-01T22:26:02.513399Z","caller":"traceutil/trace.go:171","msg":"trace[338504801] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:580; }","duration":"176.338087ms","start":"2026-05-01T22:26:02.337048Z","end":"2026-05-01T22:26:02.513386Z","steps":["trace[338504801] 'agreement among raft nodes before linearized reading' (duration: 175.173779ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T22:26:02.512972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.50185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-qrjnx\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-05-01T22:26:02.513746Z","caller":"traceutil/trace.go:171","msg":"trace[212611677] range","detail":"{range_begin:/registry/pods/kube-system/cilium-qrjnx; range_end:; response_count:1; response_revision:580; }","duration":"158.313619ms","start":"2026-05-01T22:26:02.355421Z","end":"2026-05-01T22:26:02.513735Z","steps":["trace[212611677] 'agreement among raft nodes before linearized reading' (duration: 156.895416ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T22:26:03.509481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"760.051718ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-01T22:26:03.509718Z","caller":"traceutil/trace.go:171","msg":"trace[1954255813] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"989.325173ms","start":"2026-05-01T22:26:02.520357Z","end":"2026-05-01T22:26:03.509682Z","steps":["trace[1954255813] 'process raft request' (duration: 989.21284ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T22:26:03.509865Z","caller":"traceutil/trace.go:171","msg":"trace[2055563671] linearizableReadLoop","detail":"{readStateIndex:601; appliedIndex:599; }","duration":"990.766767ms","start":"2026-05-01T22:26:02.519091Z","end":"2026-05-01T22:26:03.509857Z","steps":["trace[2055563671] 'read index received' (duration: 36.361503ms)","trace[2055563671] 'applied index is now lower than readState.Index' (duration: 954.404124ms)"],"step_count":2} {"level":"info","ts":"2026-05-01T22:26:03.509861Z","caller":"traceutil/trace.go:171","msg":"trace[2017877280] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"982.418989ms","start":"2026-05-01T22:26:02.527412Z","end":"2026-05-01T22:26:03.509831Z","steps":["trace[2017877280] 'process raft request' (duration: 982.214034ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T22:26:03.509807Z","caller":"traceutil/trace.go:171","msg":"trace[1020204989] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"992.101519ms","start":"2026-05-01T22:26:02.517679Z","end":"2026-05-01T22:26:03.50978Z","steps":["trace[1020204989] 'process raft request' (duration: 231.554499ms)","trace[1020204989] 'compare' (duration: 759.919405ms)"],"step_count":2} {"level":"warn","ts":"2026-05-01T22:26:03.509954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T22:26:02.520348Z","time spent":"989.518067ms","remote":"127.0.0.1:38860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-01T22:26:03.510071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T22:26:02.517653Z","time spent":"992.330314ms","remote":"127.0.0.1:38860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-01T22:26:03.510156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T22:26:02.527394Z","time spent":"982.533161ms","remote":"127.0.0.1:38788","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-01T22:26:03.534269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.015177097s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-05-01T22:26:03.534334Z","caller":"traceutil/trace.go:171","msg":"trace[1892026907] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:584; }","duration":"1.015277119s","start":"2026-05-01T22:26:02.519035Z","end":"2026-05-01T22:26:03.534312Z","steps":["trace[1892026907] 'agreement among raft nodes before linearized reading' (duration: 990.88776ms)","trace[1892026907] 'range keys from in-memory index tree' (duration: 24.286896ms)"],"step_count":2} {"level":"warn","ts":"2026-05-01T22:26:03.534374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T22:26:02.519016Z","time spent":"1.015349771s","remote":"127.0.0.1:38814","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":217,"request content":"key:\"/registry/serviceaccounts/kube-system/job-controller\" "} {"level":"warn","ts":"2026-05-01T22:26:03.534588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.856541ms","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-01T22:26:03.534645Z","caller":"traceutil/trace.go:171","msg":"trace[1438114547] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:584; }","duration":"107.960334ms","start":"2026-05-01T22:26:03.426672Z","end":"2026-05-01T22:26:03.534632Z","steps":["trace[1438114547] 'agreement among raft nodes before linearized reading' (duration: 107.536614ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T22:26:04.840064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.032424ms","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-01T22:26:04.840162Z","caller":"traceutil/trace.go:171","msg":"trace[1979349295] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:594; }","duration":"281.214447ms","start":"2026-05-01T22:26:04.558928Z","end":"2026-05-01T22:26:04.840142Z","steps":["trace[1979349295] 'range keys from in-memory index tree' (duration: 280.710006ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T22:26:18.705184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.167818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-01T22:26:18.705249Z","caller":"traceutil/trace.go:171","msg":"trace[1402807210] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:742; }","duration":"209.2721ms","start":"2026-05-01T22:26:18.495963Z","end":"2026-05-01T22:26:18.705235Z","steps":["trace[1402807210] 'range keys from in-memory index tree' (duration: 209.101096ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T22:26:18.705247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.577584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-bk2l9\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-05-01T22:26:18.705317Z","caller":"traceutil/trace.go:171","msg":"trace[108711960] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-bk2l9; range_end:; response_count:1; response_revision:742; }","duration":"259.682397ms","start":"2026-05-01T22:26:18.445619Z","end":"2026-05-01T22:26:18.705301Z","steps":["trace[108711960] 'range keys from in-memory index tree' (duration: 259.451281ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T22:26:25.812186Z","caller":"traceutil/trace.go:171","msg":"trace[782717030] linearizableReadLoop","detail":"{readStateIndex:835; appliedIndex:833; }","duration":"172.511225ms","start":"2026-05-01T22:26:25.639651Z","end":"2026-05-01T22:26:25.812162Z","steps":["trace[782717030] 'read index received' (duration: 15.597708ms)","trace[782717030] 'applied index is now lower than readState.Index' (duration: 156.912107ms)"],"step_count":2} {"level":"info","ts":"2026-05-01T22:26:25.8122Z","caller":"traceutil/trace.go:171","msg":"trace[904861269] transaction","detail":"{read_only:false; response_revision:809; number_of_response:1; }","duration":"203.59599ms","start":"2026-05-01T22:26:25.608579Z","end":"2026-05-01T22:26:25.812175Z","steps":["trace[904861269] 'process raft request' (duration: 172.226529ms)","trace[904861269] 'compare' (duration: 31.236187ms)"],"step_count":2} {"level":"info","ts":"2026-05-01T22:26:25.812273Z","caller":"traceutil/trace.go:171","msg":"trace[451738336] transaction","detail":"{read_only:false; response_revision:810; number_of_response:1; }","duration":"191.214109ms","start":"2026-05-01T22:26:25.621011Z","end":"2026-05-01T22:26:25.812225Z","steps":["trace[451738336] 'process raft request' (duration: 191.111376ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T22:26:25.812824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.14068ms","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-01T22:26:25.812927Z","caller":"traceutil/trace.go:171","msg":"trace[321344197] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:810; }","duration":"173.285704ms","start":"2026-05-01T22:26:25.639622Z","end":"2026-05-01T22:26:25.812907Z","steps":["trace[321344197] 'agreement among raft nodes before linearized reading' (duration: 172.671879ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T22:26:26.075824Z","caller":"traceutil/trace.go:171","msg":"trace[974793994] transaction","detail":"{read_only:false; response_revision:814; number_of_response:1; }","duration":"128.244902ms","start":"2026-05-01T22:26:25.947554Z","end":"2026-05-01T22:26:26.075799Z","steps":["trace[974793994] 'process raft request' (duration: 124.132042ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T22:26:26.076324Z","caller":"traceutil/trace.go:171","msg":"trace[1349020018] transaction","detail":"{read_only:false; response_revision:815; number_of_response:1; }","duration":"125.240008ms","start":"2026-05-01T22:26:25.951073Z","end":"2026-05-01T22:26:26.076313Z","steps":["trace[1349020018] 'process raft request' (duration: 124.653074ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T22:26:48.135462Z","caller":"traceutil/trace.go:171","msg":"trace[266043471] transaction","detail":"{read_only:false; response_revision:936; number_of_response:1; }","duration":"105.793926ms","start":"2026-05-01T22:26:48.029639Z","end":"2026-05-01T22:26:48.135433Z","steps":["trace[266043471] 'process raft request' (duration: 100.533909ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T22:26:53.725173Z","caller":"traceutil/trace.go:171","msg":"trace[224115755] linearizableReadLoop","detail":"{readStateIndex:987; appliedIndex:986; }","duration":"432.469232ms","start":"2026-05-01T22:26:53.292679Z","end":"2026-05-01T22:26:53.725148Z","steps":["trace[224115755] 'read index received' (duration: 432.206206ms)","trace[224115755] 'applied index is now lower than readState.Index' (duration: 258.616µs)"],"step_count":2} {"level":"info","ts":"2026-05-01T22:26:53.725271Z","caller":"traceutil/trace.go:171","msg":"trace[610989516] transaction","detail":"{read_only:false; response_revision:954; number_of_response:1; }","duration":"615.118714ms","start":"2026-05-01T22:26:53.110124Z","end":"2026-05-01T22:26:53.725243Z","steps":["trace[610989516] 'process raft request' (duration: 614.846837ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T22:26:53.725372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.057817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-01T22:26:53.725386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.694678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-01T22:26:53.725414Z","caller":"traceutil/trace.go:171","msg":"trace[958199330] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:954; }","duration":"229.17647ms","start":"2026-05-01T22:26:53.496225Z","end":"2026-05-01T22:26:53.725401Z","steps":["trace[958199330] 'agreement among raft nodes before linearized reading' (duration: 229.098468ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T22:26:53.725421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T22:26:53.110101Z","time spent":"615.240616ms","remote":"127.0.0.1:38860","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-01T22:26:53.725427Z","caller":"traceutil/trace.go:171","msg":"trace[890075644] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:954; }","duration":"432.78998ms","start":"2026-05-01T22:26:53.292627Z","end":"2026-05-01T22:26:53.725417Z","steps":["trace[890075644] 'agreement among raft nodes before linearized reading' (duration: 432.620596ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T22:26:53.725564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T22:26:53.292607Z","time spent":"432.895103ms","remote":"127.0.0.1:38860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-01T22:26:54.23156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.543009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-01T22:26:54.231681Z","caller":"traceutil/trace.go:171","msg":"trace[1947482415] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:955; }","duration":"426.713543ms","start":"2026-05-01T22:26:53.804948Z","end":"2026-05-01T22:26:54.231662Z","steps":["trace[1947482415] 'range keys from in-memory index tree' (duration: 426.286552ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T22:26:54.231726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T22:26:53.804936Z","time spent":"426.781164ms","remote":"127.0.0.1:38860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-01T22:26:54.299381Z","caller":"traceutil/trace.go:171","msg":"trace[311765548] linearizableReadLoop","detail":"{readStateIndex:989; appliedIndex:988; }","duration":"246.624193ms","start":"2026-05-01T22:26:54.052736Z","end":"2026-05-01T22:26:54.29936Z","steps":["trace[311765548] 'read index received' (duration: 246.552112ms)","trace[311765548] 'applied index is now lower than readState.Index' (duration: 70.621µs)"],"step_count":2} {"level":"info","ts":"2026-05-01T22:26:54.299499Z","caller":"traceutil/trace.go:171","msg":"trace[899267388] transaction","detail":"{read_only:false; response_revision:956; number_of_response:1; }","duration":"255.468308ms","start":"2026-05-01T22:26:54.04399Z","end":"2026-05-01T22:26:54.299458Z","steps":["trace[899267388] 'process raft request' (duration: 255.232172ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T22:26:54.299625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.844939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-01T22:26:54.299662Z","caller":"traceutil/trace.go:171","msg":"trace[693810679] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:956; }","duration":"246.946851ms","start":"2026-05-01T22:26:54.052705Z","end":"2026-05-01T22:26:54.299652Z","steps":["trace[693810679] 'agreement among raft nodes before linearized reading' (duration: 246.759686ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T22:26:54.799949Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14208186009074000155,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-01T22:26:54.90272Z","caller":"traceutil/trace.go:171","msg":"trace[1200882808] transaction","detail":"{read_only:false; response_revision:957; number_of_response:1; }","duration":"667.603807ms","start":"2026-05-01T22:26:54.235089Z","end":"2026-05-01T22:26:54.902693Z","steps":["trace[1200882808] 'process raft request' (duration: 667.473254ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T22:26:54.902875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T22:26:54.235069Z","time spent":"667.723809ms","remote":"127.0.0.1:38860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-01T22:26:55.084881Z","caller":"traceutil/trace.go:171","msg":"trace[1661203507] linearizableReadLoop","detail":"{readStateIndex:991; appliedIndex:989; }","duration":"785.403135ms","start":"2026-05-01T22:26:54.299443Z","end":"2026-05-01T22:26:55.084846Z","steps":["trace[1661203507] 'read index received' (duration: 603.096142ms)","trace[1661203507] 'applied index is now lower than readState.Index' (duration: 182.305573ms)"],"step_count":2} {"level":"info","ts":"2026-05-01T22:26:55.084925Z","caller":"traceutil/trace.go:171","msg":"trace[1645706367] transaction","detail":"{read_only:false; response_revision:958; number_of_response:1; }","duration":"780.978677ms","start":"2026-05-01T22:26:54.303919Z","end":"2026-05-01T22:26:55.084898Z","steps":["trace[1645706367] 'process raft request' (duration: 779.606224ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T22:26:55.085102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T22:26:54.303895Z","time spent":"781.11665ms","remote":"127.0.0.1:38860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-01T22:26:55.085113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"587.096043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-01T22:26:55.085123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"918.153495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-01T22:26:55.085168Z","caller":"traceutil/trace.go:171","msg":"trace[146958189] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:958; }","duration":"587.206336ms","start":"2026-05-01T22:26:54.497944Z","end":"2026-05-01T22:26:55.08515Z","steps":["trace[146958189] 'agreement among raft nodes before linearized reading' (duration: 587.094603ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T22:26:55.08519Z","caller":"traceutil/trace.go:171","msg":"trace[194699537] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:958; }","duration":"918.227257ms","start":"2026-05-01T22:26:54.166946Z","end":"2026-05-01T22:26:55.085173Z","steps":["trace[194699537] 'agreement among raft nodes before linearized reading' (duration: 918.051713ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T22:26:55.085219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T22:26:54.497924Z","time spent":"587.283368ms","remote":"127.0.0.1:38620","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-01T22:26:55.085232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T22:26:54.166897Z","time spent":"918.327069ms","remote":"127.0.0.1:38860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-01T22:26:55.336142Z","caller":"traceutil/trace.go:171","msg":"trace[1097886723] transaction","detail":"{read_only:false; response_revision:959; number_of_response:1; }","duration":"246.751796ms","start":"2026-05-01T22:26:55.089361Z","end":"2026-05-01T22:26:55.336113Z","steps":["trace[1097886723] 'process raft request' (duration: 174.963265ms)","trace[1097886723] 'compare' (duration: 71.674769ms)"],"step_count":2} {"level":"warn","ts":"2026-05-01T22:27:14.996214Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14208186009074000321,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-01T22:27:15.011787Z","caller":"traceutil/trace.go:171","msg":"trace[238491074] linearizableReadLoop","detail":"{readStateIndex:1059; appliedIndex:1058; }","duration":"515.952448ms","start":"2026-05-01T22:27:14.495802Z","end":"2026-05-01T22:27:15.011755Z","steps":["trace[238491074] 'read index received' (duration: 515.605069ms)","trace[238491074] 'applied index is now lower than readState.Index' (duration: 346.229µs)"],"step_count":2} {"level":"info","ts":"2026-05-01T22:27:15.011951Z","caller":"traceutil/trace.go:171","msg":"trace[2036423164] transaction","detail":"{read_only:false; response_revision:1022; number_of_response:1; }","duration":"561.981675ms","start":"2026-05-01T22:27:14.449907Z","end":"2026-05-01T22:27:15.011889Z","steps":["trace[2036423164] 'process raft request' (duration: 561.602816ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T22:27:15.012004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"516.179083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-01T22:27:15.012101Z","caller":"traceutil/trace.go:171","msg":"trace[904107739] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1022; }","duration":"516.348547ms","start":"2026-05-01T22:27:14.49573Z","end":"2026-05-01T22:27:15.012078Z","steps":["trace[904107739] 'agreement among raft nodes before linearized reading' (duration: 516.167773ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T22:27:15.012012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.542017ms","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-01T22:27:15.012191Z","caller":"traceutil/trace.go:171","msg":"trace[220410133] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1022; }","duration":"449.756482ms","start":"2026-05-01T22:27:14.562413Z","end":"2026-05-01T22:27:15.01217Z","steps":["trace[220410133] 'agreement among raft nodes before linearized reading' (duration: 449.532006ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T22:27:15.012221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T22:27:14.449881Z","time spent":"562.199169ms","remote":"127.0.0.1:38860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-01T22:27:15.012238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T22:27:14.495705Z","time spent":"516.519821ms","remote":"127.0.0.1:38620","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-01T22:27:15.012242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T22:27:14.562401Z","time spent":"449.827103ms","remote":"127.0.0.1:58948","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"info","ts":"2026-05-01T22:27:16.154902Z","caller":"traceutil/trace.go:171","msg":"trace[489266957] transaction","detail":"{read_only:false; response_revision:1026; number_of_response:1; }","duration":"260.809547ms","start":"2026-05-01T22:27:15.894075Z","end":"2026-05-01T22:27:16.154884Z","steps":["trace[489266957] 'process raft request' (duration: 260.661324ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T22:27:16.155029Z","caller":"traceutil/trace.go:171","msg":"trace[267700742] linearizableReadLoop","detail":"{readStateIndex:1063; appliedIndex:1062; }","duration":"224.913846ms","start":"2026-05-01T22:27:15.930081Z","end":"2026-05-01T22:27:16.154995Z","steps":["trace[267700742] 'read index received' (duration: 224.65994ms)","trace[267700742] 'applied index is now lower than readState.Index' (duration: 250.256µs)"],"step_count":2} {"level":"warn","ts":"2026-05-01T22:27:16.155161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.068861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-01T22:27:16.155187Z","caller":"traceutil/trace.go:171","msg":"trace[936897835] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1026; }","duration":"225.144283ms","start":"2026-05-01T22:27:15.930036Z","end":"2026-05-01T22:27:16.15518Z","steps":["trace[936897835] 'agreement among raft nodes before linearized reading' (duration: 225.04681ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T22:27:16.407289Z","caller":"traceutil/trace.go:171","msg":"trace[965023381] transaction","detail":"{read_only:false; response_revision:1027; number_of_response:1; }","duration":"247.604888ms","start":"2026-05-01T22:27:16.159623Z","end":"2026-05-01T22:27:16.407228Z","steps":["trace[965023381] 'process raft request' (duration: 227.210683ms)","trace[965023381] 'compare' (duration: 20.056496ms)"],"step_count":2} {"level":"info","ts":"2026-05-01T22:27:17.133982Z","caller":"traceutil/trace.go:171","msg":"trace[901901682] transaction","detail":"{read_only:false; response_revision:1028; number_of_response:1; }","duration":"114.939059ms","start":"2026-05-01T22:27:17.019016Z","end":"2026-05-01T22:27:17.133955Z","steps":["trace[901901682] 'process raft request' (duration: 114.712314ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T22:27:19.733245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.63688ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-01T22:27:19.73373Z","caller":"traceutil/trace.go:171","msg":"trace[1928097152] linearizableReadLoop","detail":"{readStateIndex:1075; appliedIndex:1074; }","duration":"485.700303ms","start":"2026-05-01T22:27:19.247996Z","end":"2026-05-01T22:27:19.733697Z","steps":["trace[1928097152] 'read index received' (duration: 199.372987ms)","trace[1928097152] 'applied index is now lower than readState.Index' (duration: 286.324186ms)"],"step_count":2} {"level":"info","ts":"2026-05-01T22:27:19.733883Z","caller":"traceutil/trace.go:171","msg":"trace[264657189] transaction","detail":"{read_only:false; response_revision:1037; number_of_response:1; }","duration":"519.522954ms","start":"2026-05-01T22:27:19.214345Z","end":"2026-05-01T22:27:19.733868Z","steps":["trace[264657189] 'process raft request' (duration: 233.122626ms)","trace[264657189] 'compare' (duration: 285.060346ms)"],"step_count":2} {"level":"warn","ts":"2026-05-01T22:27:19.734023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T22:27:19.21432Z","time spent":"519.656317ms","remote":"127.0.0.1:38860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-01T22:27:19.734104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.305491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-01T22:27:19.734205Z","caller":"traceutil/trace.go:171","msg":"trace[270576111] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1037; }","duration":"238.468046ms","start":"2026-05-01T22:27:19.495712Z","end":"2026-05-01T22:27:19.73418Z","steps":["trace[270576111] 'agreement among raft nodes before linearized reading' (duration: 238.297132ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T22:27:19.734609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.615806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-01T22:27:19.734667Z","caller":"traceutil/trace.go:171","msg":"trace[379727437] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1037; }","duration":"486.705139ms","start":"2026-05-01T22:27:19.247947Z","end":"2026-05-01T22:27:19.734652Z","steps":["trace[379727437] 'agreement among raft nodes before linearized reading' (duration: 486.529364ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T22:27:19.73472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T22:27:19.247924Z","time spent":"486.78142ms","remote":"127.0.0.1:38860","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-01T22:27:19.734727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.375614ms","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-01T22:27:19.734771Z","caller":"traceutil/trace.go:171","msg":"trace[510807939] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1037; }","duration":"217.454796ms","start":"2026-05-01T22:27:19.517305Z","end":"2026-05-01T22:27:19.73476Z","steps":["trace[510807939] 'agreement among raft nodes before linearized reading' (duration: 217.260021ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T22:27:20.442077Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14208186009074000369,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-01T22:27:20.607943Z","caller":"traceutil/trace.go:171","msg":"trace[756950514] transaction","detail":"{read_only:false; response_revision:1038; number_of_response:1; }","duration":"868.585312ms","start":"2026-05-01T22:27:19.739336Z","end":"2026-05-01T22:27:20.607921Z","steps":["trace[756950514] 'process raft request' (duration: 868.46548ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T22:27:20.608243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T22:27:19.739315Z","time spent":"868.706236ms","remote":"127.0.0.1:38860","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-01T22:27:20.627071Z","caller":"traceutil/trace.go:171","msg":"trace[522434557] linearizableReadLoop","detail":"{readStateIndex:1077; appliedIndex:1076; }","duration":"685.48891ms","start":"2026-05-01T22:27:19.941549Z","end":"2026-05-01T22:27:20.627038Z","steps":["trace[522434557] 'read index received' (duration: 666.653553ms)","trace[522434557] 'applied index is now lower than readState.Index' (duration: 18.833827ms)"],"step_count":2} {"level":"info","ts":"2026-05-01T22:27:20.627388Z","caller":"traceutil/trace.go:171","msg":"trace[1954079875] transaction","detail":"{read_only:false; response_revision:1039; number_of_response:1; }","duration":"887.658005ms","start":"2026-05-01T22:27:19.739716Z","end":"2026-05-01T22:27:20.627374Z","steps":["trace[1954079875] 'process raft request' (duration: 887.178353ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T22:27:20.627554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T22:27:19.739695Z","time spent":"887.731676ms","remote":"127.0.0.1:38860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-01T22:27:20.627809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"686.265139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-01T22:27:20.627847Z","caller":"traceutil/trace.go:171","msg":"trace[1822736295] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:1039; }","duration":"686.361561ms","start":"2026-05-01T22:27:19.941473Z","end":"2026-05-01T22:27:20.627834Z","steps":["trace[1822736295] 'agreement among raft nodes before linearized reading' (duration: 686.29352ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T22:27:20.627879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T22:27:19.941442Z","time spent":"686.429353ms","remote":"127.0.0.1:59080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true "} {"level":"warn","ts":"2026-05-01T22:27:20.62805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"671.114151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-01T22:27:20.628081Z","caller":"traceutil/trace.go:171","msg":"trace[1344561630] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1039; }","duration":"671.183973ms","start":"2026-05-01T22:27:19.956885Z","end":"2026-05-01T22:27:20.628069Z","steps":["trace[1344561630] 'agreement among raft nodes before linearized reading' (duration: 671.140362ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T22:27:20.628125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T22:27:19.956859Z","time spent":"671.257705ms","remote":"127.0.0.1:58936","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"warn","ts":"2026-05-01T22:27:20.628447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.602167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-01T22:27:20.628502Z","caller":"traceutil/trace.go:171","msg":"trace[1569145608] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1039; }","duration":"132.699969ms","start":"2026-05-01T22:27:20.495789Z","end":"2026-05-01T22:27:20.628489Z","steps":["trace[1569145608] 'agreement among raft nodes before linearized reading' (duration: 132.620927ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T22:27:20.62861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"606.653398ms","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-01T22:27:20.628696Z","caller":"traceutil/trace.go:171","msg":"trace[1160049527] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:1039; }","duration":"606.808342ms","start":"2026-05-01T22:27:20.021868Z","end":"2026-05-01T22:27:20.628677Z","steps":["trace[1160049527] 'agreement among raft nodes before linearized reading' (duration: 606.607117ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T22:27:20.628746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T22:27:20.021846Z","time spent":"606.889953ms","remote":"127.0.0.1:58872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true "} {"level":"warn","ts":"2026-05-01T22:27:20.628816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.165332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-01T22:27:20.628891Z","caller":"traceutil/trace.go:171","msg":"trace[111590904] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:1039; }","duration":"394.281385ms","start":"2026-05-01T22:27:20.234591Z","end":"2026-05-01T22:27:20.628872Z","steps":["trace[111590904] 'agreement among raft nodes before linearized reading' (duration: 394.142271ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T22:27:20.628902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.949456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-05-01T22:27:20.628938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T22:27:20.234578Z","time spent":"394.348647ms","remote":"127.0.0.1:38858","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":31,"request content":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true "} {"level":"info","ts":"2026-05-01T22:27:20.628959Z","caller":"traceutil/trace.go:171","msg":"trace[886438652] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1039; }","duration":"199.084049ms","start":"2026-05-01T22:27:20.42986Z","end":"2026-05-01T22:27:20.628944Z","steps":["trace[886438652] 'agreement among raft nodes before linearized reading' (duration: 198.896305ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T22:27:20.629087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"459.530078ms","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-01T22:27:20.629141Z","caller":"traceutil/trace.go:171","msg":"trace[747330843] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1039; }","duration":"459.659591ms","start":"2026-05-01T22:27:20.169466Z","end":"2026-05-01T22:27:20.629126Z","steps":["trace[747330843] 'agreement among raft nodes before linearized reading' (duration: 459.477136ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T22:27:20.629182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T22:27:20.169428Z","time spent":"459.744333ms","remote":"127.0.0.1:38860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-01T22:27:21.599439Z","caller":"traceutil/trace.go:171","msg":"trace[2088287360] linearizableReadLoop","detail":"{readStateIndex:1080; appliedIndex:1079; }","duration":"103.572633ms","start":"2026-05-01T22:27:21.495839Z","end":"2026-05-01T22:27:21.599412Z","steps":["trace[2088287360] 'read index received' (duration: 103.397159ms)","trace[2088287360] 'applied index is now lower than readState.Index' (duration: 173.984µs)"],"step_count":2} {"level":"info","ts":"2026-05-01T22:27:21.599489Z","caller":"traceutil/trace.go:171","msg":"trace[246823123] transaction","detail":"{read_only:false; response_revision:1042; number_of_response:1; }","duration":"382.52863ms","start":"2026-05-01T22:27:21.216935Z","end":"2026-05-01T22:27:21.599463Z","steps":["trace[246823123] 'process raft request' (duration: 382.316365ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T22:27:21.59957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.717656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-01T22:27:21.599642Z","caller":"traceutil/trace.go:171","msg":"trace[1467960360] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1042; }","duration":"103.859319ms","start":"2026-05-01T22:27:21.495771Z","end":"2026-05-01T22:27:21.59963Z","steps":["trace[1467960360] 'agreement among raft nodes before linearized reading' (duration: 103.739616ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T22:27:21.599724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T22:27:21.21689Z","time spent":"382.660823ms","remote":"127.0.0.1:38860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-01T22:27:23.080059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.897627ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-01T22:27:23.080142Z","caller":"traceutil/trace.go:171","msg":"trace[1394410088] linearizableReadLoop","detail":"{readStateIndex:1084; appliedIndex:1083; }","duration":"426.98922ms","start":"2026-05-01T22:27:22.653139Z","end":"2026-05-01T22:27:23.080128Z","steps":["trace[1394410088] 'read index received' (duration: 175.867577ms)","trace[1394410088] 'applied index is now lower than readState.Index' (duration: 251.120303ms)"],"step_count":2} {"level":"info","ts":"2026-05-01T22:27:23.08019Z","caller":"traceutil/trace.go:171","msg":"trace[1118629502] transaction","detail":"{read_only:false; response_revision:1046; number_of_response:1; }","duration":"439.028931ms","start":"2026-05-01T22:27:22.641153Z","end":"2026-05-01T22:27:23.080182Z","steps":["trace[1118629502] 'process raft request' (duration: 187.931039ms)","trace[1118629502] 'compare' (duration: 250.682312ms)"],"step_count":2} {"level":"warn","ts":"2026-05-01T22:27:23.080223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T22:27:22.641136Z","time spent":"439.070353ms","remote":"127.0.0.1:38860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-01T22:27:23.080352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.214245ms","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-01T22:27:23.08038Z","caller":"traceutil/trace.go:171","msg":"trace[1536888701] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1046; }","duration":"427.252885ms","start":"2026-05-01T22:27:22.65311Z","end":"2026-05-01T22:27:23.080363Z","steps":["trace[1536888701] 'agreement among raft nodes before linearized reading' (duration: 427.178714ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T22:27:23.080395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T22:27:22.653099Z","time spent":"427.293307ms","remote":"127.0.0.1:38860","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-01T22:27:23.158854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"473.59175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-01T22:27:23.158951Z","caller":"traceutil/trace.go:171","msg":"trace[657688069] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1047; }","duration":"473.729663ms","start":"2026-05-01T22:27:22.685204Z","end":"2026-05-01T22:27:23.158933Z","steps":["trace[657688069] 'agreement among raft nodes before linearized reading' (duration: 473.433396ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T22:27:23.158997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T22:27:22.685193Z","time spent":"473.795075ms","remote":"127.0.0.1:38860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-01T22:27:24.308863Z","caller":"traceutil/trace.go:171","msg":"trace[949692516] linearizableReadLoop","detail":"{readStateIndex:1089; appliedIndex:1088; }","duration":"294.135375ms","start":"2026-05-01T22:27:24.014703Z","end":"2026-05-01T22:27:24.308838Z","steps":["trace[949692516] 'read index received' (duration: 293.891839ms)","trace[949692516] 'applied index is now lower than readState.Index' (duration: 242.116µs)"],"step_count":2} {"level":"info","ts":"2026-05-01T22:27:24.308917Z","caller":"traceutil/trace.go:171","msg":"trace[1637630293] transaction","detail":"{read_only:false; response_revision:1050; number_of_response:1; }","duration":"470.138605ms","start":"2026-05-01T22:27:23.838747Z","end":"2026-05-01T22:27:24.308886Z","steps":["trace[1637630293] 'process raft request' (duration: 469.93633ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T22:27:24.308987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.271779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-01T22:27:24.309129Z","caller":"traceutil/trace.go:171","msg":"trace[785717442] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:1050; }","duration":"294.471194ms","start":"2026-05-01T22:27:24.014646Z","end":"2026-05-01T22:27:24.309117Z","steps":["trace[785717442] 'agreement among raft nodes before linearized reading' (duration: 294.287059ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T22:27:24.309176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T22:27:23.838722Z","time spent":"470.31552ms","remote":"127.0.0.1:38860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-01T22:27:24.716723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.998811ms","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-01T22:27:24.716811Z","caller":"traceutil/trace.go:171","msg":"trace[365389948] 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:1050; }","duration":"338.177735ms","start":"2026-05-01T22:27:24.378614Z","end":"2026-05-01T22:27:24.716791Z","steps":["trace[365389948] 'count revisions from in-memory index tree' (duration: 337.852607ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T22:27:24.716873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T22:27:24.378595Z","time spent":"338.265987ms","remote":"127.0.0.1:58916","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-01T22:27:24.717302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.949464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-01T22:27:24.717374Z","caller":"traceutil/trace.go:171","msg":"trace[693447367] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1050; }","duration":"222.057817ms","start":"2026-05-01T22:27:24.495299Z","end":"2026-05-01T22:27:24.717357Z","steps":["trace[693447367] 'range keys from in-memory index tree' (duration: 221.873763ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T22:27:24.884329Z","caller":"traceutil/trace.go:171","msg":"trace[295075493] transaction","detail":"{read_only:false; response_revision:1051; number_of_response:1; }","duration":"161.1688ms","start":"2026-05-01T22:27:24.723136Z","end":"2026-05-01T22:27:24.884305Z","steps":["trace[295075493] 'process raft request' (duration: 161.032197ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T22:27:35.110598Z","caller":"traceutil/trace.go:171","msg":"trace[1977992830] linearizableReadLoop","detail":"{readStateIndex:1178; appliedIndex:1177; }","duration":"112.449798ms","start":"2026-05-01T22:27:34.998131Z","end":"2026-05-01T22:27:35.110581Z","steps":["trace[1977992830] 'read index received' (duration: 112.292854ms)","trace[1977992830] 'applied index is now lower than readState.Index' (duration: 156.174µs)"],"step_count":2} {"level":"warn","ts":"2026-05-01T22:27:35.110737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.581991ms","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:7"} {"level":"info","ts":"2026-05-01T22:27:35.110765Z","caller":"traceutil/trace.go:171","msg":"trace[1564240873] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1135; }","duration":"112.669743ms","start":"2026-05-01T22:27:34.998086Z","end":"2026-05-01T22:27:35.110756Z","steps":["trace[1564240873] 'agreement among raft nodes before linearized reading' (duration: 112.584641ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T22:27:35.110751Z","caller":"traceutil/trace.go:171","msg":"trace[1349312311] transaction","detail":"{read_only:false; response_revision:1135; number_of_response:1; }","duration":"121.157616ms","start":"2026-05-01T22:27:34.989531Z","end":"2026-05-01T22:27:35.110689Z","steps":["trace[1349312311] 'process raft request' (duration: 120.941221ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T22:27:35.397013Z","caller":"traceutil/trace.go:171","msg":"trace[384095267] transaction","detail":"{read_only:false; response_revision:1136; number_of_response:1; }","duration":"141.174323ms","start":"2026-05-01T22:27:35.255816Z","end":"2026-05-01T22:27:35.396991Z","steps":["trace[384095267] 'process raft request' (duration: 141.028639ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T22:27:35.413432Z","caller":"traceutil/trace.go:171","msg":"trace[392066956] linearizableReadLoop","detail":"{readStateIndex:1180; appliedIndex:1178; }","duration":"155.582806ms","start":"2026-05-01T22:27:35.257822Z","end":"2026-05-01T22:27:35.413405Z","steps":["trace[392066956] 'read index received' (duration: 139.044012ms)","trace[392066956] 'applied index is now lower than readState.Index' (duration: 16.537584ms)"],"step_count":2} {"level":"warn","ts":"2026-05-01T22:27:35.413637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.786971ms","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-01T22:27:35.413623Z","caller":"traceutil/trace.go:171","msg":"trace[1898534733] transaction","detail":"{read_only:false; response_revision:1137; number_of_response:1; }","duration":"156.554269ms","start":"2026-05-01T22:27:35.257038Z","end":"2026-05-01T22:27:35.413592Z","steps":["trace[1898534733] 'process raft request' (duration: 156.218941ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T22:27:35.413664Z","caller":"traceutil/trace.go:171","msg":"trace[1388255275] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1137; }","duration":"155.859203ms","start":"2026-05-01T22:27:35.257796Z","end":"2026-05-01T22:27:35.413655Z","steps":["trace[1388255275] 'agreement among raft nodes before linearized reading' (duration: 155.707779ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T22:27:37.642344Z","caller":"traceutil/trace.go:171","msg":"trace[1476707483] linearizableReadLoop","detail":"{readStateIndex:1185; appliedIndex:1184; }","duration":"223.407672ms","start":"2026-05-01T22:27:37.418912Z","end":"2026-05-01T22:27:37.64232Z","steps":["trace[1476707483] 'read index received' (duration: 223.180596ms)","trace[1476707483] 'applied index is now lower than readState.Index' (duration: 225.776µs)"],"step_count":2} {"level":"info","ts":"2026-05-01T22:27:37.642448Z","caller":"traceutil/trace.go:171","msg":"trace[1406944893] transaction","detail":"{read_only:false; response_revision:1142; number_of_response:1; }","duration":"238.592084ms","start":"2026-05-01T22:27:37.40383Z","end":"2026-05-01T22:27:37.642422Z","steps":["trace[1406944893] 'process raft request' (duration: 238.346778ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T22:27:37.642578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.645118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-05-01T22:27:37.642612Z","caller":"traceutil/trace.go:171","msg":"trace[504880397] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1142; }","duration":"223.75633ms","start":"2026-05-01T22:27:37.418845Z","end":"2026-05-01T22:27:37.642601Z","steps":["trace[504880397] 'agreement among raft nodes before linearized reading' (duration: 223.607967ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T22:27:37.642717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.999749ms","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-01T22:27:37.642752Z","caller":"traceutil/trace.go:171","msg":"trace[1175603528] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1142; }","duration":"214.06581ms","start":"2026-05-01T22:27:37.428674Z","end":"2026-05-01T22:27:37.642739Z","steps":["trace[1175603528] 'agreement among raft nodes before linearized reading' (duration: 213.930246ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T22:27:37.876292Z","caller":"traceutil/trace.go:171","msg":"trace[1535040931] transaction","detail":"{read_only:false; response_revision:1146; number_of_response:1; }","duration":"115.702516ms","start":"2026-05-01T22:27:37.760565Z","end":"2026-05-01T22:27:37.876267Z","steps":["trace[1535040931] 'process raft request' (duration: 76.679227ms)","trace[1535040931] 'compare' (duration: 38.916247ms)"],"step_count":2} {"level":"info","ts":"2026-05-01T22:27:38.495484Z","caller":"traceutil/trace.go:171","msg":"trace[1019814218] transaction","detail":"{read_only:false; response_revision:1148; number_of_response:1; }","duration":"126.86419ms","start":"2026-05-01T22:27:38.368596Z","end":"2026-05-01T22:27:38.495461Z","steps":["trace[1019814218] 'process raft request' (duration: 126.507782ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T22:27:39.916697Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14208186009074000624,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-01T22:27:40.349735Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.222460481s","expected-duration":"1s"} {"level":"info","ts":"2026-05-01T22:27:40.350099Z","caller":"traceutil/trace.go:171","msg":"trace[294695685] transaction","detail":"{read_only:false; response_revision:1149; number_of_response:1; }","duration":"1.222907901s","start":"2026-05-01T22:27:39.127168Z","end":"2026-05-01T22:27:40.350076Z","steps":["trace[294695685] 'process raft request' (duration: 1.222760358s)"],"step_count":1} {"level":"warn","ts":"2026-05-01T22:27:40.350248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T22:27:39.127144Z","time spent":"1.223026305s","remote":"127.0.0.1:38860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-01T22:27:40.44856Z","caller":"traceutil/trace.go:171","msg":"trace[1297205303] linearizableReadLoop","detail":"{readStateIndex:1194; appliedIndex:1192; }","duration":"1.032567028s","start":"2026-05-01T22:27:39.415972Z","end":"2026-05-01T22:27:40.448539Z","steps":["trace[1297205303] 'read index received' (duration: 934.027931ms)","trace[1297205303] 'applied index is now lower than readState.Index' (duration: 98.537937ms)"],"step_count":2} {"level":"info","ts":"2026-05-01T22:27:40.448662Z","caller":"traceutil/trace.go:171","msg":"trace[221966514] transaction","detail":"{read_only:false; response_revision:1150; number_of_response:1; }","duration":"645.988899ms","start":"2026-05-01T22:27:39.802661Z","end":"2026-05-01T22:27:40.44865Z","steps":["trace[221966514] 'process raft request' (duration: 592.192947ms)","trace[221966514] 'compare' (duration: 53.591497ms)"],"step_count":2} {"level":"warn","ts":"2026-05-01T22:27:40.448752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T22:27:39.802638Z","time spent":"646.04648ms","remote":"127.0.0.1:38860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-01T22:27:40.448853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"954.144469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-01T22:27:40.448907Z","caller":"traceutil/trace.go:171","msg":"trace[1500854047] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1150; }","duration":"954.253152ms","start":"2026-05-01T22:27:39.49464Z","end":"2026-05-01T22:27:40.448893Z","steps":["trace[1500854047] 'agreement among raft nodes before linearized reading' (duration: 954.073178ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T22:27:40.448974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T22:27:39.494624Z","time spent":"954.340564ms","remote":"127.0.0.1:38620","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-01T22:27:40.449086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"792.160211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-01T22:27:40.449116Z","caller":"traceutil/trace.go:171","msg":"trace[441020573] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1150; }","duration":"792.188442ms","start":"2026-05-01T22:27:39.656919Z","end":"2026-05-01T22:27:40.449107Z","steps":["trace[441020573] 'agreement among raft nodes before linearized reading' (duration: 792.10277ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T22:27:40.449135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T22:27:39.656866Z","time spent":"792.263493ms","remote":"127.0.0.1:38860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-01T22:27:40.449243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"797.602901ms","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-01T22:27:40.44928Z","caller":"traceutil/trace.go:171","msg":"trace[2000769060] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1150; }","duration":"797.660411ms","start":"2026-05-01T22:27:39.65161Z","end":"2026-05-01T22:27:40.449271Z","steps":["trace[2000769060] 'agreement among raft nodes before linearized reading' (duration: 797.559909ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T22:27:40.44931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T22:27:39.651573Z","time spent":"797.731313ms","remote":"127.0.0.1:38860","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-01T22:27:40.449252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.033280225s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-01T22:27:40.449364Z","caller":"traceutil/trace.go:171","msg":"trace[1061627013] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:1150; }","duration":"1.033424438s","start":"2026-05-01T22:27:39.415931Z","end":"2026-05-01T22:27:40.449355Z","steps":["trace[1061627013] 'agreement among raft nodes before linearized reading' (duration: 1.033293325s)"],"step_count":1} {"level":"warn","ts":"2026-05-01T22:27:40.449391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T22:27:39.415915Z","time spent":"1.033467799s","remote":"127.0.0.1:58996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true "} {"level":"warn","ts":"2026-05-01T22:27:40.449516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"802.750202ms","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-01T22:27:40.449598Z","caller":"traceutil/trace.go:171","msg":"trace[693407130] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1150; }","duration":"802.890196ms","start":"2026-05-01T22:27:39.646688Z","end":"2026-05-01T22:27:40.449578Z","steps":["trace[693407130] 'agreement among raft nodes before linearized reading' (duration: 802.63175ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T22:27:40.44965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T22:27:39.646666Z","time spent":"802.974098ms","remote":"127.0.0.1:38860","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-01T22:27:52.658611Z","caller":"traceutil/trace.go:171","msg":"trace[1715651475] linearizableReadLoop","detail":"{readStateIndex:1235; appliedIndex:1234; }","duration":"235.668384ms","start":"2026-05-01T22:27:52.422885Z","end":"2026-05-01T22:27:52.658554Z","steps":["trace[1715651475] 'read index received' (duration: 235.527161ms)","trace[1715651475] 'applied index is now lower than readState.Index' (duration: 139.943µs)"],"step_count":2} {"level":"warn","ts":"2026-05-01T22:27:52.659114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.197247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-01T22:27:52.65919Z","caller":"traceutil/trace.go:171","msg":"trace[1472011660] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:1189; }","duration":"236.306499ms","start":"2026-05-01T22:27:52.422865Z","end":"2026-05-01T22:27:52.659171Z","steps":["trace[1472011660] 'agreement among raft nodes before linearized reading' (duration: 236.167006ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T22:27:52.659069Z","caller":"traceutil/trace.go:171","msg":"trace[1068703480] transaction","detail":"{read_only:false; response_revision:1189; number_of_response:1; }","duration":"258.755674ms","start":"2026-05-01T22:27:52.400297Z","end":"2026-05-01T22:27:52.659053Z","steps":["trace[1068703480] 'process raft request' (duration: 258.055867ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T22:27:52.751404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.294896ms","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":"info","ts":"2026-05-01T22:27:52.751417Z","caller":"traceutil/trace.go:171","msg":"trace[1848759655] transaction","detail":"{read_only:false; response_revision:1190; number_of_response:1; }","duration":"254.233846ms","start":"2026-05-01T22:27:52.497152Z","end":"2026-05-01T22:27:52.751386Z","steps":["trace[1848759655] 'process raft request' (duration: 187.555858ms)","trace[1848759655] 'compare' (duration: 66.483893ms)"],"step_count":2} {"level":"info","ts":"2026-05-01T22:27:52.751478Z","caller":"traceutil/trace.go:171","msg":"trace[2099962146] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:1190; }","duration":"267.387738ms","start":"2026-05-01T22:27:52.484074Z","end":"2026-05-01T22:27:52.751461Z","steps":["trace[2099962146] 'agreement among raft nodes before linearized reading' (duration: 267.236585ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T22:27:52.751555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.41105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-01T22:27:52.751567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.00599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-01T22:27:52.751666Z","caller":"traceutil/trace.go:171","msg":"trace[1529961834] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1190; }","duration":"207.153424ms","start":"2026-05-01T22:27:52.544494Z","end":"2026-05-01T22:27:52.751647Z","steps":["trace[1529961834] 'agreement among raft nodes before linearized reading' (duration: 206.916668ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T22:27:52.751615Z","caller":"traceutil/trace.go:171","msg":"trace[2125170460] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1190; }","duration":"249.525233ms","start":"2026-05-01T22:27:52.502079Z","end":"2026-05-01T22:27:52.751605Z","steps":["trace[2125170460] 'agreement among raft nodes before linearized reading' (duration: 249.319158ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T22:27:52.752195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.545054ms","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-01T22:27:52.752264Z","caller":"traceutil/trace.go:171","msg":"trace[579052060] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1190; }","duration":"236.635526ms","start":"2026-05-01T22:27:52.515617Z","end":"2026-05-01T22:27:52.752252Z","steps":["trace[579052060] 'agreement among raft nodes before linearized reading' (duration: 235.715304ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T22:27:52.755831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.034719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-01T22:27:52.755906Z","caller":"traceutil/trace.go:171","msg":"trace[1598566875] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1190; }","duration":"243.155261ms","start":"2026-05-01T22:27:52.512739Z","end":"2026-05-01T22:27:52.755895Z","steps":["trace[1598566875] 'agreement among raft nodes before linearized reading' (duration: 238.612283ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T22:27:53.603214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"502.596993ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-01T22:27:53.603935Z","caller":"traceutil/trace.go:171","msg":"trace[293174942] transaction","detail":"{read_only:false; response_revision:1192; number_of_response:1; }","duration":"847.758095ms","start":"2026-05-01T22:27:52.756125Z","end":"2026-05-01T22:27:53.603883Z","steps":["trace[293174942] 'process raft request' (duration: 344.250781ms)","trace[293174942] 'compare' (duration: 501.905516ms)"],"step_count":2} {"level":"warn","ts":"2026-05-01T22:27:53.604109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T22:27:52.756119Z","time spent":"847.898559ms","remote":"127.0.0.1:38860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-01T22:27:53.604196Z","caller":"traceutil/trace.go:171","msg":"trace[1442117445] transaction","detail":"{read_only:false; response_revision:1193; number_of_response:1; }","duration":"848.017351ms","start":"2026-05-01T22:27:52.756156Z","end":"2026-05-01T22:27:53.604173Z","steps":["trace[1442117445] 'process raft request' (duration: 847.145211ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T22:27:53.604491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T22:27:52.756137Z","time spent":"848.293037ms","remote":"127.0.0.1:38860","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-01T22:27:53.604531Z","caller":"traceutil/trace.go:171","msg":"trace[1630865777] transaction","detail":"{read_only:false; response_revision:1194; number_of_response:1; }","duration":"845.623194ms","start":"2026-05-01T22:27:52.758877Z","end":"2026-05-01T22:27:53.6045Z","steps":["trace[1630865777] 'process raft request' (duration: 844.486967ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T22:27:53.604656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T22:27:52.758866Z","time spent":"845.724846ms","remote":"127.0.0.1:38860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-01T22:27:53.963099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.638387ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-05-01T22:27:53.96337Z","caller":"traceutil/trace.go:171","msg":"trace[1289540177] linearizableReadLoop","detail":"{readStateIndex:1241; appliedIndex:1237; }","duration":"609.56079ms","start":"2026-05-01T22:27:53.353787Z","end":"2026-05-01T22:27:53.963347Z","steps":["trace[1289540177] 'read index received' (duration: 152.118783ms)","trace[1289540177] 'applied index is now lower than readState.Index' (duration: 457.439297ms)"],"step_count":2} {"level":"warn","ts":"2026-05-01T22:27:53.963499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"609.666553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-01T22:27:53.963532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"608.870824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-01T22:27:53.963531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"462.078907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-01T22:27:53.963544Z","caller":"traceutil/trace.go:171","msg":"trace[2140671732] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1194; }","duration":"609.769026ms","start":"2026-05-01T22:27:53.353758Z","end":"2026-05-01T22:27:53.963527Z","steps":["trace[2140671732] 'agreement among raft nodes before linearized reading' (duration: 609.656733ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T22:27:53.963561Z","caller":"traceutil/trace.go:171","msg":"trace[1898972080] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:1194; }","duration":"608.922705ms","start":"2026-05-01T22:27:53.35463Z","end":"2026-05-01T22:27:53.963553Z","steps":["trace[1898972080] 'agreement among raft nodes before linearized reading' (duration: 608.837363ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T22:27:53.963568Z","caller":"traceutil/trace.go:171","msg":"trace[1874169538] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1194; }","duration":"462.143228ms","start":"2026-05-01T22:27:53.501413Z","end":"2026-05-01T22:27:53.963556Z","steps":["trace[1874169538] 'agreement among raft nodes before linearized reading' (duration: 462.063946ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T22:27:53.963609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T22:27:53.5014Z","time spent":"462.204181ms","remote":"127.0.0.1:38622","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-01T22:27:53.963609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T22:27:53.354625Z","time spent":"608.955836ms","remote":"127.0.0.1:39140","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":29,"response size":31,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true "} {"level":"warn","ts":"2026-05-01T22:27:53.963608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T22:27:53.353746Z","time spent":"609.830747ms","remote":"127.0.0.1:38620","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-01T22:27:55.040711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.701244ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-01T22:27:55.040797Z","caller":"traceutil/trace.go:171","msg":"trace[347537634] linearizableReadLoop","detail":"{readStateIndex:1244; appliedIndex:1243; }","duration":"265.541946ms","start":"2026-05-01T22:27:54.775242Z","end":"2026-05-01T22:27:55.040784Z","steps":["trace[347537634] 'read index received' (duration: 39.851µs)","trace[347537634] 'applied index is now lower than readState.Index' (duration: 265.500995ms)"],"step_count":2} {"level":"warn","ts":"2026-05-01T22:27:55.040953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.71763ms","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-01T22:27:55.040976Z","caller":"traceutil/trace.go:171","msg":"trace[74413638] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1197; }","duration":"265.780081ms","start":"2026-05-01T22:27:54.77519Z","end":"2026-05-01T22:27:55.04097Z","steps":["trace[74413638] 'agreement among raft nodes before linearized reading' (duration: 265.641378ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T22:27:55.041267Z","caller":"traceutil/trace.go:171","msg":"trace[1846267330] transaction","detail":"{read_only:false; response_revision:1197; number_of_response:1; }","duration":"284.306743ms","start":"2026-05-01T22:27:54.756934Z","end":"2026-05-01T22:27:55.041241Z","steps":["trace[1846267330] 'process raft request' (duration: 14.010264ms)","trace[1846267330] 'compare' (duration: 269.594682ms)"],"step_count":2} {"level":"info","ts":"2026-05-01T22:27:56.026231Z","caller":"traceutil/trace.go:171","msg":"trace[1557533745] transaction","detail":"{read_only:false; response_revision:1199; number_of_response:1; }","duration":"412.103427ms","start":"2026-05-01T22:27:55.614096Z","end":"2026-05-01T22:27:56.026199Z","steps":["trace[1557533745] 'process raft request' (duration: 411.897732ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T22:27:56.026416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T22:27:55.614082Z","time spent":"412.23898ms","remote":"127.0.0.1:38860","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-01T22:27:56.154739Z","caller":"traceutil/trace.go:171","msg":"trace[1997488796] transaction","detail":"{read_only:false; response_revision:1201; number_of_response:1; }","duration":"538.328873ms","start":"2026-05-01T22:27:55.616384Z","end":"2026-05-01T22:27:56.154713Z","steps":["trace[1997488796] 'process raft request' (duration: 538.282352ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T22:27:56.154893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T22:27:55.616377Z","time spent":"538.434277ms","remote":"127.0.0.1:38860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-01T22:27:56.155318Z","caller":"traceutil/trace.go:171","msg":"trace[1158741837] transaction","detail":"{read_only:false; response_revision:1200; number_of_response:1; }","duration":"541.134111ms","start":"2026-05-01T22:27:55.61417Z","end":"2026-05-01T22:27:56.155305Z","steps":["trace[1158741837] 'process raft request' (duration: 540.351372ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T22:27:56.15539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T22:27:55.614147Z","time spent":"541.207053ms","remote":"127.0.0.1:38860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-01T22:27:56.589506Z","caller":"traceutil/trace.go:171","msg":"trace[1966966656] linearizableReadLoop","detail":"{readStateIndex:1251; appliedIndex:1250; }","duration":"107.685485ms","start":"2026-05-01T22:27:56.481802Z","end":"2026-05-01T22:27:56.589487Z","steps":["trace[1966966656] 'read index received' (duration: 107.592813ms)","trace[1966966656] 'applied index is now lower than readState.Index' (duration: 91.612µs)"],"step_count":2} {"level":"info","ts":"2026-05-01T22:27:56.589571Z","caller":"traceutil/trace.go:171","msg":"trace[1352893032] transaction","detail":"{read_only:false; response_revision:1204; number_of_response:1; }","duration":"153.480326ms","start":"2026-05-01T22:27:56.436045Z","end":"2026-05-01T22:27:56.589525Z","steps":["trace[1352893032] 'process raft request' (duration: 153.333123ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T22:27:56.589711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.88747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-01T22:27:56.589797Z","caller":"traceutil/trace.go:171","msg":"trace[1237224244] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:1204; }","duration":"107.982212ms","start":"2026-05-01T22:27:56.481795Z","end":"2026-05-01T22:27:56.589777Z","steps":["trace[1237224244] 'agreement among raft nodes before linearized reading' (duration: 107.800558ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T22:27:57.292167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.503697ms","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-01T22:27:57.292255Z","caller":"traceutil/trace.go:171","msg":"trace[640308954] 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:1205; }","duration":"365.63721ms","start":"2026-05-01T22:27:56.926595Z","end":"2026-05-01T22:27:57.292232Z","steps":["trace[640308954] 'count revisions from in-memory index tree' (duration: 365.423455ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T22:27:57.292299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T22:27:56.926574Z","time spent":"365.713392ms","remote":"127.0.0.1:59100","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-01T22:27:57.552362Z","caller":"traceutil/trace.go:171","msg":"trace[1792858933] linearizableReadLoop","detail":"{readStateIndex:1253; appliedIndex:1252; }","duration":"409.327821ms","start":"2026-05-01T22:27:57.143016Z","end":"2026-05-01T22:27:57.552344Z","steps":["trace[1792858933] 'read index received' (duration: 408.632795ms)","trace[1792858933] 'applied index is now lower than readState.Index' (duration: 694.406µs)"],"step_count":2} {"level":"info","ts":"2026-05-01T22:27:57.552407Z","caller":"traceutil/trace.go:171","msg":"trace[1134056108] transaction","detail":"{read_only:false; response_revision:1206; number_of_response:1; }","duration":"506.68327ms","start":"2026-05-01T22:27:57.045707Z","end":"2026-05-01T22:27:57.55239Z","steps":["trace[1134056108] 'process raft request' (duration: 505.991994ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T22:27:57.552492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T22:27:57.045688Z","time spent":"506.743951ms","remote":"127.0.0.1:38860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-01T22:27:57.552502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.510895ms","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-01T22:27:57.552524Z","caller":"traceutil/trace.go:171","msg":"trace[1061772306] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1206; }","duration":"409.595837ms","start":"2026-05-01T22:27:57.142921Z","end":"2026-05-01T22:27:57.552517Z","steps":["trace[1061772306] 'agreement among raft nodes before linearized reading' (duration: 409.508395ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T22:27:57.552542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T22:27:57.14285Z","time spent":"409.68779ms","remote":"127.0.0.1:38860","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-01T22:27:57.732505Z","caller":"traceutil/trace.go:171","msg":"trace[4430309] transaction","detail":"{read_only:false; response_revision:1207; number_of_response:1; }","duration":"175.656844ms","start":"2026-05-01T22:27:57.55682Z","end":"2026-05-01T22:27:57.732477Z","steps":["trace[4430309] 'process raft request' (duration: 135.893997ms)","trace[4430309] 'compare' (duration: 39.565953ms)"],"step_count":2} {"level":"warn","ts":"2026-05-01T22:27:58.153019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.020506ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-01T22:27:58.153132Z","caller":"traceutil/trace.go:171","msg":"trace[1771808174] linearizableReadLoop","detail":"{readStateIndex:1257; appliedIndex:1256; }","duration":"143.378136ms","start":"2026-05-01T22:27:58.009738Z","end":"2026-05-01T22:27:58.153116Z","steps":["trace[1771808174] 'read index received' (duration: 33.521µs)","trace[1771808174] 'applied index is now lower than readState.Index' (duration: 143.342915ms)"],"step_count":2} {"level":"info","ts":"2026-05-01T22:27:58.153175Z","caller":"traceutil/trace.go:171","msg":"trace[1287091337] transaction","detail":"{read_only:false; response_revision:1209; number_of_response:1; }","duration":"391.560067ms","start":"2026-05-01T22:27:57.761582Z","end":"2026-05-01T22:27:58.153142Z","steps":["trace[1287091337] 'process raft request' (duration: 230.327416ms)","trace[1287091337] 'compare' (duration: 160.879913ms)"],"step_count":2} {"level":"warn","ts":"2026-05-01T22:27:58.153342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.575164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-01T22:27:58.153353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T22:27:57.761568Z","time spent":"391.695491ms","remote":"127.0.0.1:38626","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-01T22:27:58.153381Z","caller":"traceutil/trace.go:171","msg":"trace[330020342] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1209; }","duration":"123.645946ms","start":"2026-05-01T22:27:58.029725Z","end":"2026-05-01T22:27:58.153371Z","steps":["trace[330020342] 'agreement among raft nodes before linearized reading' (duration: 123.482252ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T22:27:58.153433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.58344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-01T22:27:58.153565Z","caller":"traceutil/trace.go:171","msg":"trace[270905725] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:1209; }","duration":"143.868187ms","start":"2026-05-01T22:27:58.009683Z","end":"2026-05-01T22:27:58.153551Z","steps":["trace[270905725] 'agreement among raft nodes before linearized reading' (duration: 143.499718ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T22:27:58.588167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.63299ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-01T22:27:58.588251Z","caller":"traceutil/trace.go:171","msg":"trace[1023420367] linearizableReadLoop","detail":"{readStateIndex:1258; appliedIndex:1257; }","duration":"428.147289ms","start":"2026-05-01T22:27:58.160089Z","end":"2026-05-01T22:27:58.588237Z","steps":["trace[1023420367] 'read index received' (duration: 117.253793ms)","trace[1023420367] 'applied index is now lower than readState.Index' (duration: 310.892236ms)"],"step_count":2} {"level":"info","ts":"2026-05-01T22:27:58.588405Z","caller":"traceutil/trace.go:171","msg":"trace[1461755542] transaction","detail":"{read_only:false; response_revision:1210; number_of_response:1; }","duration":"429.344418ms","start":"2026-05-01T22:27:58.159051Z","end":"2026-05-01T22:27:58.588396Z","steps":["trace[1461755542] 'process raft request' (duration: 118.38916ms)","trace[1461755542] 'compare' (duration: 310.378894ms)"],"step_count":2} {"level":"warn","ts":"2026-05-01T22:27:58.588448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T22:27:58.159031Z","time spent":"429.3948ms","remote":"127.0.0.1:38860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-01T22:27:58.588775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.656821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-05-01T22:27:58.588862Z","caller":"traceutil/trace.go:171","msg":"trace[1091971834] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:1210; }","duration":"428.784105ms","start":"2026-05-01T22:27:58.160057Z","end":"2026-05-01T22:27:58.588841Z","steps":["trace[1091971834] 'agreement among raft nodes before linearized reading' (duration: 428.61145ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T22:27:58.588904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T22:27:58.160047Z","time spent":"428.849546ms","remote":"127.0.0.1:38876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/endpointslices/default/kubernetes\" "} {"level":"warn","ts":"2026-05-01T22:27:58.681198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"520.991131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-01T22:27:58.681288Z","caller":"traceutil/trace.go:171","msg":"trace[2101201879] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1211; }","duration":"521.115504ms","start":"2026-05-01T22:27:58.160154Z","end":"2026-05-01T22:27:58.681269Z","steps":["trace[2101201879] 'agreement among raft nodes before linearized reading' (duration: 520.865218ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T22:27:58.681337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T22:27:58.160145Z","time spent":"521.181086ms","remote":"127.0.0.1:38860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-01T22:27:58.68167Z","caller":"traceutil/trace.go:171","msg":"trace[914756297] transaction","detail":"{read_only:false; response_revision:1211; number_of_response:1; }","duration":"315.449964ms","start":"2026-05-01T22:27:58.366207Z","end":"2026-05-01T22:27:58.681657Z","steps":["trace[914756297] 'process raft request' (duration: 314.666916ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T22:27:58.681792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T22:27:58.366183Z","time spent":"315.521217ms","remote":"127.0.0.1:38860","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-01T22:27:58.681966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.446203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-01T22:27:58.682004Z","caller":"traceutil/trace.go:171","msg":"trace[400692658] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:1211; }","duration":"100.519995ms","start":"2026-05-01T22:27:58.581473Z","end":"2026-05-01T22:27:58.681993Z","steps":["trace[400692658] 'agreement among raft nodes before linearized reading' (duration: 100.457523ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T22:27:58.682166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.398997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-01T22:27:58.682198Z","caller":"traceutil/trace.go:171","msg":"trace[1947971572] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1211; }","duration":"180.479729ms","start":"2026-05-01T22:27:58.50171Z","end":"2026-05-01T22:27:58.68219Z","steps":["trace[1947971572] 'agreement among raft nodes before linearized reading' (duration: 180.428558ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T22:27:58.68289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"522.649311ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-01T22:27:58.682943Z","caller":"traceutil/trace.go:171","msg":"trace[552720828] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1211; }","duration":"522.732443ms","start":"2026-05-01T22:27:58.160198Z","end":"2026-05-01T22:27:58.68293Z","steps":["trace[552720828] 'agreement among raft nodes before linearized reading' (duration: 522.556829ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T22:27:58.683094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T22:27:58.160185Z","time spent":"522.870506ms","remote":"127.0.0.1:38860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-01T22:27:59.940611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.591351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-8t5g2\" ","response":"range_response_count:1 size:11061"} {"level":"info","ts":"2026-05-01T22:27:59.940699Z","caller":"traceutil/trace.go:171","msg":"trace[905369086] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-8t5g2; range_end:; response_count:1; response_revision:1227; }","duration":"125.717513ms","start":"2026-05-01T22:27:59.814961Z","end":"2026-05-01T22:27:59.940679Z","steps":["trace[905369086] 'range keys from in-memory index tree' (duration: 125.380596ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T22:28:08.069958Z","caller":"traceutil/trace.go:171","msg":"trace[1693373611] linearizableReadLoop","detail":"{readStateIndex:1348; appliedIndex:1347; }","duration":"364.680947ms","start":"2026-05-01T22:28:07.705247Z","end":"2026-05-01T22:28:08.069928Z","steps":["trace[1693373611] 'read index received' (duration: 364.426141ms)","trace[1693373611] 'applied index is now lower than readState.Index' (duration: 253.446µs)"],"step_count":2} {"level":"info","ts":"2026-05-01T22:28:08.070451Z","caller":"traceutil/trace.go:171","msg":"trace[488152936] transaction","detail":"{read_only:false; response_revision:1299; number_of_response:1; }","duration":"487.695077ms","start":"2026-05-01T22:28:07.58272Z","end":"2026-05-01T22:28:08.070415Z","steps":["trace[488152936] 'process raft request' (duration: 487.014571ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T22:28:08.070557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.977911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-01T22:28:08.070643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T22:28:07.582699Z","time spent":"487.84118ms","remote":"127.0.0.1:38860","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-01T22:28:08.070661Z","caller":"traceutil/trace.go:171","msg":"trace[1709159059] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1299; }","duration":"296.145055ms","start":"2026-05-01T22:28:07.77449Z","end":"2026-05-01T22:28:08.070635Z","steps":["trace[1709159059] 'agreement among raft nodes before linearized reading' (duration: 295.924089ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T22:28:08.07109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.766613ms","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-01T22:28:08.071172Z","caller":"traceutil/trace.go:171","msg":"trace[1319191805] range","detail":"{range_begin:/registry/masterleases/199.204.45.250; range_end:; response_count:1; response_revision:1299; }","duration":"365.964478ms","start":"2026-05-01T22:28:07.705194Z","end":"2026-05-01T22:28:08.071158Z","steps":["trace[1319191805] 'agreement among raft nodes before linearized reading' (duration: 364.969974ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T22:28:08.07124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-01T22:28:07.705173Z","time spent":"366.05607ms","remote":"127.0.0.1:38626","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-01T22:29:50.967618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.997293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-05-01T22:29:50.967722Z","caller":"traceutil/trace.go:171","msg":"trace[1171522724] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1771; }","duration":"127.184876ms","start":"2026-05-01T22:29:50.840519Z","end":"2026-05-01T22:29:50.967704Z","steps":["trace[1171522724] 'range keys from in-memory index tree' (duration: 126.786787ms)"],"step_count":1} {"level":"info","ts":"2026-05-01T22:29:51.63445Z","caller":"traceutil/trace.go:171","msg":"trace[1176642479] transaction","detail":"{read_only:false; response_revision:1777; number_of_response:1; }","duration":"119.747741ms","start":"2026-05-01T22:29:51.514668Z","end":"2026-05-01T22:29:51.634416Z","steps":["trace[1176642479] 'process raft request' (duration: 74.628945ms)","trace[1176642479] 'compare' (duration: 44.915731ms)"],"step_count":2} {"level":"info","ts":"2026-05-01T22:29:53.514094Z","caller":"traceutil/trace.go:171","msg":"trace[431535222] transaction","detail":"{read_only:false; response_revision:1782; number_of_response:1; }","duration":"180.717122ms","start":"2026-05-01T22:29:53.333344Z","end":"2026-05-01T22:29:53.514061Z","steps":["trace[431535222] 'process raft request' (duration: 180.376534ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T22:31:24.417909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.765084ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-01T22:31:24.418106Z","caller":"traceutil/trace.go:171","msg":"trace[758488751] linearizableReadLoop","detail":"{readStateIndex:2322; appliedIndex:2320; }","duration":"151.614276ms","start":"2026-05-01T22:31:24.266475Z","end":"2026-05-01T22:31:24.418089Z","steps":["trace[758488751] 'read index received' (duration: 25.590276ms)","trace[758488751] 'applied index is now lower than readState.Index' (duration: 126.02283ms)"],"step_count":2} {"level":"info","ts":"2026-05-01T22:31:24.41821Z","caller":"traceutil/trace.go:171","msg":"trace[1457239533] transaction","detail":"{read_only:false; response_revision:2231; number_of_response:1; }","duration":"220.186827ms","start":"2026-05-01T22:31:24.198007Z","end":"2026-05-01T22:31:24.418194Z","steps":["trace[1457239533] 'process raft request' (duration: 94.041894ms)","trace[1457239533] 'compare' (duration: 125.641671ms)"],"step_count":2} {"level":"info","ts":"2026-05-01T22:31:24.418563Z","caller":"traceutil/trace.go:171","msg":"trace[1553469266] transaction","detail":"{read_only:false; response_revision:2232; number_of_response:1; }","duration":"219.848919ms","start":"2026-05-01T22:31:24.198677Z","end":"2026-05-01T22:31:24.418526Z","steps":["trace[1553469266] 'process raft request' (duration: 219.353977ms)"],"step_count":1} {"level":"warn","ts":"2026-05-01T22:31:24.420094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.607352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-05-01T22:31:24.420523Z","caller":"traceutil/trace.go:171","msg":"trace[1424473915] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2232; }","duration":"154.087564ms","start":"2026-05-01T22:31:24.266408Z","end":"2026-05-01T22:31:24.420495Z","steps":["trace[1424473915] 'agreement among raft nodes before linearized reading' (duration: 152.026545ms)"],"step_count":1}