{"level":"warn","ts":"2026-04-16T16:29:03.731908Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-16T16:29:03.732034Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.238: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.238:2380","--initial-cluster=instance=https://199.204.45.238:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.238:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.238: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-04-16T16:29:03.732189Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-16T16:29:03.732207Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.238:2380"]} {"level":"info","ts":"2026-04-16T16:29:03.732232Z","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-04-16T16:29:03.733128Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.238:2379"]} {"level":"info","ts":"2026-04-16T16:29:03.733228Z","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.238:2380"],"listen-peer-urls":["https://199.204.45.238:2380"],"advertise-client-urls":["https://199.204.45.238:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.238:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.238: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-04-16T16:29:03.738564Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.069905ms"} {"level":"info","ts":"2026-04-16T16:29:03.746274Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d7dbc9f5893cc1f6","cluster-id":"823d1d08ab21430c"} {"level":"info","ts":"2026-04-16T16:29:03.746535Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 switched to configuration voters=()"} {"level":"info","ts":"2026-04-16T16:29:03.746631Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 became follower at term 0"} {"level":"info","ts":"2026-04-16T16:29:03.746654Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d7dbc9f5893cc1f6 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-16T16:29:03.746725Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 became follower at term 1"} {"level":"info","ts":"2026-04-16T16:29:03.746896Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 switched to configuration voters=(15554247794460770806)"} {"level":"warn","ts":"2026-04-16T16:29:03.754293Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-16T16:29:03.756295Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-16T16:29:03.757462Z","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-04-16T16:29:03.7598Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d7dbc9f5893cc1f6","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-16T16:29:03.760263Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d7dbc9f5893cc1f6","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-16T16:29:03.760394Z","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-04-16T16:29:03.760444Z","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-04-16T16:29:03.760454Z","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-04-16T16:29:03.765844Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 switched to configuration voters=(15554247794460770806)"} {"level":"info","ts":"2026-04-16T16:29:03.766289Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"823d1d08ab21430c","local-member-id":"d7dbc9f5893cc1f6","added-peer-id":"d7dbc9f5893cc1f6","added-peer-peer-urls":["https://199.204.45.238:2380"]} {"level":"info","ts":"2026-04-16T16:29:03.774454Z","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-04-16T16:29:03.774646Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.238:2380"} {"level":"info","ts":"2026-04-16T16:29:03.774684Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.238:2380"} {"level":"info","ts":"2026-04-16T16:29:03.77912Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d7dbc9f5893cc1f6","initial-advertise-peer-urls":["https://199.204.45.238:2380"],"listen-peer-urls":["https://199.204.45.238:2380"],"advertise-client-urls":["https://199.204.45.238:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.238:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-16T16:29:03.779174Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-16T16:29:04.247726Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 is starting a new election at term 1"} {"level":"info","ts":"2026-04-16T16:29:04.247796Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-16T16:29:04.247824Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 received MsgPreVoteResp from d7dbc9f5893cc1f6 at term 1"} {"level":"info","ts":"2026-04-16T16:29:04.247836Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 became candidate at term 2"} {"level":"info","ts":"2026-04-16T16:29:04.247847Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 received MsgVoteResp from d7dbc9f5893cc1f6 at term 2"} {"level":"info","ts":"2026-04-16T16:29:04.247855Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 became leader at term 2"} {"level":"info","ts":"2026-04-16T16:29:04.247862Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d7dbc9f5893cc1f6 elected leader d7dbc9f5893cc1f6 at term 2"} {"level":"info","ts":"2026-04-16T16:29:04.248852Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-16T16:29:04.2499Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d7dbc9f5893cc1f6","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.238:2379]}","request-path":"/0/members/d7dbc9f5893cc1f6/attributes","cluster-id":"823d1d08ab21430c","publish-timeout":"7s"} {"level":"info","ts":"2026-04-16T16:29:04.250012Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-16T16:29:04.250031Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-16T16:29:04.250487Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"823d1d08ab21430c","local-member-id":"d7dbc9f5893cc1f6","cluster-version":"3.5"} {"level":"info","ts":"2026-04-16T16:29:04.250616Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-16T16:29:04.250657Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-16T16:29:04.252691Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.238:2379"} {"level":"info","ts":"2026-04-16T16:29:04.25339Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-16T16:29:04.253765Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-16T16:29:04.253832Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-16T16:29:26.528201Z","caller":"traceutil/trace.go:171","msg":"trace[1169711302] transaction","detail":"{read_only:false; response_revision:422; number_of_response:1; }","duration":"438.111667ms","start":"2026-04-16T16:29:26.09004Z","end":"2026-04-16T16:29:26.528152Z","steps":["trace[1169711302] 'process raft request' (duration: 437.915242ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:29:26.529548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:29:26.090022Z","time spent":"438.279241ms","remote":"127.0.0.1:60790","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T16:29:36.913033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.121065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:29:36.913155Z","caller":"traceutil/trace.go:171","msg":"trace[372455032] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:478; }","duration":"325.375841ms","start":"2026-04-16T16:29:36.587759Z","end":"2026-04-16T16:29:36.913135Z","steps":["trace[372455032] 'range keys from in-memory index tree' (duration: 325.020602ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:29:36.913199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:29:36.587719Z","time spent":"325.468853ms","remote":"127.0.0.1:60590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":29,"request content":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" "} {"level":"info","ts":"2026-04-16T16:29:36.913228Z","caller":"traceutil/trace.go:171","msg":"trace[1692269940] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"300.710944ms","start":"2026-04-16T16:29:36.612411Z","end":"2026-04-16T16:29:36.913121Z","steps":["trace[1692269940] 'process raft request' (duration: 242.983862ms)","trace[1692269940] 'compare' (duration: 57.214509ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T16:29:36.913376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:29:36.612389Z","time spent":"300.904319ms","remote":"127.0.0.1:60790","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-04-16T16:29:37.240083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.923501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:29:37.240199Z","caller":"traceutil/trace.go:171","msg":"trace[1158830032] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:479; }","duration":"204.085775ms","start":"2026-04-16T16:29:37.036089Z","end":"2026-04-16T16:29:37.240175Z","steps":["trace[1158830032] 'range keys from in-memory index tree' (duration: 203.834749ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:29:38.20567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.392067ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-16T16:29:38.205969Z","caller":"traceutil/trace.go:171","msg":"trace[1379114986] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"728.254351ms","start":"2026-04-16T16:29:37.47768Z","end":"2026-04-16T16:29:38.205934Z","steps":["trace[1379114986] 'process raft request' (duration: 429.365652ms)","trace[1379114986] 'compare' (duration: 298.093989ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T16:29:38.206058Z","caller":"traceutil/trace.go:171","msg":"trace[1460573562] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"728.125638ms","start":"2026-04-16T16:29:37.477894Z","end":"2026-04-16T16:29:38.206019Z","steps":["trace[1460573562] 'process raft request' (duration: 727.914683ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:29:38.206147Z","caller":"traceutil/trace.go:171","msg":"trace[290035551] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"721.888754ms","start":"2026-04-16T16:29:37.484249Z","end":"2026-04-16T16:29:38.206138Z","steps":["trace[290035551] 'process raft request' (duration: 721.738571ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:29:38.206171Z","caller":"traceutil/trace.go:171","msg":"trace[481470107] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"721.761201ms","start":"2026-04-16T16:29:37.484292Z","end":"2026-04-16T16:29:38.206053Z","steps":["trace[481470107] 'process raft request' (duration: 721.72375ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:29:38.206201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:29:37.484232Z","time spent":"721.951166ms","remote":"127.0.0.1:32788","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1004,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-16T16:29:38.206277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:29:37.477885Z","time spent":"728.302563ms","remote":"127.0.0.1:60536","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-04-16T16:29:38.206096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:29:37.477653Z","time spent":"728.384255ms","remote":"127.0.0.1:60536","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-04-16T16:29:38.206379Z","caller":"traceutil/trace.go:171","msg":"trace[187584127] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"726.468988ms","start":"2026-04-16T16:29:37.47985Z","end":"2026-04-16T16:29:38.206319Z","steps":["trace[187584127] 'process raft request' (duration: 726.063598ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:29:38.206431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:29:37.479836Z","time spent":"726.566391ms","remote":"127.0.0.1:60536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-16T16:29:38.206286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:29:37.484286Z","time spent":"721.926106ms","remote":"127.0.0.1:32788","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":980,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-16T16:29:38.206098Z","caller":"traceutil/trace.go:171","msg":"trace[1585562770] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"721.832383ms","start":"2026-04-16T16:29:37.48422Z","end":"2026-04-16T16:29:38.206052Z","steps":["trace[1585562770] 'process raft request' (duration: 721.735481ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:29:38.206557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:29:37.484207Z","time spent":"722.325366ms","remote":"127.0.0.1:60536","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-04-16T16:29:38.208755Z","caller":"traceutil/trace.go:171","msg":"trace[2041060317] linearizableReadLoop","detail":"{readStateIndex:514; appliedIndex:499; }","duration":"591.563206ms","start":"2026-04-16T16:29:37.617176Z","end":"2026-04-16T16:29:38.208739Z","steps":["trace[2041060317] 'read index received' (duration: 289.916699ms)","trace[2041060317] 'applied index is now lower than readState.Index' (duration: 301.645267ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T16:29:38.208963Z","caller":"traceutil/trace.go:171","msg":"trace[819769831] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"717.748332ms","start":"2026-04-16T16:29:37.491204Z","end":"2026-04-16T16:29:38.208952Z","steps":["trace[819769831] 'process raft request' (duration: 716.772608ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:29:38.209073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:29:37.491196Z","time spent":"717.844336ms","remote":"127.0.0.1:32788","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":995,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-16T16:29:38.209235Z","caller":"traceutil/trace.go:171","msg":"trace[1143929196] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"714.153224ms","start":"2026-04-16T16:29:37.495072Z","end":"2026-04-16T16:29:38.209226Z","steps":["trace[1143929196] 'process raft request' (duration: 713.013325ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:29:38.209308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:29:37.495065Z","time spent":"714.200745ms","remote":"127.0.0.1:60536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9125,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T16:29:38.209434Z","caller":"traceutil/trace.go:171","msg":"trace[1357477939] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"710.853103ms","start":"2026-04-16T16:29:37.498572Z","end":"2026-04-16T16:29:38.209425Z","steps":["trace[1357477939] 'process raft request' (duration: 709.595151ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:29:38.20949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:29:37.498553Z","time spent":"710.908974ms","remote":"127.0.0.1:60536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10063,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T16:29:38.209601Z","caller":"traceutil/trace.go:171","msg":"trace[1261742368] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"710.960305ms","start":"2026-04-16T16:29:37.498633Z","end":"2026-04-16T16:29:38.209593Z","steps":["trace[1261742368] 'process raft request' (duration: 709.57517ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:29:38.209649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:29:37.498629Z","time spent":"710.998526ms","remote":"127.0.0.1:60536","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-04-16T16:29:38.209759Z","caller":"traceutil/trace.go:171","msg":"trace[756019856] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"711.062808ms","start":"2026-04-16T16:29:37.498688Z","end":"2026-04-16T16:29:38.209751Z","steps":["trace[756019856] 'process raft request' (duration: 709.615222ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:29:38.209805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:29:37.498683Z","time spent":"711.103909ms","remote":"127.0.0.1:32788","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-16T16:29:38.20991Z","caller":"traceutil/trace.go:171","msg":"trace[1095596359] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"700.256811ms","start":"2026-04-16T16:29:37.509644Z","end":"2026-04-16T16:29:38.209901Z","steps":["trace[1095596359] 'process raft request' (duration: 698.689352ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:29:38.209964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:29:37.509625Z","time spent":"700.312563ms","remote":"127.0.0.1:60536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28739,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T16:29:38.210075Z","caller":"traceutil/trace.go:171","msg":"trace[1299518340] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"696.469359ms","start":"2026-04-16T16:29:37.513597Z","end":"2026-04-16T16:29:38.210067Z","steps":["trace[1299518340] 'process raft request' (duration: 694.792248ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:29:38.210119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:29:37.513567Z","time spent":"696.53514ms","remote":"127.0.0.1:60536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-16T16:29:38.210255Z","caller":"traceutil/trace.go:171","msg":"trace[333429553] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"694.449789ms","start":"2026-04-16T16:29:37.515797Z","end":"2026-04-16T16:29:38.210247Z","steps":["trace[333429553] 'process raft request' (duration: 692.670326ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:29:38.210305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:29:37.515784Z","time spent":"694.49927ms","remote":"127.0.0.1:60536","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-04-16T16:29:38.210511Z","caller":"traceutil/trace.go:171","msg":"trace[722284440] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"679.866269ms","start":"2026-04-16T16:29:37.530634Z","end":"2026-04-16T16:29:38.2105Z","steps":["trace[722284440] 'process raft request' (duration: 677.920072ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:29:38.210562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:29:37.530604Z","time spent":"679.937201ms","remote":"127.0.0.1:60536","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-04-16T16:29:38.210672Z","caller":"traceutil/trace.go:171","msg":"trace[774643311] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"580.348439ms","start":"2026-04-16T16:29:37.630315Z","end":"2026-04-16T16:29:38.210664Z","steps":["trace[774643311] 'process raft request' (duration: 578.34745ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:29:38.210717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:29:37.630275Z","time spent":"580.424021ms","remote":"127.0.0.1:60536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-16T16:29:38.210862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"593.691308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:29:38.210899Z","caller":"traceutil/trace.go:171","msg":"trace[370509268] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:499; }","duration":"593.769429ms","start":"2026-04-16T16:29:37.61712Z","end":"2026-04-16T16:29:38.210889Z","steps":["trace[370509268] 'agreement among raft nodes before linearized reading' (duration: 593.691318ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:29:38.210931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:29:37.617087Z","time spent":"593.837811ms","remote":"127.0.0.1:60490","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-04-16T16:29:38.213105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.750655ms","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-04-16T16:29:38.213156Z","caller":"traceutil/trace.go:171","msg":"trace[679366312] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:499; }","duration":"207.840617ms","start":"2026-04-16T16:29:38.005303Z","end":"2026-04-16T16:29:38.213144Z","steps":["trace[679366312] 'agreement among raft nodes before linearized reading' (duration: 207.762875ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:29:38.213267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.972204ms","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-04-16T16:29:38.213444Z","caller":"traceutil/trace.go:171","msg":"trace[80007356] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:499; }","duration":"345.189439ms","start":"2026-04-16T16:29:37.86824Z","end":"2026-04-16T16:29:38.21343Z","steps":["trace[80007356] 'agreement among raft nodes before linearized reading' (duration: 342.899903ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:29:38.213546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:29:37.868219Z","time spent":"345.314582ms","remote":"127.0.0.1:60790","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-04-16T16:29:38.213359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.815839ms","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-04-16T16:29:38.2137Z","caller":"traceutil/trace.go:171","msg":"trace[1985539876] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:499; }","duration":"295.167387ms","start":"2026-04-16T16:29:37.918507Z","end":"2026-04-16T16:29:38.213674Z","steps":["trace[1985539876] 'agreement among raft nodes before linearized reading' (duration: 294.782238ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:29:38.801002Z","caller":"traceutil/trace.go:171","msg":"trace[83145747] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"105.919618ms","start":"2026-04-16T16:29:38.695063Z","end":"2026-04-16T16:29:38.800983Z","steps":["trace[83145747] 'process raft request' (duration: 105.837676ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:29:38.801355Z","caller":"traceutil/trace.go:171","msg":"trace[1447759691] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"158.01882ms","start":"2026-04-16T16:29:38.643331Z","end":"2026-04-16T16:29:38.801349Z","steps":["trace[1447759691] 'process raft request' (duration: 145.966434ms)","trace[1447759691] 'compare' (duration: 11.466792ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T16:29:45.184605Z","caller":"traceutil/trace.go:171","msg":"trace[934006308] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"609.593039ms","start":"2026-04-16T16:29:44.574979Z","end":"2026-04-16T16:29:45.184572Z","steps":["trace[934006308] 'process raft request' (duration: 608.210385ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:29:45.185078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:29:44.574953Z","time spent":"609.791504ms","remote":"127.0.0.1:60590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036305,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T16:29:45.770617Z","caller":"traceutil/trace.go:171","msg":"trace[310195179] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"563.937445ms","start":"2026-04-16T16:29:45.206652Z","end":"2026-04-16T16:29:45.770589Z","steps":["trace[310195179] 'process raft request' (duration: 563.73416ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:29:45.77225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:29:45.206635Z","time spent":"564.067718ms","remote":"127.0.0.1:60690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T16:29:45.774781Z","caller":"traceutil/trace.go:171","msg":"trace[263397425] linearizableReadLoop","detail":"{readStateIndex:613; appliedIndex:611; }","duration":"158.810869ms","start":"2026-04-16T16:29:45.615949Z","end":"2026-04-16T16:29:45.77476Z","steps":["trace[263397425] 'read index received' (duration: 154.433722ms)","trace[263397425] 'applied index is now lower than readState.Index' (duration: 4.376497ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T16:29:45.774887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.919852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:29:45.774913Z","caller":"traceutil/trace.go:171","msg":"trace[103752011] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"524.234798ms","start":"2026-04-16T16:29:45.250626Z","end":"2026-04-16T16:29:45.774861Z","steps":["trace[103752011] 'process raft request' (duration: 524.055874ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:29:45.775024Z","caller":"traceutil/trace.go:171","msg":"trace[169556932] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:594; }","duration":"158.976455ms","start":"2026-04-16T16:29:45.615927Z","end":"2026-04-16T16:29:45.774903Z","steps":["trace[169556932] 'agreement among raft nodes before linearized reading' (duration: 158.909482ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:29:45.775058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:29:45.250601Z","time spent":"524.378762ms","remote":"127.0.0.1:60756","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-16T16:29:46.668916Z","caller":"traceutil/trace.go:171","msg":"trace[355029726] linearizableReadLoop","detail":"{readStateIndex:614; appliedIndex:613; }","duration":"395.937929ms","start":"2026-04-16T16:29:46.272949Z","end":"2026-04-16T16:29:46.668887Z","steps":["trace[355029726] 'read index received' (duration: 395.680313ms)","trace[355029726] 'applied index is now lower than readState.Index' (duration: 256.676µs)"],"step_count":2} {"level":"info","ts":"2026-04-16T16:29:46.669146Z","caller":"traceutil/trace.go:171","msg":"trace[1248733104] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"809.159763ms","start":"2026-04-16T16:29:45.859892Z","end":"2026-04-16T16:29:46.669051Z","steps":["trace[1248733104] 'process raft request' (duration: 808.795004ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:29:46.669268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.928048ms","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-04-16T16:29:46.669314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.422518ms","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-04-16T16:29:46.669317Z","caller":"traceutil/trace.go:171","msg":"trace[361031267] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:595; }","duration":"384.99384ms","start":"2026-04-16T16:29:46.284307Z","end":"2026-04-16T16:29:46.669301Z","steps":["trace[361031267] 'agreement among raft nodes before linearized reading' (duration: 384.860487ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:29:46.669289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.305548ms","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-04-16T16:29:46.669356Z","caller":"traceutil/trace.go:171","msg":"trace[406808284] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:595; }","duration":"391.499649ms","start":"2026-04-16T16:29:46.277845Z","end":"2026-04-16T16:29:46.669344Z","steps":["trace[406808284] 'agreement among raft nodes before linearized reading' (duration: 391.418677ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:29:46.669363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:29:46.284295Z","time spent":"385.060742ms","remote":"127.0.0.1:60790","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-04-16T16:29:46.669392Z","caller":"traceutil/trace.go:171","msg":"trace[1325427052] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:595; }","duration":"396.462302ms","start":"2026-04-16T16:29:46.272904Z","end":"2026-04-16T16:29:46.669366Z","steps":["trace[1325427052] 'agreement among raft nodes before linearized reading' (duration: 396.166275ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:29:46.669238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.397165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-04-16T16:29:46.669401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:29:46.277823Z","time spent":"391.568572ms","remote":"127.0.0.1:60790","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-16T16:29:46.669436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:29:46.272883Z","time spent":"396.545094ms","remote":"127.0.0.1:60790","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-04-16T16:29:46.669378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:29:45.859874Z","time spent":"809.405749ms","remote":"127.0.0.1:60690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2735,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-16T16:29:46.66983Z","caller":"traceutil/trace.go:171","msg":"trace[1822386908] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:595; }","duration":"395.641932ms","start":"2026-04-16T16:29:46.273792Z","end":"2026-04-16T16:29:46.669434Z","steps":["trace[1822386908] 'agreement among raft nodes before linearized reading' (duration: 395.289033ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:29:46.669906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:29:46.273785Z","time spent":"396.099243ms","remote":"127.0.0.1:60790","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-16T16:29:47.605666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"803.133385ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T16:29:47.606023Z","caller":"traceutil/trace.go:171","msg":"trace[1787841367] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"932.548812ms","start":"2026-04-16T16:29:46.673433Z","end":"2026-04-16T16:29:47.605982Z","steps":["trace[1787841367] 'process raft request' (duration: 129.020417ms)","trace[1787841367] 'compare' (duration: 802.702074ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T16:29:47.606182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:29:46.673415Z","time spent":"932.690335ms","remote":"127.0.0.1:60790","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T16:29:47.606215Z","caller":"traceutil/trace.go:171","msg":"trace[1702164767] linearizableReadLoop","detail":"{readStateIndex:623; appliedIndex:614; }","duration":"405.560366ms","start":"2026-04-16T16:29:47.200638Z","end":"2026-04-16T16:29:47.606198Z","steps":["trace[1702164767] 'read index received' (duration: 401.899516ms)","trace[1702164767] 'applied index is now lower than readState.Index' (duration: 3.66011ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T16:29:47.606244Z","caller":"traceutil/trace.go:171","msg":"trace[1136013389] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"930.136391ms","start":"2026-04-16T16:29:46.676072Z","end":"2026-04-16T16:29:47.606209Z","steps":["trace[1136013389] 'process raft request' (duration: 929.981057ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:29:47.606282Z","caller":"traceutil/trace.go:171","msg":"trace[184179458] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"931.166876ms","start":"2026-04-16T16:29:46.675097Z","end":"2026-04-16T16:29:47.606264Z","steps":["trace[184179458] 'process raft request' (duration: 930.89222ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:29:47.606358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.72782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.238\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-16T16:29:47.606381Z","caller":"traceutil/trace.go:171","msg":"trace[1692068847] range","detail":"{range_begin:/registry/masterleases/199.204.45.238; range_end:; response_count:1; response_revision:604; }","duration":"405.805642ms","start":"2026-04-16T16:29:47.200567Z","end":"2026-04-16T16:29:47.606373Z","steps":["trace[1692068847] 'agreement among raft nodes before linearized reading' (duration: 405.678489ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:29:47.606407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:29:47.200549Z","time spent":"405.850133ms","remote":"127.0.0.1:60522","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.238\" "} {"level":"warn","ts":"2026-04-16T16:29:47.606411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:29:46.676065Z","time spent":"930.252215ms","remote":"127.0.0.1:60790","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-04-16T16:29:47.606429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:29:46.675089Z","time spent":"931.268499ms","remote":"127.0.0.1:60566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":905,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-16T16:29:47.60628Z","caller":"traceutil/trace.go:171","msg":"trace[119156014] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"931.596437ms","start":"2026-04-16T16:29:46.674641Z","end":"2026-04-16T16:29:47.606237Z","steps":["trace[119156014] 'process raft request' (duration: 931.124365ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:29:47.606519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:29:46.674625Z","time spent":"931.860204ms","remote":"127.0.0.1:60790","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-04-16T16:29:47.606643Z","caller":"traceutil/trace.go:171","msg":"trace[777684987] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"931.267469ms","start":"2026-04-16T16:29:46.675365Z","end":"2026-04-16T16:29:47.606633Z","steps":["trace[777684987] 'process raft request' (duration: 930.660795ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:29:47.606719Z","caller":"traceutil/trace.go:171","msg":"trace[7850078] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"928.284995ms","start":"2026-04-16T16:29:46.678425Z","end":"2026-04-16T16:29:47.60671Z","steps":["trace[7850078] 'process raft request' (duration: 927.708851ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:29:47.60672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:29:46.675359Z","time spent":"931.30823ms","remote":"127.0.0.1:60790","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-04-16T16:29:47.606775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:29:46.678418Z","time spent":"928.326427ms","remote":"127.0.0.1:60690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3209,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T16:29:47.606841Z","caller":"traceutil/trace.go:171","msg":"trace[1669214987] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"489.650865ms","start":"2026-04-16T16:29:47.117182Z","end":"2026-04-16T16:29:47.606833Z","steps":["trace[1669214987] 'process raft request' (duration: 488.983939ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:29:47.606873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:29:47.117157Z","time spent":"489.697117ms","remote":"127.0.0.1:60790","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T16:29:47.606941Z","caller":"traceutil/trace.go:171","msg":"trace[1448576586] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"930.152711ms","start":"2026-04-16T16:29:46.676771Z","end":"2026-04-16T16:29:47.606923Z","steps":["trace[1448576586] 'process raft request' (duration: 929.305571ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:29:47.606938Z","caller":"traceutil/trace.go:171","msg":"trace[1354310239] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"929.649868ms","start":"2026-04-16T16:29:46.677264Z","end":"2026-04-16T16:29:47.606914Z","steps":["trace[1354310239] 'process raft request' (duration: 928.835658ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:29:47.6072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:29:46.676764Z","time spent":"930.379719ms","remote":"127.0.0.1:60566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":775,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-16T16:29:47.60723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:29:46.677243Z","time spent":"929.926386ms","remote":"127.0.0.1:60756","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T16:29:49.699926Z","caller":"traceutil/trace.go:171","msg":"trace[969138485] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"386.22492ms","start":"2026-04-16T16:29:49.313673Z","end":"2026-04-16T16:29:49.699898Z","steps":["trace[969138485] 'process raft request' (duration: 386.048246ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:29:49.700079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:29:49.31364Z","time spent":"386.377063ms","remote":"127.0.0.1:60536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9343,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-16T16:29:49.705183Z","caller":"traceutil/trace.go:171","msg":"trace[1455947701] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"192.235903ms","start":"2026-04-16T16:29:49.512148Z","end":"2026-04-16T16:29:49.704384Z","steps":["trace[1455947701] 'process raft request' (duration: 191.770522ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:30:03.609973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"653.318226ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-04-16T16:30:03.610081Z","caller":"traceutil/trace.go:171","msg":"trace[1105130068] linearizableReadLoop","detail":"{readStateIndex:783; appliedIndex:782; }","duration":"366.402402ms","start":"2026-04-16T16:30:03.243661Z","end":"2026-04-16T16:30:03.610063Z","steps":["trace[1105130068] 'read index received' (duration: 35.241µs)","trace[1105130068] 'applied index is now lower than readState.Index' (duration: 366.365711ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T16:30:03.610685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.018087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-cd9dw\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-04-16T16:30:03.610731Z","caller":"traceutil/trace.go:171","msg":"trace[82356318] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-cd9dw; range_end:; response_count:1; response_revision:759; }","duration":"367.073038ms","start":"2026-04-16T16:30:03.243646Z","end":"2026-04-16T16:30:03.610719Z","steps":["trace[82356318] 'agreement among raft nodes before linearized reading' (duration: 366.890494ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:30:03.610768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:30:03.243589Z","time spent":"367.16996ms","remote":"127.0.0.1:60690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3989,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-cd9dw\" "} {"level":"warn","ts":"2026-04-16T16:30:03.610781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.622457ms","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-04-16T16:30:03.610857Z","caller":"traceutil/trace.go:171","msg":"trace[1766918959] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:759; }","duration":"366.720449ms","start":"2026-04-16T16:30:03.244116Z","end":"2026-04-16T16:30:03.610837Z","steps":["trace[1766918959] 'agreement among raft nodes before linearized reading' (duration: 366.502814ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:30:03.610897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:30:03.244108Z","time spent":"366.780431ms","remote":"127.0.0.1:60790","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-16T16:30:38.947276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.144126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-16T16:30:38.947276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.581883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:30:38.947389Z","caller":"traceutil/trace.go:171","msg":"trace[2084318157] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:978; }","duration":"108.772407ms","start":"2026-04-16T16:30:38.838602Z","end":"2026-04-16T16:30:38.947375Z","steps":["trace[2084318157] 'count revisions from in-memory index tree' (duration: 108.45771ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:30:38.94739Z","caller":"traceutil/trace.go:171","msg":"trace[1570465525] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:978; }","duration":"329.291539ms","start":"2026-04-16T16:30:38.618075Z","end":"2026-04-16T16:30:38.947367Z","steps":["trace[1570465525] 'range keys from in-memory index tree' (duration: 329.057144ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:30:38.947437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:30:38.61806Z","time spent":"329.366771ms","remote":"127.0.0.1:60488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-16T16:30:40.158769Z","caller":"traceutil/trace.go:171","msg":"trace[1618842221] linearizableReadLoop","detail":"{readStateIndex:1012; appliedIndex:1011; }","duration":"153.317588ms","start":"2026-04-16T16:30:40.005428Z","end":"2026-04-16T16:30:40.158746Z","steps":["trace[1618842221] 'read index received' (duration: 153.040672ms)","trace[1618842221] 'applied index is now lower than readState.Index' (duration: 276.046µs)"],"step_count":2} {"level":"info","ts":"2026-04-16T16:30:40.158847Z","caller":"traceutil/trace.go:171","msg":"trace[1467107604] transaction","detail":"{read_only:false; response_revision:979; number_of_response:1; }","duration":"354.923669ms","start":"2026-04-16T16:30:39.803884Z","end":"2026-04-16T16:30:40.158807Z","steps":["trace[1467107604] 'process raft request' (duration: 354.700984ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:30:40.158981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:30:39.803872Z","time spent":"355.037442ms","remote":"127.0.0.1:60790","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-04-16T16:30:40.159048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.128605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-04-16T16:30:40.159062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.597215ms","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-04-16T16:30:40.159077Z","caller":"traceutil/trace.go:171","msg":"trace[1898229338] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:979; }","duration":"140.178356ms","start":"2026-04-16T16:30:40.018892Z","end":"2026-04-16T16:30:40.15907Z","steps":["trace[1898229338] 'agreement among raft nodes before linearized reading' (duration: 140.023573ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:30:40.159116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.177277ms","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-04-16T16:30:40.1591Z","caller":"traceutil/trace.go:171","msg":"trace[1355281102] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:979; }","duration":"153.700866ms","start":"2026-04-16T16:30:40.005389Z","end":"2026-04-16T16:30:40.15909Z","steps":["trace[1355281102] 'agreement among raft nodes before linearized reading' (duration: 153.479942ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:30:40.159122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.037334ms","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-04-16T16:30:40.159194Z","caller":"traceutil/trace.go:171","msg":"trace[1596325373] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:979; }","duration":"136.279099ms","start":"2026-04-16T16:30:40.022897Z","end":"2026-04-16T16:30:40.159176Z","steps":["trace[1596325373] 'agreement among raft nodes before linearized reading' (duration: 136.069615ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:30:40.159235Z","caller":"traceutil/trace.go:171","msg":"trace[133634821] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:979; }","duration":"136.205418ms","start":"2026-04-16T16:30:40.023011Z","end":"2026-04-16T16:30:40.159216Z","steps":["trace[133634821] 'agreement among raft nodes before linearized reading' (duration: 135.923862ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:30:40.9699Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976531765991803709,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-16T16:30:41.299994Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.135303456s","expected-duration":"1s"} {"level":"info","ts":"2026-04-16T16:30:41.308066Z","caller":"traceutil/trace.go:171","msg":"trace[761885426] transaction","detail":"{read_only:false; response_revision:980; number_of_response:1; }","duration":"1.143430657s","start":"2026-04-16T16:30:40.164615Z","end":"2026-04-16T16:30:41.308045Z","steps":["trace[761885426] 'process raft request' (duration: 1.135531791s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:30:41.308176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:30:40.164595Z","time spent":"1.143519348s","remote":"127.0.0.1:60790","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T16:30:41.311966Z","caller":"traceutil/trace.go:171","msg":"trace[1080442792] transaction","detail":"{read_only:false; response_revision:981; number_of_response:1; }","duration":"1.147242651s","start":"2026-04-16T16:30:40.164712Z","end":"2026-04-16T16:30:41.311954Z","steps":["trace[1080442792] 'process raft request' (duration: 1.147115029s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:30:41.31203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:30:40.164693Z","time spent":"1.147304332s","remote":"127.0.0.1:60790","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T16:30:41.312025Z","caller":"traceutil/trace.go:171","msg":"trace[1360612148] transaction","detail":"{read_only:false; response_revision:982; number_of_response:1; }","duration":"1.147179759s","start":"2026-04-16T16:30:40.16482Z","end":"2026-04-16T16:30:41.312Z","steps":["trace[1360612148] 'process raft request' (duration: 1.147079847s)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:30:41.312108Z","caller":"traceutil/trace.go:171","msg":"trace[1981199760] transaction","detail":"{read_only:false; response_revision:983; number_of_response:1; }","duration":"1.147230711s","start":"2026-04-16T16:30:40.164854Z","end":"2026-04-16T16:30:41.312084Z","steps":["trace[1981199760] 'process raft request' (duration: 1.147062618s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:30:41.312139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:30:40.164815Z","time spent":"1.147264401s","remote":"127.0.0.1:60790","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-04-16T16:30:41.312222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:30:40.164828Z","time spent":"1.147338154s","remote":"127.0.0.1:60790","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-04-16T16:30:41.312695Z","caller":"traceutil/trace.go:171","msg":"trace[975075482] linearizableReadLoop","detail":"{readStateIndex:1018; appliedIndex:1012; }","duration":"843.265455ms","start":"2026-04-16T16:30:40.469421Z","end":"2026-04-16T16:30:41.312686Z","steps":["trace[975075482] 'read index received' (duration: 830.733385ms)","trace[975075482] 'applied index is now lower than readState.Index' (duration: 12.5313ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T16:30:41.312759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"843.340737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:30:41.312787Z","caller":"traceutil/trace.go:171","msg":"trace[3656010] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:985; }","duration":"843.400188ms","start":"2026-04-16T16:30:40.469381Z","end":"2026-04-16T16:30:41.312781Z","steps":["trace[3656010] 'agreement among raft nodes before linearized reading' (duration: 843.338486ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:30:41.312817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:30:40.469363Z","time spent":"843.448979ms","remote":"127.0.0.1:60958","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":29,"request content":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true "} {"level":"info","ts":"2026-04-16T16:30:41.31288Z","caller":"traceutil/trace.go:171","msg":"trace[104562987] transaction","detail":"{read_only:false; response_revision:984; number_of_response:1; }","duration":"838.854258ms","start":"2026-04-16T16:30:40.474017Z","end":"2026-04-16T16:30:41.312872Z","steps":["trace[104562987] 'process raft request' (duration: 837.913626ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:30:41.312934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:30:40.474006Z","time spent":"838.896878ms","remote":"127.0.0.1:60790","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-04-16T16:30:41.31295Z","caller":"traceutil/trace.go:171","msg":"trace[892170843] transaction","detail":"{read_only:false; response_revision:985; number_of_response:1; }","duration":"505.494077ms","start":"2026-04-16T16:30:40.80743Z","end":"2026-04-16T16:30:41.312924Z","steps":["trace[892170843] 'process raft request' (duration: 505.220901ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:30:41.313072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:30:40.807409Z","time spent":"505.60615ms","remote":"127.0.0.1:60790","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T16:31:01.579392Z","caller":"traceutil/trace.go:171","msg":"trace[872469713] linearizableReadLoop","detail":"{readStateIndex:1084; appliedIndex:1083; }","duration":"103.283896ms","start":"2026-04-16T16:31:01.476083Z","end":"2026-04-16T16:31:01.579367Z","steps":["trace[872469713] 'read index received' (duration: 20.945586ms)","trace[872469713] 'applied index is now lower than readState.Index' (duration: 82.33726ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T16:31:01.579826Z","caller":"traceutil/trace.go:171","msg":"trace[1428152023] transaction","detail":"{read_only:false; response_revision:1047; number_of_response:1; }","duration":"105.163057ms","start":"2026-04-16T16:31:01.474645Z","end":"2026-04-16T16:31:01.579808Z","steps":["trace[1428152023] 'process raft request' (duration: 22.408568ms)","trace[1428152023] 'compare' (duration: 82.170556ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T16:31:01.5801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.004553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-16T16:31:01.580173Z","caller":"traceutil/trace.go:171","msg":"trace[319011678] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1047; }","duration":"104.077474ms","start":"2026-04-16T16:31:01.476052Z","end":"2026-04-16T16:31:01.580129Z","steps":["trace[319011678] 'agreement among raft nodes before linearized reading' (duration: 103.94485ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:31:02.794692Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976531765991803893,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-16T16:31:02.957723Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.067306094s","expected-duration":"1s"} {"level":"info","ts":"2026-04-16T16:31:03.004279Z","caller":"traceutil/trace.go:171","msg":"trace[1937540314] transaction","detail":"{read_only:false; response_revision:1051; number_of_response:1; }","duration":"1.113931831s","start":"2026-04-16T16:31:01.890314Z","end":"2026-04-16T16:31:03.004246Z","steps":["trace[1937540314] 'process raft request' (duration: 1.067690603s)","trace[1937540314] 'compare' (duration: 46.131326ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T16:31:03.0044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:31:01.890289Z","time spent":"1.114046454s","remote":"127.0.0.1:60790","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T16:31:03.043261Z","caller":"traceutil/trace.go:171","msg":"trace[1960932096] linearizableReadLoop","detail":"{readStateIndex:1089; appliedIndex:1087; }","duration":"749.649632ms","start":"2026-04-16T16:31:02.293561Z","end":"2026-04-16T16:31:03.043211Z","steps":["trace[1960932096] 'read index received' (duration: 664.414637ms)","trace[1960932096] 'applied index is now lower than readState.Index' (duration: 85.233535ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T16:31:03.043508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"749.933299ms","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-04-16T16:31:03.043548Z","caller":"traceutil/trace.go:171","msg":"trace[581128634] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1051; }","duration":"750.02573ms","start":"2026-04-16T16:31:02.293511Z","end":"2026-04-16T16:31:03.043537Z","steps":["trace[581128634] 'agreement among raft nodes before linearized reading' (duration: 749.859127ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:31:03.043583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:31:02.293491Z","time spent":"750.083202ms","remote":"127.0.0.1:60790","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-04-16T16:31:03.043658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.132988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:31:03.043781Z","caller":"traceutil/trace.go:171","msg":"trace[205035301] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:1051; }","duration":"312.25717ms","start":"2026-04-16T16:31:02.73145Z","end":"2026-04-16T16:31:03.043707Z","steps":["trace[205035301] 'agreement among raft nodes before linearized reading' (duration: 312.133687ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:31:03.043841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:31:02.731429Z","time spent":"312.382073ms","remote":"127.0.0.1:60882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":29,"request content":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true "} {"level":"info","ts":"2026-04-16T16:31:03.782923Z","caller":"traceutil/trace.go:171","msg":"trace[564834810] linearizableReadLoop","detail":"{readStateIndex:1092; appliedIndex:1091; }","duration":"169.209512ms","start":"2026-04-16T16:31:03.613691Z","end":"2026-04-16T16:31:03.782901Z","steps":["trace[564834810] 'read index received' (duration: 169.032788ms)","trace[564834810] 'applied index is now lower than readState.Index' (duration: 175.594µs)"],"step_count":2} {"level":"info","ts":"2026-04-16T16:31:03.782957Z","caller":"traceutil/trace.go:171","msg":"trace[1259881979] transaction","detail":"{read_only:false; response_revision:1054; number_of_response:1; }","duration":"193.851059ms","start":"2026-04-16T16:31:03.589077Z","end":"2026-04-16T16:31:03.782928Z","steps":["trace[1259881979] 'process raft request' (duration: 193.681475ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:31:03.783183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.473158ms","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-04-16T16:31:03.783227Z","caller":"traceutil/trace.go:171","msg":"trace[909011071] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1054; }","duration":"169.57382ms","start":"2026-04-16T16:31:03.613644Z","end":"2026-04-16T16:31:03.783218Z","steps":["trace[909011071] 'agreement among raft nodes before linearized reading' (duration: 169.383766ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:31:03.783185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.270522ms","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-04-16T16:31:03.78334Z","caller":"traceutil/trace.go:171","msg":"trace[1594574041] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1054; }","duration":"169.460717ms","start":"2026-04-16T16:31:03.613871Z","end":"2026-04-16T16:31:03.783332Z","steps":["trace[1594574041] 'agreement among raft nodes before linearized reading' (duration: 169.183481ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:31:03.783391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.229623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-16T16:31:03.783465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.351295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-16T16:31:03.783481Z","caller":"traceutil/trace.go:171","msg":"trace[1192943173] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1054; }","duration":"165.373727ms","start":"2026-04-16T16:31:03.618085Z","end":"2026-04-16T16:31:03.783459Z","steps":["trace[1192943173] 'agreement among raft nodes before linearized reading' (duration: 165.214173ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:31:03.78349Z","caller":"traceutil/trace.go:171","msg":"trace[1922018394] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1054; }","duration":"169.392885ms","start":"2026-04-16T16:31:03.614089Z","end":"2026-04-16T16:31:03.783482Z","steps":["trace[1922018394] 'agreement among raft nodes before linearized reading' (duration: 169.306483ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:31:04.091863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.716565ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T16:31:04.092093Z","caller":"traceutil/trace.go:171","msg":"trace[1133084290] transaction","detail":"{read_only:false; response_revision:1056; number_of_response:1; }","duration":"303.389513ms","start":"2026-04-16T16:31:03.788686Z","end":"2026-04-16T16:31:04.092075Z","steps":["trace[1133084290] 'process raft request' (duration: 303.301301ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:31:04.092167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:31:03.788667Z","time spent":"303.469385ms","remote":"127.0.0.1:60790","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-04-16T16:31:04.092309Z","caller":"traceutil/trace.go:171","msg":"trace[1011002743] transaction","detail":"{read_only:false; response_revision:1055; number_of_response:1; }","duration":"304.380225ms","start":"2026-04-16T16:31:03.787766Z","end":"2026-04-16T16:31:04.092147Z","steps":["trace[1011002743] 'process raft request' (duration: 110.283151ms)","trace[1011002743] 'compare' (duration: 193.512641ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T16:31:04.092473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:31:03.787738Z","time spent":"304.650161ms","remote":"127.0.0.1:60790","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T16:31:04.09277Z","caller":"traceutil/trace.go:171","msg":"trace[1885073848] transaction","detail":"{read_only:false; response_revision:1057; number_of_response:1; }","duration":"304.070658ms","start":"2026-04-16T16:31:03.78869Z","end":"2026-04-16T16:31:04.092761Z","steps":["trace[1885073848] 'process raft request' (duration: 303.350682ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:31:04.092852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:31:03.788675Z","time spent":"304.14995ms","remote":"127.0.0.1:60790","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T16:31:05.329678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"512.051081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-16T16:31:05.329736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:31:04.251462Z","time spent":"1.078262458s","remote":"127.0.0.1:33882","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-04-16T16:31:05.329764Z","caller":"traceutil/trace.go:171","msg":"trace[50781443] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:1057; }","duration":"512.283416ms","start":"2026-04-16T16:31:04.817463Z","end":"2026-04-16T16:31:05.329747Z","steps":["trace[50781443] 'count revisions from in-memory index tree' (duration: 511.95078ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:31:05.32982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:31:04.817442Z","time spent":"512.366229ms","remote":"127.0.0.1:60666","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":3,"response size":31,"request content":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true "} {"level":"warn","ts":"2026-04-16T16:31:05.329947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.649696ms","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-04-16T16:31:05.329983Z","caller":"traceutil/trace.go:171","msg":"trace[1047006034] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1057; }","duration":"270.718887ms","start":"2026-04-16T16:31:05.059253Z","end":"2026-04-16T16:31:05.329972Z","steps":["trace[1047006034] 'range keys from in-memory index tree' (duration: 270.523052ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:31:05.330001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.013627312s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-16T16:31:05.330048Z","caller":"traceutil/trace.go:171","msg":"trace[4059341] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:1057; }","duration":"1.013719163s","start":"2026-04-16T16:31:04.316317Z","end":"2026-04-16T16:31:05.330036Z","steps":["trace[4059341] 'count revisions from in-memory index tree' (duration: 1.013485549s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:31:05.330087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:31:04.316295Z","time spent":"1.013782775s","remote":"127.0.0.1:60718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":43,"response size":31,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"warn","ts":"2026-04-16T16:31:05.330365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"711.567327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:31:05.330424Z","caller":"traceutil/trace.go:171","msg":"trace[1809675253] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1057; }","duration":"711.659809ms","start":"2026-04-16T16:31:04.618752Z","end":"2026-04-16T16:31:05.330411Z","steps":["trace[1809675253] 'range keys from in-memory index tree' (duration: 711.478455ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:31:05.330461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:31:04.618727Z","time spent":"711.72463ms","remote":"127.0.0.1:60490","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-04-16T16:31:05.329769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.510986ms","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-04-16T16:31:05.330819Z","caller":"traceutil/trace.go:171","msg":"trace[774090268] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:1057; }","duration":"434.5728ms","start":"2026-04-16T16:31:04.896232Z","end":"2026-04-16T16:31:05.330804Z","steps":["trace[774090268] 'count revisions from in-memory index tree' (duration: 433.388103ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:31:05.330874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:31:04.896167Z","time spent":"434.689022ms","remote":"127.0.0.1:60756","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true "} {"level":"info","ts":"2026-04-16T16:31:05.791754Z","caller":"traceutil/trace.go:171","msg":"trace[1738792326] transaction","detail":"{read_only:false; response_revision:1059; number_of_response:1; }","duration":"279.36836ms","start":"2026-04-16T16:31:05.512362Z","end":"2026-04-16T16:31:05.79173Z","steps":["trace[1738792326] 'process raft request' (duration: 279.162575ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:31:05.792142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.758256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-16T16:31:05.792369Z","caller":"traceutil/trace.go:171","msg":"trace[1225385394] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1059; }","duration":"279.066983ms","start":"2026-04-16T16:31:05.513286Z","end":"2026-04-16T16:31:05.792353Z","steps":["trace[1225385394] 'agreement among raft nodes before linearized reading' (duration: 278.769376ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:31:05.791682Z","caller":"traceutil/trace.go:171","msg":"trace[442409538] linearizableReadLoop","detail":"{readStateIndex:1097; appliedIndex:1096; }","duration":"278.291895ms","start":"2026-04-16T16:31:05.513369Z","end":"2026-04-16T16:31:05.791661Z","steps":["trace[442409538] 'read index received' (duration: 278.111201ms)","trace[442409538] 'applied index is now lower than readState.Index' (duration: 179.494µs)"],"step_count":2} {"level":"warn","ts":"2026-04-16T16:31:05.793225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.426287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:31:05.793308Z","caller":"traceutil/trace.go:171","msg":"trace[1866563684] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1059; }","duration":"174.613931ms","start":"2026-04-16T16:31:05.618676Z","end":"2026-04-16T16:31:05.79329Z","steps":["trace[1866563684] 'agreement among raft nodes before linearized reading' (duration: 174.478858ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:31:05.794182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.167217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-16T16:31:05.794308Z","caller":"traceutil/trace.go:171","msg":"trace[1304452675] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:1059; }","duration":"239.29325ms","start":"2026-04-16T16:31:05.554998Z","end":"2026-04-16T16:31:05.794291Z","steps":["trace[1304452675] 'agreement among raft nodes before linearized reading' (duration: 238.116333ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:31:06.28026Z","caller":"traceutil/trace.go:171","msg":"trace[25361539] transaction","detail":"{read_only:false; response_revision:1061; number_of_response:1; }","duration":"179.208564ms","start":"2026-04-16T16:31:06.101006Z","end":"2026-04-16T16:31:06.280215Z","steps":["trace[25361539] 'process raft request' (duration: 179.02656ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:31:06.992766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"609.576261ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T16:31:06.993253Z","caller":"traceutil/trace.go:171","msg":"trace[539075713] transaction","detail":"{read_only:false; response_revision:1062; number_of_response:1; }","duration":"892.145852ms","start":"2026-04-16T16:31:06.101085Z","end":"2026-04-16T16:31:06.993231Z","steps":["trace[539075713] 'process raft request' (duration: 282.002959ms)","trace[539075713] 'compare' (duration: 609.109999ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T16:31:06.993332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:31:06.101072Z","time spent":"892.225873ms","remote":"127.0.0.1:60790","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T16:31:06.993771Z","caller":"traceutil/trace.go:171","msg":"trace[1505850236] linearizableReadLoop","detail":"{readStateIndex:1101; appliedIndex:1099; }","duration":"491.489275ms","start":"2026-04-16T16:31:06.502271Z","end":"2026-04-16T16:31:06.99376Z","steps":["trace[1505850236] 'read index received' (duration: 63.381µs)","trace[1505850236] 'applied index is now lower than readState.Index' (duration: 491.425084ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T16:31:06.993953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"491.665929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-16T16:31:06.994023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.10481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:31:06.994033Z","caller":"traceutil/trace.go:171","msg":"trace[130849630] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:1063; }","duration":"491.843202ms","start":"2026-04-16T16:31:06.50217Z","end":"2026-04-16T16:31:06.994014Z","steps":["trace[130849630] 'agreement among raft nodes before linearized reading' (duration: 491.689179ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:31:06.994056Z","caller":"traceutil/trace.go:171","msg":"trace[1424017481] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1063; }","duration":"376.167282ms","start":"2026-04-16T16:31:06.617878Z","end":"2026-04-16T16:31:06.994045Z","steps":["trace[1424017481] 'agreement among raft nodes before linearized reading' (duration: 376.114251ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:31:06.994078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:31:06.502149Z","time spent":"491.919314ms","remote":"127.0.0.1:59094","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true "} {"level":"warn","ts":"2026-04-16T16:31:06.99414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:31:06.617858Z","time spent":"376.228253ms","remote":"127.0.0.1:60488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-16T16:31:06.994279Z","caller":"traceutil/trace.go:171","msg":"trace[1804099998] transaction","detail":"{read_only:false; response_revision:1063; number_of_response:1; }","duration":"893.104313ms","start":"2026-04-16T16:31:06.101162Z","end":"2026-04-16T16:31:06.994267Z","steps":["trace[1804099998] 'process raft request' (duration: 892.52351ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:31:06.994366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:31:06.101153Z","time spent":"893.160215ms","remote":"127.0.0.1:60790","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-04-16T16:31:06.994646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.893268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-16T16:31:06.994689Z","caller":"traceutil/trace.go:171","msg":"trace[83068408] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1063; }","duration":"210.9722ms","start":"2026-04-16T16:31:06.783707Z","end":"2026-04-16T16:31:06.994679Z","steps":["trace[83068408] 'agreement among raft nodes before linearized reading' (duration: 210.868318ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:31:07.555553Z","caller":"traceutil/trace.go:171","msg":"trace[116409609] linearizableReadLoop","detail":"{readStateIndex:1104; appliedIndex:1103; }","duration":"111.764884ms","start":"2026-04-16T16:31:07.443765Z","end":"2026-04-16T16:31:07.55553Z","steps":["trace[116409609] 'read index received' (duration: 111.663972ms)","trace[116409609] 'applied index is now lower than readState.Index' (duration: 99.882µs)"],"step_count":2} {"level":"info","ts":"2026-04-16T16:31:07.555678Z","caller":"traceutil/trace.go:171","msg":"trace[220954749] transaction","detail":"{read_only:false; response_revision:1065; number_of_response:1; }","duration":"123.670739ms","start":"2026-04-16T16:31:07.431956Z","end":"2026-04-16T16:31:07.555627Z","steps":["trace[220954749] 'process raft request' (duration: 123.423724ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:31:07.555708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.922418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-04-16T16:31:07.555972Z","caller":"traceutil/trace.go:171","msg":"trace[1509683472] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:1065; }","duration":"112.229405ms","start":"2026-04-16T16:31:07.443727Z","end":"2026-04-16T16:31:07.555956Z","steps":["trace[1509683472] 'agreement among raft nodes before linearized reading' (duration: 111.907068ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:31:08.010306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.75147ms","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-04-16T16:31:08.010385Z","caller":"traceutil/trace.go:171","msg":"trace[1517634593] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1066; }","duration":"132.869854ms","start":"2026-04-16T16:31:07.877498Z","end":"2026-04-16T16:31:08.010368Z","steps":["trace[1517634593] 'range keys from in-memory index tree' (duration: 132.539356ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:31:19.151215Z","caller":"traceutil/trace.go:171","msg":"trace[1278675915] transaction","detail":"{read_only:false; response_revision:1153; number_of_response:1; }","duration":"106.5578ms","start":"2026-04-16T16:31:19.044628Z","end":"2026-04-16T16:31:19.151186Z","steps":["trace[1278675915] 'process raft request' (duration: 106.370046ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:31:19.170888Z","caller":"traceutil/trace.go:171","msg":"trace[333406981] transaction","detail":"{read_only:false; response_revision:1154; number_of_response:1; }","duration":"125.51258ms","start":"2026-04-16T16:31:19.045353Z","end":"2026-04-16T16:31:19.170866Z","steps":["trace[333406981] 'process raft request' (duration: 125.374687ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:31:21.116896Z","caller":"traceutil/trace.go:171","msg":"trace[1092388123] linearizableReadLoop","detail":"{readStateIndex:1202; appliedIndex:1201; }","duration":"497.69963ms","start":"2026-04-16T16:31:20.619177Z","end":"2026-04-16T16:31:21.116877Z","steps":["trace[1092388123] 'read index received' (duration: 497.514005ms)","trace[1092388123] 'applied index is now lower than readState.Index' (duration: 184.995µs)"],"step_count":2} {"level":"info","ts":"2026-04-16T16:31:21.117036Z","caller":"traceutil/trace.go:171","msg":"trace[1071910950] transaction","detail":"{read_only:false; response_revision:1159; number_of_response:1; }","duration":"694.870986ms","start":"2026-04-16T16:31:20.422136Z","end":"2026-04-16T16:31:21.117007Z","steps":["trace[1071910950] 'process raft request' (duration: 694.599179ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:31:21.117066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.867714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:31:21.117173Z","caller":"traceutil/trace.go:171","msg":"trace[280599814] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1159; }","duration":"498.025738ms","start":"2026-04-16T16:31:20.619137Z","end":"2026-04-16T16:31:21.117163Z","steps":["trace[280599814] 'agreement among raft nodes before linearized reading' (duration: 497.813153ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:31:21.117176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:31:20.42212Z","time spent":"694.980818ms","remote":"127.0.0.1:60790","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T16:31:21.117203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:31:20.619117Z","time spent":"498.077078ms","remote":"127.0.0.1:60490","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-04-16T16:31:22.017117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"861.6983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-16T16:31:22.017171Z","caller":"traceutil/trace.go:171","msg":"trace[1595201093] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1159; }","duration":"861.784642ms","start":"2026-04-16T16:31:21.155374Z","end":"2026-04-16T16:31:22.017159Z","steps":["trace[1595201093] 'range keys from in-memory index tree' (duration: 861.595508ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:31:22.017174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.0754ms","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-04-16T16:31:22.017198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:31:21.15536Z","time spent":"861.832073ms","remote":"127.0.0.1:60790","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-16T16:31:22.017222Z","caller":"traceutil/trace.go:171","msg":"trace[985757010] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1159; }","duration":"391.181502ms","start":"2026-04-16T16:31:21.626029Z","end":"2026-04-16T16:31:22.017211Z","steps":["trace[985757010] 'range keys from in-memory index tree' (duration: 390.970958ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:31:22.017248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:31:21.626012Z","time spent":"391.230172ms","remote":"127.0.0.1:60790","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-04-16T16:31:22.017304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"843.567087ms","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-04-16T16:31:22.017353Z","caller":"traceutil/trace.go:171","msg":"trace[3417774] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1159; }","duration":"843.64277ms","start":"2026-04-16T16:31:21.173695Z","end":"2026-04-16T16:31:22.017338Z","steps":["trace[3417774] 'range keys from in-memory index tree' (duration: 843.396584ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:31:22.017386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:31:21.17368Z","time spent":"843.6991ms","remote":"127.0.0.1:60790","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-16T16:31:22.018122Z","caller":"traceutil/trace.go:171","msg":"trace[1263684133] transaction","detail":"{read_only:false; response_revision:1160; number_of_response:1; }","duration":"167.617901ms","start":"2026-04-16T16:31:21.850491Z","end":"2026-04-16T16:31:22.018108Z","steps":["trace[1263684133] 'process raft request' (duration: 164.201796ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:31:23.301522Z","caller":"traceutil/trace.go:171","msg":"trace[1137972155] transaction","detail":"{read_only:false; response_revision:1165; number_of_response:1; }","duration":"175.762791ms","start":"2026-04-16T16:31:23.125737Z","end":"2026-04-16T16:31:23.3015Z","steps":["trace[1137972155] 'process raft request' (duration: 175.599017ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:31:37.379879Z","caller":"traceutil/trace.go:171","msg":"trace[2065166855] linearizableReadLoop","detail":"{readStateIndex:1276; appliedIndex:1275; }","duration":"106.797044ms","start":"2026-04-16T16:31:37.273059Z","end":"2026-04-16T16:31:37.379856Z","steps":["trace[2065166855] 'read index received' (duration: 50.743794ms)","trace[2065166855] 'applied index is now lower than readState.Index' (duration: 56.05185ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T16:31:37.380265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.194973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-04-16T16:31:37.380303Z","caller":"traceutil/trace.go:171","msg":"trace[1822026723] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:1229; }","duration":"107.269475ms","start":"2026-04-16T16:31:37.273023Z","end":"2026-04-16T16:31:37.380292Z","steps":["trace[1822026723] 'agreement among raft nodes before linearized reading' (duration: 107.175563ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:31:38.691734Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976531765991804303,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-16T16:31:39.192933Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976531765991804303,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-16T16:31:39.487334Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.298821577s","expected-duration":"1s"} {"level":"info","ts":"2026-04-16T16:31:39.487779Z","caller":"traceutil/trace.go:171","msg":"trace[1517163009] linearizableReadLoop","detail":"{readStateIndex:1280; appliedIndex:1279; }","duration":"1.296283301s","start":"2026-04-16T16:31:38.191476Z","end":"2026-04-16T16:31:39.48776Z","steps":["trace[1517163009] 'read index received' (duration: 1.296144788s)","trace[1517163009] 'applied index is now lower than readState.Index' (duration: 137.753µs)"],"step_count":2} {"level":"info","ts":"2026-04-16T16:31:39.487804Z","caller":"traceutil/trace.go:171","msg":"trace[274778293] transaction","detail":"{read_only:false; response_revision:1233; number_of_response:1; }","duration":"1.299462392s","start":"2026-04-16T16:31:38.188327Z","end":"2026-04-16T16:31:39.48779Z","steps":["trace[274778293] 'process raft request' (duration: 1.299303369s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:31:39.487907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:31:38.188298Z","time spent":"1.299550654s","remote":"127.0.0.1:60790","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-04-16T16:31:39.487959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.296465075s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-16T16:31:39.487992Z","caller":"traceutil/trace.go:171","msg":"trace[1238140642] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1233; }","duration":"1.296546236s","start":"2026-04-16T16:31:38.191434Z","end":"2026-04-16T16:31:39.487981Z","steps":["trace[1238140642] 'agreement among raft nodes before linearized reading' (duration: 1.296423044s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:31:39.488022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:31:38.191416Z","time spent":"1.296599418s","remote":"127.0.0.1:60790","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-16T16:31:39.951342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.340676ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T16:31:39.951431Z","caller":"traceutil/trace.go:171","msg":"trace[1968821289] linearizableReadLoop","detail":"{readStateIndex:1281; appliedIndex:1280; }","duration":"463.579565ms","start":"2026-04-16T16:31:39.487837Z","end":"2026-04-16T16:31:39.951416Z","steps":["trace[1968821289] 'read index received' (duration: 71.79193ms)","trace[1968821289] 'applied index is now lower than readState.Index' (duration: 391.786415ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T16:31:39.951493Z","caller":"traceutil/trace.go:171","msg":"trace[1977643433] transaction","detail":"{read_only:false; response_revision:1234; number_of_response:1; }","duration":"467.718707ms","start":"2026-04-16T16:31:39.483764Z","end":"2026-04-16T16:31:39.951483Z","steps":["trace[1977643433] 'process raft request' (duration: 75.88729ms)","trace[1977643433] 'compare' (duration: 391.238224ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T16:31:39.95155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:31:39.483744Z","time spent":"467.779507ms","remote":"127.0.0.1:60790","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-04-16T16:31:39.951634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.737762076s","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-04-16T16:31:39.951704Z","caller":"traceutil/trace.go:171","msg":"trace[184965148] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1234; }","duration":"1.737860189s","start":"2026-04-16T16:31:38.213825Z","end":"2026-04-16T16:31:39.951685Z","steps":["trace[184965148] 'agreement among raft nodes before linearized reading' (duration: 1.737675305s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:31:39.951743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:31:38.213813Z","time spent":"1.7379217s","remote":"127.0.0.1:60790","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-04-16T16:31:39.951804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.758774942s","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-04-16T16:31:39.95186Z","caller":"traceutil/trace.go:171","msg":"trace[20029064] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1234; }","duration":"1.758842354s","start":"2026-04-16T16:31:38.193008Z","end":"2026-04-16T16:31:39.95185Z","steps":["trace[20029064] 'agreement among raft nodes before linearized reading' (duration: 1.758704681s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:31:39.95189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:31:38.192968Z","time spent":"1.758915195s","remote":"127.0.0.1:60790","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-16T16:31:39.95196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.40599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-16T16:31:39.951996Z","caller":"traceutil/trace.go:171","msg":"trace[1013372687] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1234; }","duration":"486.463722ms","start":"2026-04-16T16:31:39.465523Z","end":"2026-04-16T16:31:39.951987Z","steps":["trace[1013372687] 'agreement among raft nodes before linearized reading' (duration: 486.37321ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:31:39.952023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:31:39.465504Z","time spent":"486.512562ms","remote":"127.0.0.1:60790","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-16T16:31:39.952111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.298750047s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-16T16:31:39.952149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.75962662s","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-04-16T16:31:39.952206Z","caller":"traceutil/trace.go:171","msg":"trace[104659537] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:1234; }","duration":"1.2989032s","start":"2026-04-16T16:31:38.653283Z","end":"2026-04-16T16:31:39.952186Z","steps":["trace[104659537] 'agreement among raft nodes before linearized reading' (duration: 1.298721246s)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:31:39.952218Z","caller":"traceutil/trace.go:171","msg":"trace[871702232] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1234; }","duration":"1.759785594s","start":"2026-04-16T16:31:38.192415Z","end":"2026-04-16T16:31:39.952201Z","steps":["trace[871702232] 'agreement among raft nodes before linearized reading' (duration: 1.759550149s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:31:39.952261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:31:38.653227Z","time spent":"1.299021922s","remote":"127.0.0.1:60886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":29,"request content":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true "} {"level":"warn","ts":"2026-04-16T16:31:39.952263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:31:38.192398Z","time spent":"1.759854565s","remote":"127.0.0.1:60790","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-04-16T16:31:40.001939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.774361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:31:40.001964Z","caller":"traceutil/trace.go:171","msg":"trace[2100058339] transaction","detail":"{read_only:false; response_revision:1235; number_of_response:1; }","duration":"509.199205ms","start":"2026-04-16T16:31:39.492732Z","end":"2026-04-16T16:31:40.001931Z","steps":["trace[2100058339] 'process raft request' (duration: 509.013071ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:31:40.002093Z","caller":"traceutil/trace.go:171","msg":"trace[1451955793] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1235; }","duration":"380.961445ms","start":"2026-04-16T16:31:39.621115Z","end":"2026-04-16T16:31:40.002076Z","steps":["trace[1451955793] 'agreement among raft nodes before linearized reading' (duration: 380.75029ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:31:40.002225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:31:39.492677Z","time spent":"509.46254ms","remote":"127.0.0.1:60790","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-04-16T16:31:40.002232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:31:39.621099Z","time spent":"381.120789ms","remote":"127.0.0.1:60490","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-16T16:31:40.430929Z","caller":"traceutil/trace.go:171","msg":"trace[422822243] linearizableReadLoop","detail":"{readStateIndex:1286; appliedIndex:1282; }","duration":"427.634649ms","start":"2026-04-16T16:31:40.00327Z","end":"2026-04-16T16:31:40.430905Z","steps":["trace[422822243] 'read index received' (duration: 427.006715ms)","trace[422822243] 'applied index is now lower than readState.Index' (duration: 627.134µs)"],"step_count":2} {"level":"warn","ts":"2026-04-16T16:31:40.43105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.765181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:31:40.431075Z","caller":"traceutil/trace.go:171","msg":"trace[1115652270] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1239; }","duration":"427.828703ms","start":"2026-04-16T16:31:40.00324Z","end":"2026-04-16T16:31:40.431069Z","steps":["trace[1115652270] 'agreement among raft nodes before linearized reading' (duration: 427.737741ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:31:40.431098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:31:40.003226Z","time spent":"427.866424ms","remote":"127.0.0.1:60488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-16T16:31:40.431284Z","caller":"traceutil/trace.go:171","msg":"trace[1610920644] transaction","detail":"{read_only:false; response_revision:1236; number_of_response:1; }","duration":"475.946348ms","start":"2026-04-16T16:31:39.954987Z","end":"2026-04-16T16:31:40.430934Z","steps":["trace[1610920644] 'process raft request' (duration: 475.357305ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:31:40.431599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:31:39.95497Z","time spent":"476.530631ms","remote":"127.0.0.1:60790","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T16:31:40.431348Z","caller":"traceutil/trace.go:171","msg":"trace[1687636025] transaction","detail":"{read_only:false; response_revision:1238; number_of_response:1; }","duration":"474.134268ms","start":"2026-04-16T16:31:39.957204Z","end":"2026-04-16T16:31:40.431338Z","steps":["trace[1687636025] 'process raft request' (duration: 473.641907ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:31:40.431375Z","caller":"traceutil/trace.go:171","msg":"trace[1001840008] transaction","detail":"{read_only:false; response_revision:1237; number_of_response:1; }","duration":"474.22941ms","start":"2026-04-16T16:31:39.957141Z","end":"2026-04-16T16:31:40.43137Z","steps":["trace[1001840008] 'process raft request' (duration: 473.675718ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:31:40.43202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:31:39.957123Z","time spent":"474.845314ms","remote":"127.0.0.1:60790","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T16:31:40.431407Z","caller":"traceutil/trace.go:171","msg":"trace[1176666703] transaction","detail":"{read_only:false; response_revision:1239; number_of_response:1; }","duration":"473.509024ms","start":"2026-04-16T16:31:39.95788Z","end":"2026-04-16T16:31:40.431389Z","steps":["trace[1176666703] 'process raft request' (duration: 472.990153ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:31:40.432348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:31:39.957874Z","time spent":"474.443675ms","remote":"127.0.0.1:60790","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T16:31:40.434643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:31:39.957196Z","time spent":"474.667529ms","remote":"127.0.0.1:60790","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-04-16T16:31:41.725499Z","caller":"traceutil/trace.go:171","msg":"trace[1746566062] linearizableReadLoop","detail":"{readStateIndex:1287; appliedIndex:1286; }","duration":"107.117962ms","start":"2026-04-16T16:31:41.61836Z","end":"2026-04-16T16:31:41.725478Z","steps":["trace[1746566062] 'read index received' (duration: 106.904367ms)","trace[1746566062] 'applied index is now lower than readState.Index' (duration: 212.685µs)"],"step_count":2} {"level":"info","ts":"2026-04-16T16:31:41.725583Z","caller":"traceutil/trace.go:171","msg":"trace[950007437] transaction","detail":"{read_only:false; response_revision:1240; number_of_response:1; }","duration":"232.065378ms","start":"2026-04-16T16:31:41.493481Z","end":"2026-04-16T16:31:41.725547Z","steps":["trace[950007437] 'process raft request' (duration: 231.854684ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:31:41.725658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.284615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:31:41.72574Z","caller":"traceutil/trace.go:171","msg":"trace[1561106674] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1240; }","duration":"107.412708ms","start":"2026-04-16T16:31:41.618308Z","end":"2026-04-16T16:31:41.725721Z","steps":["trace[1561106674] 'agreement among raft nodes before linearized reading' (duration: 107.266935ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:31:42.62973Z","caller":"traceutil/trace.go:171","msg":"trace[1596643658] transaction","detail":"{read_only:false; response_revision:1243; number_of_response:1; }","duration":"189.433695ms","start":"2026-04-16T16:31:42.440278Z","end":"2026-04-16T16:31:42.629712Z","steps":["trace[1596643658] 'process raft request' (duration: 189.327603ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:31:43.590062Z","caller":"traceutil/trace.go:171","msg":"trace[607474454] transaction","detail":"{read_only:false; response_revision:1246; number_of_response:1; }","duration":"1.147644241s","start":"2026-04-16T16:31:42.442395Z","end":"2026-04-16T16:31:43.59004Z","steps":["trace[607474454] 'process raft request' (duration: 1.14760226s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:31:43.5902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:31:42.442387Z","time spent":"1.147746323s","remote":"127.0.0.1:60790","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-04-16T16:31:43.590423Z","caller":"traceutil/trace.go:171","msg":"trace[2110524525] transaction","detail":"{read_only:false; response_revision:1244; number_of_response:1; }","duration":"1.149983872s","start":"2026-04-16T16:31:42.440426Z","end":"2026-04-16T16:31:43.59041Z","steps":["trace[2110524525] 'process raft request' (duration: 1.070691497s)","trace[2110524525] 'compare' (duration: 78.271462ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T16:31:43.590487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:31:42.440421Z","time spent":"1.150036044s","remote":"127.0.0.1:60790","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T16:31:43.590528Z","caller":"traceutil/trace.go:171","msg":"trace[1302391357] transaction","detail":"{read_only:false; response_revision:1245; number_of_response:1; }","duration":"1.148706674s","start":"2026-04-16T16:31:42.4418Z","end":"2026-04-16T16:31:43.590507Z","steps":["trace[1302391357] 'process raft request' (duration: 1.14809621s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:31:43.590683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:31:42.441778Z","time spent":"1.148823327s","remote":"127.0.0.1:60790","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T16:31:43.816643Z","caller":"traceutil/trace.go:171","msg":"trace[496987116] linearizableReadLoop","detail":"{readStateIndex:1295; appliedIndex:1294; }","duration":"198.622988ms","start":"2026-04-16T16:31:43.617968Z","end":"2026-04-16T16:31:43.816591Z","steps":["trace[496987116] 'read index received' (duration: 198.347592ms)","trace[496987116] 'applied index is now lower than readState.Index' (duration: 274.336µs)"],"step_count":2} {"level":"warn","ts":"2026-04-16T16:31:43.816809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.826083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:31:43.81686Z","caller":"traceutil/trace.go:171","msg":"trace[1604951603] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1247; }","duration":"198.932875ms","start":"2026-04-16T16:31:43.617913Z","end":"2026-04-16T16:31:43.816846Z","steps":["trace[1604951603] 'agreement among raft nodes before linearized reading' (duration: 198.818922ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:31:43.81688Z","caller":"traceutil/trace.go:171","msg":"trace[476211537] transaction","detail":"{read_only:false; response_revision:1247; number_of_response:1; }","duration":"1.129610602s","start":"2026-04-16T16:31:42.687234Z","end":"2026-04-16T16:31:43.816844Z","steps":["trace[476211537] 'process raft request' (duration: 1.129189933s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:31:43.817092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:31:42.687211Z","time spent":"1.129740975s","remote":"127.0.0.1:60678","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5883,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T16:31:43.936516Z","caller":"traceutil/trace.go:171","msg":"trace[1700187633] transaction","detail":"{read_only:false; response_revision:1248; number_of_response:1; }","duration":"202.463613ms","start":"2026-04-16T16:31:43.734028Z","end":"2026-04-16T16:31:43.936492Z","steps":["trace[1700187633] 'process raft request' (duration: 192.207446ms)","trace[1700187633] 'compare' (duration: 10.112754ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T16:31:43.9367Z","caller":"traceutil/trace.go:171","msg":"trace[2049282662] linearizableReadLoop","detail":"{readStateIndex:1297; appliedIndex:1295; }","duration":"118.8154ms","start":"2026-04-16T16:31:43.817839Z","end":"2026-04-16T16:31:43.936655Z","steps":["trace[2049282662] 'read index received' (duration: 108.36961ms)","trace[2049282662] 'applied index is now lower than readState.Index' (duration: 10.44384ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T16:31:43.936828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.967275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:31:43.936873Z","caller":"traceutil/trace.go:171","msg":"trace[660907460] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1249; }","duration":"119.064027ms","start":"2026-04-16T16:31:43.817797Z","end":"2026-04-16T16:31:43.936861Z","steps":["trace[660907460] 'agreement among raft nodes before linearized reading' (duration: 118.958294ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:31:43.937053Z","caller":"traceutil/trace.go:171","msg":"trace[1976128441] transaction","detail":"{read_only:false; response_revision:1249; number_of_response:1; }","duration":"153.06695ms","start":"2026-04-16T16:31:43.783968Z","end":"2026-04-16T16:31:43.937035Z","steps":["trace[1976128441] 'process raft request' (duration: 152.483566ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:31:44.309644Z","caller":"traceutil/trace.go:171","msg":"trace[1667720252] transaction","detail":"{read_only:false; response_revision:1254; number_of_response:1; }","duration":"183.006513ms","start":"2026-04-16T16:31:44.126609Z","end":"2026-04-16T16:31:44.309615Z","steps":["trace[1667720252] 'process raft request' (duration: 182.939852ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:31:44.30972Z","caller":"traceutil/trace.go:171","msg":"trace[927684057] transaction","detail":"{read_only:false; response_revision:1253; number_of_response:1; }","duration":"185.18089ms","start":"2026-04-16T16:31:44.124515Z","end":"2026-04-16T16:31:44.309695Z","steps":["trace[927684057] 'process raft request' (duration: 184.874823ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:31:44.979807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.215621ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-16T16:31:44.980247Z","caller":"traceutil/trace.go:171","msg":"trace[1951101472] linearizableReadLoop","detail":"{readStateIndex:1304; appliedIndex:1302; }","duration":"519.274947ms","start":"2026-04-16T16:31:44.460955Z","end":"2026-04-16T16:31:44.98023Z","steps":["trace[1951101472] 'read index received' (duration: 165.578196ms)","trace[1951101472] 'applied index is now lower than readState.Index' (duration: 353.695641ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T16:31:44.980497Z","caller":"traceutil/trace.go:171","msg":"trace[551466579] transaction","detail":"{read_only:false; response_revision:1256; number_of_response:1; }","duration":"664.513013ms","start":"2026-04-16T16:31:44.315932Z","end":"2026-04-16T16:31:44.980445Z","steps":["trace[551466579] 'process raft request' (duration: 664.208897ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:31:44.980531Z","caller":"traceutil/trace.go:171","msg":"trace[31692429] transaction","detail":"{read_only:false; response_revision:1255; number_of_response:1; }","duration":"665.547917ms","start":"2026-04-16T16:31:44.314947Z","end":"2026-04-16T16:31:44.980495Z","steps":["trace[31692429] 'process raft request' (duration: 311.57675ms)","trace[31692429] 'compare' (duration: 353.069798ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T16:31:44.98059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:31:44.315914Z","time spent":"664.627156ms","remote":"127.0.0.1:60790","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":458,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T16:31:44.980669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:31:44.314931Z","time spent":"665.68049ms","remote":"127.0.0.1:60566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":773,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-16T16:31:44.980821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.399324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:31:44.980874Z","caller":"traceutil/trace.go:171","msg":"trace[1439950716] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1256; }","duration":"362.498706ms","start":"2026-04-16T16:31:44.618363Z","end":"2026-04-16T16:31:44.980861Z","steps":["trace[1439950716] 'agreement among raft nodes before linearized reading' (duration: 362.401464ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:31:44.980908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:31:44.61834Z","time spent":"362.560817ms","remote":"127.0.0.1:60490","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-04-16T16:31:44.981012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"520.015833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-16T16:31:44.981062Z","caller":"traceutil/trace.go:171","msg":"trace[121696321] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:1256; }","duration":"520.150906ms","start":"2026-04-16T16:31:44.460899Z","end":"2026-04-16T16:31:44.981049Z","steps":["trace[121696321] 'agreement among raft nodes before linearized reading' (duration: 520.018653ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:31:44.981101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:31:44.460871Z","time spent":"520.221648ms","remote":"127.0.0.1:32788","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-04-16T16:31:44.981235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.272423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:31:44.981424Z","caller":"traceutil/trace.go:171","msg":"trace[612108834] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:1256; }","duration":"190.688302ms","start":"2026-04-16T16:31:44.79062Z","end":"2026-04-16T16:31:44.981309Z","steps":["trace[612108834] 'agreement among raft nodes before linearized reading' (duration: 190.234802ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:31:44.98163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.907593ms","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-04-16T16:31:44.981675Z","caller":"traceutil/trace.go:171","msg":"trace[672495534] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1256; }","duration":"347.994995ms","start":"2026-04-16T16:31:44.633668Z","end":"2026-04-16T16:31:44.981663Z","steps":["trace[672495534] 'agreement among raft nodes before linearized reading' (duration: 347.845331ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:31:44.981704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:31:44.633651Z","time spent":"348.047087ms","remote":"127.0.0.1:60790","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-04-16T16:31:45.358545Z","caller":"traceutil/trace.go:171","msg":"trace[93226056] transaction","detail":"{read_only:false; response_revision:1257; number_of_response:1; }","duration":"372.780784ms","start":"2026-04-16T16:31:44.985734Z","end":"2026-04-16T16:31:45.358515Z","steps":["trace[93226056] 'process raft request' (duration: 281.221607ms)","trace[93226056] 'compare' (duration: 91.453545ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T16:31:45.358695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:31:44.985715Z","time spent":"372.899367ms","remote":"127.0.0.1:60790","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-04-16T16:31:45.786294Z","caller":"traceutil/trace.go:171","msg":"trace[1376191384] transaction","detail":"{read_only:false; response_revision:1258; number_of_response:1; }","duration":"185.358434ms","start":"2026-04-16T16:31:45.600908Z","end":"2026-04-16T16:31:45.786267Z","steps":["trace[1376191384] 'process raft request' (duration: 185.18899ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:31:46.087296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.689798ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T16:31:46.08747Z","caller":"traceutil/trace.go:171","msg":"trace[639727921] transaction","detail":"{read_only:false; response_revision:1259; number_of_response:1; }","duration":"486.41884ms","start":"2026-04-16T16:31:45.60103Z","end":"2026-04-16T16:31:46.087449Z","steps":["trace[639727921] 'process raft request' (duration: 311.497067ms)","trace[639727921] 'compare' (duration: 174.298639ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T16:31:46.087537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:31:45.601019Z","time spent":"486.484401ms","remote":"127.0.0.1:60790","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T16:31:46.087975Z","caller":"traceutil/trace.go:171","msg":"trace[82892455] transaction","detail":"{read_only:false; response_revision:1260; number_of_response:1; }","duration":"484.375484ms","start":"2026-04-16T16:31:45.603566Z","end":"2026-04-16T16:31:46.087942Z","steps":["trace[82892455] 'process raft request' (duration: 483.825423ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:31:46.088134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:31:45.603555Z","time spent":"484.491007ms","remote":"127.0.0.1:60790","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-04-16T16:31:46.088312Z","caller":"traceutil/trace.go:171","msg":"trace[93542957] transaction","detail":"{read_only:false; response_revision:1261; number_of_response:1; }","duration":"144.452708ms","start":"2026-04-16T16:31:45.943631Z","end":"2026-04-16T16:31:46.088084Z","steps":["trace[93542957] 'process raft request' (duration: 144.036599ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:31:50.817851Z","caller":"traceutil/trace.go:171","msg":"trace[1921093715] transaction","detail":"{read_only:false; response_revision:1309; number_of_response:1; }","duration":"119.775692ms","start":"2026-04-16T16:31:50.698035Z","end":"2026-04-16T16:31:50.81781Z","steps":["trace[1921093715] 'process raft request' (duration: 119.68837ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:31:50.817904Z","caller":"traceutil/trace.go:171","msg":"trace[105497819] transaction","detail":"{read_only:false; response_revision:1308; number_of_response:1; }","duration":"123.856732ms","start":"2026-04-16T16:31:50.694005Z","end":"2026-04-16T16:31:50.817862Z","steps":["trace[105497819] 'process raft request' (duration: 93.003209ms)","trace[105497819] 'compare' (duration: 30.577857ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T16:31:52.623806Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976531765991804447,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-16T16:31:53.124354Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976531765991804447,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-16T16:31:53.625466Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976531765991804447,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-16T16:31:54.126042Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976531765991804447,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-16T16:31:54.1681Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.036269946s","expected-duration":"1s"} {"level":"info","ts":"2026-04-16T16:31:54.168587Z","caller":"traceutil/trace.go:171","msg":"trace[997305868] transaction","detail":"{read_only:false; response_revision:1313; number_of_response:1; }","duration":"2.059128902s","start":"2026-04-16T16:31:52.109432Z","end":"2026-04-16T16:31:54.168561Z","steps":["trace[997305868] 'process raft request' (duration: 2.058850456s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:31:54.168815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:31:52.109407Z","time spent":"2.059263525s","remote":"127.0.0.1:60790","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T16:31:54.194128Z","caller":"traceutil/trace.go:171","msg":"trace[1828468553] linearizableReadLoop","detail":"{readStateIndex:1363; appliedIndex:1361; }","duration":"2.071337532s","start":"2026-04-16T16:31:52.122765Z","end":"2026-04-16T16:31:54.194103Z","steps":["trace[1828468553] 'read index received' (duration: 2.045658073s)","trace[1828468553] 'applied index is now lower than readState.Index' (duration: 25.677519ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T16:31:54.194452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.070940814s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-04-16T16:31:54.194469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.071680789s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-04-16T16:31:54.194537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"528.682296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:31:54.194555Z","caller":"traceutil/trace.go:171","msg":"trace[359827737] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1313; }","duration":"2.071085227s","start":"2026-04-16T16:31:52.123433Z","end":"2026-04-16T16:31:54.194519Z","steps":["trace[359827737] 'agreement among raft nodes before linearized reading' (duration: 2.070856972s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:31:54.194604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:31:52.123426Z","time spent":"2.071169939s","remote":"127.0.0.1:60790","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-04-16T16:31:54.194572Z","caller":"traceutil/trace.go:171","msg":"trace[65916879] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1313; }","duration":"2.071824693s","start":"2026-04-16T16:31:52.122731Z","end":"2026-04-16T16:31:54.194556Z","steps":["trace[65916879] 'agreement among raft nodes before linearized reading' (duration: 2.071528717s)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:31:54.194622Z","caller":"traceutil/trace.go:171","msg":"trace[548651334] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:1313; }","duration":"528.762708ms","start":"2026-04-16T16:31:53.6658Z","end":"2026-04-16T16:31:54.194562Z","steps":["trace[548651334] 'agreement among raft nodes before linearized reading' (duration: 528.718237ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:31:54.194664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:31:53.665763Z","time spent":"528.893051ms","remote":"127.0.0.1:60656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":29,"request content":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true "} {"level":"warn","ts":"2026-04-16T16:31:54.194675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:31:52.122717Z","time spent":"2.071940755s","remote":"127.0.0.1:60790","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-04-16T16:31:54.194749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.951603051s","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:530"} {"level":"info","ts":"2026-04-16T16:31:54.194807Z","caller":"traceutil/trace.go:171","msg":"trace[1832063811] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1313; }","duration":"1.951685923s","start":"2026-04-16T16:31:52.243104Z","end":"2026-04-16T16:31:54.19479Z","steps":["trace[1832063811] 'agreement among raft nodes before linearized reading' (duration: 1.95152698s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:31:54.194864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:31:52.243085Z","time spent":"1.951763234s","remote":"127.0.0.1:60790","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":554,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-04-16T16:31:54.194911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.027191295s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-04-16T16:31:54.19447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.77793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:31:54.194954Z","caller":"traceutil/trace.go:171","msg":"trace[840901472] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1313; }","duration":"2.027264246s","start":"2026-04-16T16:31:52.167679Z","end":"2026-04-16T16:31:54.194943Z","steps":["trace[840901472] 'agreement among raft nodes before linearized reading' (duration: 2.027153474s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:31:54.194985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:31:52.167663Z","time spent":"2.027314637s","remote":"127.0.0.1:60790","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-04-16T16:31:54.194995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"797.872495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-04-16T16:31:54.19499Z","caller":"traceutil/trace.go:171","msg":"trace[87296890] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1313; }","duration":"273.341132ms","start":"2026-04-16T16:31:53.921631Z","end":"2026-04-16T16:31:54.194972Z","steps":["trace[87296890] 'agreement among raft nodes before linearized reading' (duration: 272.77896ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:31:54.195023Z","caller":"traceutil/trace.go:171","msg":"trace[1600611129] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1313; }","duration":"797.961808ms","start":"2026-04-16T16:31:53.397051Z","end":"2026-04-16T16:31:54.195013Z","steps":["trace[1600611129] 'agreement among raft nodes before linearized reading' (duration: 797.853036ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:31:54.195047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:31:53.39704Z","time spent":"798.001529ms","remote":"127.0.0.1:60790","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":559,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-04-16T16:31:54.194828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.878811569s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-04-16T16:31:54.19518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.576634678s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T16:31:54.195298Z","caller":"traceutil/trace.go:171","msg":"trace[325548614] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1313; }","duration":"1.576823313s","start":"2026-04-16T16:31:52.618452Z","end":"2026-04-16T16:31:54.195275Z","steps":["trace[325548614] 'agreement among raft nodes before linearized reading' (duration: 1.576637659s)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:31:54.195299Z","caller":"traceutil/trace.go:171","msg":"trace[146792306] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1313; }","duration":"1.87933516s","start":"2026-04-16T16:31:52.315886Z","end":"2026-04-16T16:31:54.195221Z","steps":["trace[146792306] 'agreement among raft nodes before linearized reading' (duration: 1.878479642s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:31:54.195405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:31:52.61843Z","time spent":"1.576958756s","remote":"127.0.0.1:60490","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-04-16T16:31:54.194572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.528833751s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-16T16:31:54.195538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"799.248067ms","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-04-16T16:31:54.195567Z","caller":"traceutil/trace.go:171","msg":"trace[970127273] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1313; }","duration":"799.319999ms","start":"2026-04-16T16:31:53.39624Z","end":"2026-04-16T16:31:54.19556Z","steps":["trace[970127273] 'agreement among raft nodes before linearized reading' (duration: 798.354657ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:31:54.195555Z","caller":"traceutil/trace.go:171","msg":"trace[1857698886] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:1313; }","duration":"1.529844984s","start":"2026-04-16T16:31:52.665691Z","end":"2026-04-16T16:31:54.195536Z","steps":["trace[1857698886] 'agreement among raft nodes before linearized reading' (duration: 1.52878781s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:31:54.195601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:31:53.396219Z","time spent":"799.370601ms","remote":"127.0.0.1:60790","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-04-16T16:31:54.195619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:31:52.665672Z","time spent":"1.529926826s","remote":"127.0.0.1:58932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-16T16:31:54.195604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.799316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-16T16:31:54.195727Z","caller":"traceutil/trace.go:171","msg":"trace[687464603] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1313; }","duration":"302.99394ms","start":"2026-04-16T16:31:53.892707Z","end":"2026-04-16T16:31:54.195701Z","steps":["trace[687464603] 'agreement among raft nodes before linearized reading' (duration: 302.60315ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:31:54.195803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:31:53.892684Z","time spent":"303.108762ms","remote":"127.0.0.1:60790","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-16T16:31:54.195495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T16:31:52.315854Z","time spent":"1.879579297s","remote":"127.0.0.1:60790","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-16T16:32:00.376933Z","caller":"traceutil/trace.go:171","msg":"trace[795345437] transaction","detail":"{read_only:false; response_revision:1348; number_of_response:1; }","duration":"106.482948ms","start":"2026-04-16T16:32:00.270431Z","end":"2026-04-16T16:32:00.376914Z","steps":["trace[795345437] 'process raft request' (duration: 106.311654ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:32:00.377176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.584999ms","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:530"} {"level":"info","ts":"2026-04-16T16:32:00.376919Z","caller":"traceutil/trace.go:171","msg":"trace[1124624654] transaction","detail":"{read_only:false; response_revision:1347; number_of_response:1; }","duration":"107.659084ms","start":"2026-04-16T16:32:00.269228Z","end":"2026-04-16T16:32:00.376887Z","steps":["trace[1124624654] 'process raft request' (duration: 107.390748ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:32:00.377238Z","caller":"traceutil/trace.go:171","msg":"trace[1412357997] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1348; }","duration":"105.699621ms","start":"2026-04-16T16:32:00.271524Z","end":"2026-04-16T16:32:00.377224Z","steps":["trace[1412357997] 'agreement among raft nodes before linearized reading' (duration: 105.517887ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:32:00.376817Z","caller":"traceutil/trace.go:171","msg":"trace[1696835900] linearizableReadLoop","detail":"{readStateIndex:1399; appliedIndex:1396; }","duration":"105.230401ms","start":"2026-04-16T16:32:00.27156Z","end":"2026-04-16T16:32:00.376791Z","steps":["trace[1696835900] 'read index received' (duration: 37.920021ms)","trace[1696835900] 'applied index is now lower than readState.Index' (duration: 67.30917ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T16:32:02.460653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.924824ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-04-16T16:32:02.460756Z","caller":"traceutil/trace.go:171","msg":"trace[462526278] linearizableReadLoop","detail":"{readStateIndex:1404; appliedIndex:1403; }","duration":"191.07744ms","start":"2026-04-16T16:32:02.269662Z","end":"2026-04-16T16:32:02.460739Z","steps":["trace[462526278] 'read index received' (duration: 72.032824ms)","trace[462526278] 'applied index is now lower than readState.Index' (duration: 119.043116ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T16:32:02.461258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.593402ms","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-04-16T16:32:02.461311Z","caller":"traceutil/trace.go:171","msg":"trace[207618630] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1352; }","duration":"191.690834ms","start":"2026-04-16T16:32:02.269607Z","end":"2026-04-16T16:32:02.461298Z","steps":["trace[207618630] 'agreement among raft nodes before linearized reading' (duration: 191.522801ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T16:32:02.461748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.558742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-16T16:32:02.461849Z","caller":"traceutil/trace.go:171","msg":"trace[1818325752] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1352; }","duration":"112.714616ms","start":"2026-04-16T16:32:02.349113Z","end":"2026-04-16T16:32:02.461828Z","steps":["trace[1818325752] 'agreement among raft nodes before linearized reading' (duration: 112.44227ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:32:02.606115Z","caller":"traceutil/trace.go:171","msg":"trace[9202637] transaction","detail":"{read_only:false; response_revision:1353; number_of_response:1; }","duration":"139.972679ms","start":"2026-04-16T16:32:02.466048Z","end":"2026-04-16T16:32:02.606021Z","steps":["trace[9202637] 'process raft request' (duration: 139.737454ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:32:02.736844Z","caller":"traceutil/trace.go:171","msg":"trace[506473563] transaction","detail":"{read_only:false; response_revision:1354; number_of_response:1; }","duration":"270.774276ms","start":"2026-04-16T16:32:02.466048Z","end":"2026-04-16T16:32:02.736823Z","steps":["trace[506473563] 'process raft request' (duration: 203.69868ms)","trace[506473563] 'compare' (duration: 66.888091ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T16:32:02.737031Z","caller":"traceutil/trace.go:171","msg":"trace[1705817867] transaction","detail":"{read_only:false; response_revision:1355; number_of_response:1; }","duration":"270.916519ms","start":"2026-04-16T16:32:02.46609Z","end":"2026-04-16T16:32:02.737007Z","steps":["trace[1705817867] 'process raft request' (duration: 270.704904ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:32:02.737158Z","caller":"traceutil/trace.go:171","msg":"trace[234227152] transaction","detail":"{read_only:false; response_revision:1356; number_of_response:1; }","duration":"271.004611ms","start":"2026-04-16T16:32:02.466143Z","end":"2026-04-16T16:32:02.737148Z","steps":["trace[234227152] 'process raft request' (duration: 270.785366ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:32:02.737161Z","caller":"traceutil/trace.go:171","msg":"trace[1893881740] transaction","detail":"{read_only:false; response_revision:1358; number_of_response:1; }","duration":"270.201264ms","start":"2026-04-16T16:32:02.466941Z","end":"2026-04-16T16:32:02.737142Z","steps":["trace[1893881740] 'process raft request' (duration: 270.168863ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T16:32:02.73727Z","caller":"traceutil/trace.go:171","msg":"trace[1727487850] transaction","detail":"{read_only:false; response_revision:1357; number_of_response:1; }","duration":"270.403618ms","start":"2026-04-16T16:32:02.466838Z","end":"2026-04-16T16:32:02.737242Z","steps":["trace[1727487850] 'process raft request' (duration: 270.241044ms)"],"step_count":1}