{"level":"warn","ts":"2026-01-29T15:38:31.127054Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-01-29T15:38:31.127127Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.70: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.70:2380","--initial-cluster=instance=https://199.204.45.70:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.70:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.70: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-01-29T15:38:31.127189Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-01-29T15:38:31.127197Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.70:2380"]} {"level":"info","ts":"2026-01-29T15:38:31.127212Z","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-01-29T15:38:31.127746Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.70:2379"]} {"level":"info","ts":"2026-01-29T15:38:31.127812Z","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":2,"max-cpu-available":2,"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.70:2380"],"listen-peer-urls":["https://199.204.45.70:2380"],"advertise-client-urls":["https://199.204.45.70:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.70:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.70: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-01-29T15:38:31.134844Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.843332ms"} {"level":"info","ts":"2026-01-29T15:38:31.141966Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f8844a9a4263041d","cluster-id":"4749b7c221ae0648"} {"level":"info","ts":"2026-01-29T15:38:31.142144Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d switched to configuration voters=()"} {"level":"info","ts":"2026-01-29T15:38:31.142171Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d became follower at term 0"} {"level":"info","ts":"2026-01-29T15:38:31.142252Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f8844a9a4263041d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-01-29T15:38:31.142266Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d became follower at term 1"} {"level":"info","ts":"2026-01-29T15:38:31.142372Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d switched to configuration voters=(17907520044731139101)"} {"level":"warn","ts":"2026-01-29T15:38:31.150497Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-01-29T15:38:31.153173Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-01-29T15:38:31.155995Z","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-01-29T15:38:31.162587Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f8844a9a4263041d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-01-29T15:38:31.165153Z","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-01-29T15:38:31.165419Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f8844a9a4263041d","initial-advertise-peer-urls":["https://199.204.45.70:2380"],"listen-peer-urls":["https://199.204.45.70:2380"],"advertise-client-urls":["https://199.204.45.70:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.70:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-01-29T15:38:31.165444Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-01-29T15:38:31.165554Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f8844a9a4263041d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-01-29T15:38:31.165704Z","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-01-29T15:38:31.165728Z","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-01-29T15:38:31.16574Z","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-01-29T15:38:31.165996Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d switched to configuration voters=(17907520044731139101)"} {"level":"info","ts":"2026-01-29T15:38:31.166254Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"4749b7c221ae0648","local-member-id":"f8844a9a4263041d","added-peer-id":"f8844a9a4263041d","added-peer-peer-urls":["https://199.204.45.70:2380"]} {"level":"info","ts":"2026-01-29T15:38:31.166332Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.70:2380"} {"level":"info","ts":"2026-01-29T15:38:31.166338Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.70:2380"} {"level":"info","ts":"2026-01-29T15:38:32.143683Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d is starting a new election at term 1"} {"level":"info","ts":"2026-01-29T15:38:32.143732Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d became pre-candidate at term 1"} {"level":"info","ts":"2026-01-29T15:38:32.143752Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d received MsgPreVoteResp from f8844a9a4263041d at term 1"} {"level":"info","ts":"2026-01-29T15:38:32.143848Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d became candidate at term 2"} {"level":"info","ts":"2026-01-29T15:38:32.143861Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d received MsgVoteResp from f8844a9a4263041d at term 2"} {"level":"info","ts":"2026-01-29T15:38:32.143869Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d became leader at term 2"} {"level":"info","ts":"2026-01-29T15:38:32.143922Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f8844a9a4263041d elected leader f8844a9a4263041d at term 2"} {"level":"info","ts":"2026-01-29T15:38:32.896306Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-01-29T15:38:32.89685Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-01-29T15:38:32.899667Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.70:2379"} {"level":"info","ts":"2026-01-29T15:38:32.896805Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f8844a9a4263041d","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.70:2379]}","request-path":"/0/members/f8844a9a4263041d/attributes","cluster-id":"4749b7c221ae0648","publish-timeout":"7s"} {"level":"info","ts":"2026-01-29T15:38:32.905918Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-01-29T15:38:32.906143Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-01-29T15:38:32.906154Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-01-29T15:38:32.907204Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-01-29T15:38:32.949878Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"4749b7c221ae0648","local-member-id":"f8844a9a4263041d","cluster-version":"3.5"} {"level":"info","ts":"2026-01-29T15:38:32.950012Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-01-29T15:38:32.95008Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-01-29T15:38:33.347889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.42172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-01-29T15:38:33.347994Z","caller":"traceutil/trace.go:171","msg":"trace[210750544] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1; }","duration":"368.547861ms","start":"2026-01-29T15:38:32.979437Z","end":"2026-01-29T15:38:33.347985Z","steps":["trace[210750544] 'count revisions from in-memory index tree' (duration: 368.39633ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:38:33.34803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T15:38:32.97943Z","time spent":"368.593152ms","remote":"127.0.0.1:49904","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":0,"response size":27,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"warn","ts":"2026-01-29T15:38:33.348157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.593297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-01-29T15:38:33.348192Z","caller":"traceutil/trace.go:171","msg":"trace[679321279] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1; }","duration":"356.655207ms","start":"2026-01-29T15:38:32.991532Z","end":"2026-01-29T15:38:33.348187Z","steps":["trace[679321279] 'count revisions from in-memory index tree' (duration: 356.559187ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:38:33.348221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T15:38:32.991527Z","time spent":"356.688298ms","remote":"127.0.0.1:49910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":27,"request content":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true "} {"level":"warn","ts":"2026-01-29T15:38:33.348332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.321652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-01-29T15:38:33.348399Z","caller":"traceutil/trace.go:171","msg":"trace[513975923] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1; }","duration":"357.370212ms","start":"2026-01-29T15:38:32.99099Z","end":"2026-01-29T15:38:33.34836Z","steps":["trace[513975923] 'range keys from in-memory index tree' (duration: 356.729418ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:38:33.348492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T15:38:32.990982Z","time spent":"357.502613ms","remote":"127.0.0.1:49910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":57,"response count":0,"response size":27,"request content":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" limit:10000 "} {"level":"warn","ts":"2026-01-29T15:38:33.348614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.619613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-01-29T15:38:33.34867Z","caller":"traceutil/trace.go:171","msg":"trace[1769697358] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:1; }","duration":"344.685583ms","start":"2026-01-29T15:38:33.003979Z","end":"2026-01-29T15:38:33.348665Z","steps":["trace[1769697358] 'range keys from in-memory index tree' (duration: 344.598093ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:38:33.348771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T15:38:33.003974Z","time spent":"344.789124ms","remote":"127.0.0.1:49914","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":27,"request content":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" limit:10000 "} {"level":"warn","ts":"2026-01-29T15:38:33.348886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.690383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-01-29T15:38:33.34894Z","caller":"traceutil/trace.go:171","msg":"trace[21036925] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:1; }","duration":"344.756794ms","start":"2026-01-29T15:38:33.004178Z","end":"2026-01-29T15:38:33.348934Z","steps":["trace[21036925] 'count revisions from in-memory index tree' (duration: 344.671853ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:38:33.34901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T15:38:33.004173Z","time spent":"344.829074ms","remote":"127.0.0.1:49914","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":27,"request content":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true "} {"level":"warn","ts":"2026-01-29T15:38:33.349192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.963704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-01-29T15:38:33.349247Z","caller":"traceutil/trace.go:171","msg":"trace[1493398970] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1; }","duration":"335.084244ms","start":"2026-01-29T15:38:33.014157Z","end":"2026-01-29T15:38:33.349242Z","steps":["trace[1493398970] 'count revisions from in-memory index tree' (duration: 334.942574ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:38:33.349276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T15:38:33.014154Z","time spent":"335.115985ms","remote":"127.0.0.1:49918","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":27,"request content":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true "} {"level":"warn","ts":"2026-01-29T15:38:33.349562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.426966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-01-29T15:38:33.349604Z","caller":"traceutil/trace.go:171","msg":"trace[708892362] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1; }","duration":"335.474107ms","start":"2026-01-29T15:38:33.014125Z","end":"2026-01-29T15:38:33.349599Z","steps":["trace[708892362] 'range keys from in-memory index tree' (duration: 335.173285ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:38:33.349633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T15:38:33.014107Z","time spent":"335.520447ms","remote":"127.0.0.1:49918","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":0,"response size":27,"request content":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" limit:10000 "} {"level":"warn","ts":"2026-01-29T15:38:33.349732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.615511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-01-29T15:38:33.349827Z","caller":"traceutil/trace.go:171","msg":"trace[1917680574] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1; }","duration":"326.699092ms","start":"2026-01-29T15:38:33.023111Z","end":"2026-01-29T15:38:33.34981Z","steps":["trace[1917680574] 'count revisions from in-memory index tree' (duration: 326.594981ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:38:33.349861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T15:38:33.023101Z","time spent":"326.753042ms","remote":"127.0.0.1:49920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true "} {"level":"warn","ts":"2026-01-29T15:38:33.350042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.955654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-01-29T15:38:33.350083Z","caller":"traceutil/trace.go:171","msg":"trace[593976214] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1; }","duration":"326.997904ms","start":"2026-01-29T15:38:33.02308Z","end":"2026-01-29T15:38:33.350078Z","steps":["trace[593976214] 'range keys from in-memory index tree' (duration: 326.895813ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:38:33.350126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T15:38:33.023068Z","time spent":"327.052875ms","remote":"127.0.0.1:49920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" limit:10000 "} {"level":"warn","ts":"2026-01-29T15:38:33.350258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.0254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-01-29T15:38:33.350331Z","caller":"traceutil/trace.go:171","msg":"trace[1533721600] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:1; }","duration":"323.097731ms","start":"2026-01-29T15:38:33.027218Z","end":"2026-01-29T15:38:33.350316Z","steps":["trace[1533721600] 'count revisions from in-memory index tree' (duration: 323.01054ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:38:33.350397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T15:38:33.027215Z","time spent":"323.140421ms","remote":"127.0.0.1:49936","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":0,"response size":27,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"warn","ts":"2026-01-29T15:38:33.350515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.312692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-01-29T15:38:33.350592Z","caller":"traceutil/trace.go:171","msg":"trace[1517219117] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:1; }","duration":"323.386422ms","start":"2026-01-29T15:38:33.027189Z","end":"2026-01-29T15:38:33.350576Z","steps":["trace[1517219117] 'range keys from in-memory index tree' (duration: 323.262222ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:38:33.35065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T15:38:33.027182Z","time spent":"323.461083ms","remote":"127.0.0.1:49936","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":0,"response size":27,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" limit:10000 "} {"level":"warn","ts":"2026-01-29T15:38:33.35073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.247459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-01-29T15:38:33.350792Z","caller":"traceutil/trace.go:171","msg":"trace[850947672] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:1; }","duration":"316.319479ms","start":"2026-01-29T15:38:33.034466Z","end":"2026-01-29T15:38:33.350786Z","steps":["trace[850947672] 'count revisions from in-memory index tree' (duration: 316.228469ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:38:33.35087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T15:38:33.034463Z","time spent":"316.40049ms","remote":"127.0.0.1:49942","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":27,"request content":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true "} {"level":"warn","ts":"2026-01-29T15:38:33.351042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.170766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-01-29T15:38:33.351167Z","caller":"traceutil/trace.go:171","msg":"trace[1894240127] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:1; }","duration":"118.332466ms","start":"2026-01-29T15:38:33.232817Z","end":"2026-01-29T15:38:33.351149Z","steps":["trace[1894240127] 'range keys from in-memory index tree' (duration: 118.019444ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:38:33.351537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.088854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-01-29T15:38:33.351639Z","caller":"traceutil/trace.go:171","msg":"trace[1127439488] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:1; }","duration":"317.147564ms","start":"2026-01-29T15:38:33.034418Z","end":"2026-01-29T15:38:33.351566Z","steps":["trace[1127439488] 'range keys from in-memory index tree' (duration: 317.015874ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:38:33.351679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T15:38:33.034409Z","time spent":"317.259304ms","remote":"127.0.0.1:49942","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":27,"request content":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" limit:10000 "} {"level":"warn","ts":"2026-01-29T15:38:33.352412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.769442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-01-29T15:38:33.352489Z","caller":"traceutil/trace.go:171","msg":"trace[476178133] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:1; }","duration":"119.341211ms","start":"2026-01-29T15:38:33.233141Z","end":"2026-01-29T15:38:33.352482Z","steps":["trace[476178133] 'count revisions from in-memory index tree' (duration: 117.743151ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:38:33.352532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.756837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-01-29T15:38:33.352618Z","caller":"traceutil/trace.go:171","msg":"trace[956134737] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:1; }","duration":"307.874258ms","start":"2026-01-29T15:38:33.044688Z","end":"2026-01-29T15:38:33.352562Z","steps":["trace[956134737] 'range keys from in-memory index tree' (duration: 307.707897ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:38:33.352774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.298852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-01-29T15:38:33.352813Z","caller":"traceutil/trace.go:171","msg":"trace[66196481] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:1; }","duration":"111.358072ms","start":"2026-01-29T15:38:33.24145Z","end":"2026-01-29T15:38:33.352808Z","steps":["trace[66196481] 'count revisions from in-memory index tree' (duration: 111.253661ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:38:33.352658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T15:38:33.044683Z","time spent":"307.964338ms","remote":"127.0.0.1:49950","response type":"/etcdserverpb.KV/Range","request count":0,"request size":51,"response count":0,"response size":27,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" limit:10000 "} {"level":"warn","ts":"2026-01-29T15:38:33.352958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.407512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-01-29T15:38:33.352986Z","caller":"traceutil/trace.go:171","msg":"trace[1739501517] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:1; }","duration":"111.506953ms","start":"2026-01-29T15:38:33.241474Z","end":"2026-01-29T15:38:33.352981Z","steps":["trace[1739501517] 'range keys from in-memory index tree' (duration: 111.369732ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:38:33.353141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.751633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-01-29T15:38:33.353215Z","caller":"traceutil/trace.go:171","msg":"trace[2000553505] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:1; }","duration":"101.838173ms","start":"2026-01-29T15:38:33.251371Z","end":"2026-01-29T15:38:33.353209Z","steps":["trace[2000553505] 'range keys from in-memory index tree' (duration: 101.722653ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:38:33.353327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.668773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-01-29T15:38:33.353395Z","caller":"traceutil/trace.go:171","msg":"trace[66340717] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:1; }","duration":"101.758483ms","start":"2026-01-29T15:38:33.251632Z","end":"2026-01-29T15:38:33.35339Z","steps":["trace[66340717] 'count revisions from in-memory index tree' (duration: 101.636343ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:38:33.353732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.049543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-01-29T15:38:33.353764Z","caller":"traceutil/trace.go:171","msg":"trace[793251006] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:1; }","duration":"299.085154ms","start":"2026-01-29T15:38:33.054674Z","end":"2026-01-29T15:38:33.353759Z","steps":["trace[793251006] 'range keys from in-memory index tree' (duration: 299.027803ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:38:33.353973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.062654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-01-29T15:38:33.353992Z","caller":"traceutil/trace.go:171","msg":"trace[1430100317] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:1; }","duration":"299.084584ms","start":"2026-01-29T15:38:33.054903Z","end":"2026-01-29T15:38:33.353987Z","steps":["trace[1430100317] 'count revisions from in-memory index tree' (duration: 299.038624ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:38:33.354075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.224314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-01-29T15:38:33.354155Z","caller":"traceutil/trace.go:171","msg":"trace[1300911003] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:1; }","duration":"289.324295ms","start":"2026-01-29T15:38:33.064826Z","end":"2026-01-29T15:38:33.35415Z","steps":["trace[1300911003] 'count revisions from in-memory index tree' (duration: 289.207253ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:38:33.354301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.490776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-01-29T15:38:33.354332Z","caller":"traceutil/trace.go:171","msg":"trace[894239245] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:1; }","duration":"289.533636ms","start":"2026-01-29T15:38:33.064794Z","end":"2026-01-29T15:38:33.354328Z","steps":["trace[894239245] 'range keys from in-memory index tree' (duration: 289.421035ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:38:33.354543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.769025ms","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:4"} {"level":"info","ts":"2026-01-29T15:38:33.354563Z","caller":"traceutil/trace.go:171","msg":"trace[1590805919] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:1; }","duration":"279.800545ms","start":"2026-01-29T15:38:33.074759Z","end":"2026-01-29T15:38:33.354559Z","steps":["trace[1590805919] 'count revisions from in-memory index tree' (duration: 279.672085ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:38:33.354684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.002657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-01-29T15:38:33.354716Z","caller":"traceutil/trace.go:171","msg":"trace[527151004] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:1; }","duration":"280.063187ms","start":"2026-01-29T15:38:33.074648Z","end":"2026-01-29T15:38:33.354712Z","steps":["trace[527151004] 'range keys from in-memory index tree' (duration: 279.879516ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:38:33.3549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.737994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-01-29T15:38:33.35492Z","caller":"traceutil/trace.go:171","msg":"trace[812113249] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:1; }","duration":"256.763294ms","start":"2026-01-29T15:38:33.09815Z","end":"2026-01-29T15:38:33.354914Z","steps":["trace[812113249] 'count revisions from in-memory index tree' (duration: 256.718774ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:38:33.355363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.996005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-01-29T15:38:33.355381Z","caller":"traceutil/trace.go:171","msg":"trace[880189999] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:1; }","duration":"257.276807ms","start":"2026-01-29T15:38:33.0981Z","end":"2026-01-29T15:38:33.355377Z","steps":["trace[880189999] 'range keys from in-memory index tree' (duration: 256.924275ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:38:33.355541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.965545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-01-29T15:38:33.355559Z","caller":"traceutil/trace.go:171","msg":"trace[1882214644] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:1; }","duration":"243.992175ms","start":"2026-01-29T15:38:33.111563Z","end":"2026-01-29T15:38:33.355555Z","steps":["trace[1882214644] 'count revisions from in-memory index tree' (duration: 243.945285ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:38:33.355675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.131447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-01-29T15:38:33.355728Z","caller":"traceutil/trace.go:171","msg":"trace[890097156] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:1; }","duration":"244.195947ms","start":"2026-01-29T15:38:33.111528Z","end":"2026-01-29T15:38:33.355724Z","steps":["trace[890097156] 'range keys from in-memory index tree' (duration: 244.073886ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:38:33.355882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.265336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-01-29T15:38:33.3559Z","caller":"traceutil/trace.go:171","msg":"trace[560129206] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:1; }","duration":"234.302086ms","start":"2026-01-29T15:38:33.121594Z","end":"2026-01-29T15:38:33.355896Z","steps":["trace[560129206] 'count revisions from in-memory index tree' (duration: 234.250206ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:38:33.35602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.442337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-01-29T15:38:33.356038Z","caller":"traceutil/trace.go:171","msg":"trace[320770460] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:1; }","duration":"234.468307ms","start":"2026-01-29T15:38:33.121565Z","end":"2026-01-29T15:38:33.356034Z","steps":["trace[320770460] 'range keys from in-memory index tree' (duration: 234.389247ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:38:33.356157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.303834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-01-29T15:38:33.356174Z","caller":"traceutil/trace.go:171","msg":"trace[143260811] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:1; }","duration":"224.329655ms","start":"2026-01-29T15:38:33.13184Z","end":"2026-01-29T15:38:33.35617Z","steps":["trace[143260811] 'count revisions from in-memory index tree' (duration: 224.287854ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:38:33.356392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.567108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-01-29T15:38:33.35646Z","caller":"traceutil/trace.go:171","msg":"trace[1898300848] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:1; }","duration":"224.647068ms","start":"2026-01-29T15:38:33.131808Z","end":"2026-01-29T15:38:33.356455Z","steps":["trace[1898300848] 'range keys from in-memory index tree' (duration: 224.517327ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:38:33.356638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.673247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-01-29T15:38:33.356656Z","caller":"traceutil/trace.go:171","msg":"trace[374732324] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:1; }","duration":"219.693747ms","start":"2026-01-29T15:38:33.136958Z","end":"2026-01-29T15:38:33.356652Z","steps":["trace[374732324] 'range keys from in-memory index tree' (duration: 219.645247ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:38:33.356827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.940548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-01-29T15:38:33.356845Z","caller":"traceutil/trace.go:171","msg":"trace[242040416] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:1; }","duration":"219.976559ms","start":"2026-01-29T15:38:33.136865Z","end":"2026-01-29T15:38:33.356842Z","steps":["trace[242040416] 'count revisions from in-memory index tree' (duration: 219.819438ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:38:33.357011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.524876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-01-29T15:38:33.35703Z","caller":"traceutil/trace.go:171","msg":"trace[1089491720] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1; }","duration":"193.663678ms","start":"2026-01-29T15:38:33.163361Z","end":"2026-01-29T15:38:33.357025Z","steps":["trace[1089491720] 'count revisions from in-memory index tree' (duration: 193.446006ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:38:33.357161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.717447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-01-29T15:38:33.357232Z","caller":"traceutil/trace.go:171","msg":"trace[2090471291] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1; }","duration":"193.788228ms","start":"2026-01-29T15:38:33.163436Z","end":"2026-01-29T15:38:33.357225Z","steps":["trace[2090471291] 'range keys from in-memory index tree' (duration: 193.617677ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:38:33.357397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.237557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-01-29T15:38:33.357496Z","caller":"traceutil/trace.go:171","msg":"trace[961947865] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1; }","duration":"177.352037ms","start":"2026-01-29T15:38:33.180138Z","end":"2026-01-29T15:38:33.357491Z","steps":["trace[961947865] 'count revisions from in-memory index tree' (duration: 177.208596ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:38:33.35787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.706479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-01-29T15:38:33.357912Z","caller":"traceutil/trace.go:171","msg":"trace[1498458881] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1; }","duration":"177.81382ms","start":"2026-01-29T15:38:33.180092Z","end":"2026-01-29T15:38:33.357906Z","steps":["trace[1498458881] 'range keys from in-memory index tree' (duration: 177.601598ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:38:33.358166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.307096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-01-29T15:38:33.358185Z","caller":"traceutil/trace.go:171","msg":"trace[1582126069] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:1; }","duration":"167.338556ms","start":"2026-01-29T15:38:33.190842Z","end":"2026-01-29T15:38:33.35818Z","steps":["trace[1582126069] 'count revisions from in-memory index tree' (duration: 167.277656ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:38:33.358382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.578657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-01-29T15:38:33.358492Z","caller":"traceutil/trace.go:171","msg":"trace[191095230] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:1; }","duration":"167.690788ms","start":"2026-01-29T15:38:33.19078Z","end":"2026-01-29T15:38:33.35847Z","steps":["trace[191095230] 'range keys from in-memory index tree' (duration: 167.477517ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:38:33.358674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.616832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-01-29T15:38:33.358695Z","caller":"traceutil/trace.go:171","msg":"trace[455739502] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:1; }","duration":"158.736622ms","start":"2026-01-29T15:38:33.199952Z","end":"2026-01-29T15:38:33.358689Z","steps":["trace[455739502] 'range keys from in-memory index tree' (duration: 158.570142ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:38:33.358964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.878834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-01-29T15:38:33.359036Z","caller":"traceutil/trace.go:171","msg":"trace[1130691651] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:1; }","duration":"158.969754ms","start":"2026-01-29T15:38:33.20006Z","end":"2026-01-29T15:38:33.35903Z","steps":["trace[1130691651] 'count revisions from in-memory index tree' (duration: 158.850904ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:38:33.359223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.797895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-01-29T15:38:33.359283Z","caller":"traceutil/trace.go:171","msg":"trace[1957970456] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:1; }","duration":"145.914385ms","start":"2026-01-29T15:38:33.213363Z","end":"2026-01-29T15:38:33.359277Z","steps":["trace[1957970456] 'count revisions from in-memory index tree' (duration: 145.766313ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:38:33.359464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.088316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-01-29T15:38:33.359483Z","caller":"traceutil/trace.go:171","msg":"trace[792929481] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:1; }","duration":"146.187767ms","start":"2026-01-29T15:38:33.213291Z","end":"2026-01-29T15:38:33.359479Z","steps":["trace[792929481] 'range keys from in-memory index tree' (duration: 145.942074ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:38:33.359844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.639765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-01-29T15:38:33.359937Z","caller":"traceutil/trace.go:171","msg":"trace[1178204945] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:1; }","duration":"134.741576ms","start":"2026-01-29T15:38:33.22519Z","end":"2026-01-29T15:38:33.359932Z","steps":["trace[1178204945] 'range keys from in-memory index tree' (duration: 134.619445ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:38:33.360102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.808976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-01-29T15:38:33.36012Z","caller":"traceutil/trace.go:171","msg":"trace[134278699] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:1; }","duration":"134.835796ms","start":"2026-01-29T15:38:33.22528Z","end":"2026-01-29T15:38:33.360116Z","steps":["trace[134278699] 'count revisions from in-memory index tree' (duration: 134.789246ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:38:33.352424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.571276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-01-29T15:38:33.362548Z","caller":"traceutil/trace.go:171","msg":"trace[11154462] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:1; }","duration":"317.822069ms","start":"2026-01-29T15:38:33.044717Z","end":"2026-01-29T15:38:33.362539Z","steps":["trace[11154462] 'count revisions from in-memory index tree' (duration: 307.552646ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:38:33.362599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T15:38:33.044715Z","time spent":"317.87569ms","remote":"127.0.0.1:49950","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":27,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} {"level":"info","ts":"2026-01-29T15:38:35.740188Z","caller":"traceutil/trace.go:171","msg":"trace[1189129851] transaction","detail":"{read_only:false; response_revision:148; number_of_response:1; }","duration":"227.986463ms","start":"2026-01-29T15:38:35.512175Z","end":"2026-01-29T15:38:35.740161Z","steps":["trace[1189129851] 'process raft request' (duration: 144.024308ms)","trace[1189129851] 'compare' (duration: 83.847444ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T15:38:36.076636Z","caller":"traceutil/trace.go:171","msg":"trace[1163567985] transaction","detail":"{read_only:false; response_revision:155; number_of_response:1; }","duration":"144.589717ms","start":"2026-01-29T15:38:35.932019Z","end":"2026-01-29T15:38:36.076609Z","steps":["trace[1163567985] 'process raft request' (duration: 99.911851ms)","trace[1163567985] 'compare' (duration: 44.570346ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T15:38:42.453141Z","caller":"traceutil/trace.go:171","msg":"trace[635584041] transaction","detail":"{read_only:false; response_revision:347; number_of_response:1; }","duration":"133.880471ms","start":"2026-01-29T15:38:42.319237Z","end":"2026-01-29T15:38:42.453118Z","steps":["trace[635584041] 'process raft request' (duration: 125.071971ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T15:38:42.455218Z","caller":"traceutil/trace.go:171","msg":"trace[893096823] linearizableReadLoop","detail":"{readStateIndex:359; appliedIndex:357; }","duration":"109.384661ms","start":"2026-01-29T15:38:42.345819Z","end":"2026-01-29T15:38:42.455203Z","steps":["trace[893096823] 'read index received' (duration: 98.501199ms)","trace[893096823] 'applied index is now lower than readState.Index' (duration: 10.882502ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T15:38:42.456034Z","caller":"traceutil/trace.go:171","msg":"trace[890262093] transaction","detail":"{read_only:false; response_revision:348; number_of_response:1; }","duration":"136.000432ms","start":"2026-01-29T15:38:42.320017Z","end":"2026-01-29T15:38:42.456018Z","steps":["trace[890262093] 'process raft request' (duration: 135.120128ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:38:42.456155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.331996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-01-29T15:38:42.459638Z","caller":"traceutil/trace.go:171","msg":"trace[1733846059] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:348; }","duration":"113.836135ms","start":"2026-01-29T15:38:42.345788Z","end":"2026-01-29T15:38:42.459624Z","steps":["trace[1733846059] 'agreement among raft nodes before linearized reading' (duration: 110.287026ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T15:38:42.590474Z","caller":"traceutil/trace.go:171","msg":"trace[799371904] transaction","detail":"{read_only:false; response_revision:350; number_of_response:1; }","duration":"130.252861ms","start":"2026-01-29T15:38:42.460197Z","end":"2026-01-29T15:38:42.59045Z","steps":["trace[799371904] 'process raft request' (duration: 113.850487ms)","trace[799371904] 'compare' (duration: 16.078312ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T15:38:42.591288Z","caller":"traceutil/trace.go:171","msg":"trace[1629946444] transaction","detail":"{read_only:false; response_revision:352; number_of_response:1; }","duration":"126.072996ms","start":"2026-01-29T15:38:42.465192Z","end":"2026-01-29T15:38:42.591265Z","steps":["trace[1629946444] 'process raft request' (duration: 125.999755ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T15:38:42.591482Z","caller":"traceutil/trace.go:171","msg":"trace[286186969] transaction","detail":"{read_only:false; response_revision:351; number_of_response:1; }","duration":"126.81694ms","start":"2026-01-29T15:38:42.46465Z","end":"2026-01-29T15:38:42.591467Z","steps":["trace[286186969] 'process raft request' (duration: 125.753104ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T15:38:42.620836Z","caller":"traceutil/trace.go:171","msg":"trace[1171108025] transaction","detail":"{read_only:false; response_revision:353; number_of_response:1; }","duration":"150.671346ms","start":"2026-01-29T15:38:42.470145Z","end":"2026-01-29T15:38:42.620816Z","steps":["trace[1171108025] 'process raft request' (duration: 150.496615ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T15:38:42.621231Z","caller":"traceutil/trace.go:171","msg":"trace[435098968] linearizableReadLoop","detail":"{readStateIndex:364; appliedIndex:360; }","duration":"145.433076ms","start":"2026-01-29T15:38:42.475776Z","end":"2026-01-29T15:38:42.621209Z","steps":["trace[435098968] 'read index received' (duration: 98.305129ms)","trace[435098968] 'applied index is now lower than readState.Index' (duration: 47.126987ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T15:38:42.62156Z","caller":"traceutil/trace.go:171","msg":"trace[712307871] transaction","detail":"{read_only:false; response_revision:355; number_of_response:1; }","duration":"145.550136ms","start":"2026-01-29T15:38:42.475996Z","end":"2026-01-29T15:38:42.621546Z","steps":["trace[712307871] 'process raft request' (duration: 144.936593ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:38:42.621882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.815119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-proxy-5nsz6\" ","response":"range_response_count:1 size:3416"} {"level":"info","ts":"2026-01-29T15:38:42.621974Z","caller":"traceutil/trace.go:171","msg":"trace[1339540411] range","detail":"{range_begin:/registry/pods/kube-system/kube-proxy-5nsz6; range_end:; response_count:1; response_revision:355; }","duration":"146.212971ms","start":"2026-01-29T15:38:42.475749Z","end":"2026-01-29T15:38:42.621962Z","steps":["trace[1339540411] 'agreement among raft nodes before linearized reading' (duration: 145.809568ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T15:38:42.622086Z","caller":"traceutil/trace.go:171","msg":"trace[1878820350] transaction","detail":"{read_only:false; response_revision:354; number_of_response:1; }","duration":"146.0701ms","start":"2026-01-29T15:38:42.475937Z","end":"2026-01-29T15:38:42.622007Z","steps":["trace[1878820350] 'process raft request' (duration: 144.812823ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:38:42.643782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.527797ms","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-01-29T15:38:42.643828Z","caller":"traceutil/trace.go:171","msg":"trace[758757747] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:356; }","duration":"145.613687ms","start":"2026-01-29T15:38:42.498204Z","end":"2026-01-29T15:38:42.643817Z","steps":["trace[758757747] 'agreement among raft nodes before linearized reading' (duration: 145.544286ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:38:42.644485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.373836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"info","ts":"2026-01-29T15:38:42.645685Z","caller":"traceutil/trace.go:171","msg":"trace[1299548104] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:356; }","duration":"148.571234ms","start":"2026-01-29T15:38:42.497077Z","end":"2026-01-29T15:38:42.645648Z","steps":["trace[1299548104] 'agreement among raft nodes before linearized reading' (duration: 147.163045ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:38:42.64608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.629758ms","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-01-29T15:38:42.646111Z","caller":"traceutil/trace.go:171","msg":"trace[1305491839] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:356; }","duration":"154.70696ms","start":"2026-01-29T15:38:42.491396Z","end":"2026-01-29T15:38:42.646103Z","steps":["trace[1305491839] 'agreement among raft nodes before linearized reading' (duration: 151.887784ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:38:42.963856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.967127ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-01-29T15:38:42.96403Z","caller":"traceutil/trace.go:171","msg":"trace[647269469] linearizableReadLoop","detail":"{readStateIndex:388; appliedIndex:386; }","duration":"145.325766ms","start":"2026-01-29T15:38:42.818689Z","end":"2026-01-29T15:38:42.964014Z","steps":["trace[647269469] 'read index received' (duration: 23.786676ms)","trace[647269469] 'applied index is now lower than readState.Index' (duration: 121.53814ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T15:38:42.96411Z","caller":"traceutil/trace.go:171","msg":"trace[1045090196] transaction","detail":"{read_only:false; response_revision:376; number_of_response:1; }","duration":"172.70229ms","start":"2026-01-29T15:38:42.791398Z","end":"2026-01-29T15:38:42.964101Z","steps":["trace[1045090196] 'process raft request' (duration: 51.04674ms)","trace[1045090196] 'compare' (duration: 120.831726ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T15:38:42.96442Z","caller":"traceutil/trace.go:171","msg":"trace[273490195] transaction","detail":"{read_only:false; response_revision:377; number_of_response:1; }","duration":"167.48103ms","start":"2026-01-29T15:38:42.79693Z","end":"2026-01-29T15:38:42.964411Z","steps":["trace[273490195] 'process raft request' (duration: 167.020507ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:38:42.964579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.923719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-01-29T15:38:42.964606Z","caller":"traceutil/trace.go:171","msg":"trace[300663760] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:377; }","duration":"168.968309ms","start":"2026-01-29T15:38:42.795629Z","end":"2026-01-29T15:38:42.964597Z","steps":["trace[300663760] 'agreement among raft nodes before linearized reading' (duration: 168.912039ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:38:42.96473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.337458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/admin\" ","response":"range_response_count:1 size:2278"} {"level":"info","ts":"2026-01-29T15:38:42.964795Z","caller":"traceutil/trace.go:171","msg":"trace[1314920811] range","detail":"{range_begin:/registry/clusterroles/admin; range_end:; response_count:1; response_revision:377; }","duration":"135.415849ms","start":"2026-01-29T15:38:42.829362Z","end":"2026-01-29T15:38:42.964778Z","steps":["trace[1314920811] 'agreement among raft nodes before linearized reading' (duration: 135.265758ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T15:38:43.21545Z","caller":"traceutil/trace.go:171","msg":"trace[1457736201] transaction","detail":"{read_only:false; response_revision:380; number_of_response:1; }","duration":"239.675644ms","start":"2026-01-29T15:38:42.97576Z","end":"2026-01-29T15:38:43.215435Z","steps":["trace[1457736201] 'process raft request' (duration: 239.646414ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T15:38:43.215859Z","caller":"traceutil/trace.go:171","msg":"trace[1893738698] transaction","detail":"{read_only:false; response_revision:379; number_of_response:1; }","duration":"241.412833ms","start":"2026-01-29T15:38:42.974437Z","end":"2026-01-29T15:38:43.21585Z","steps":["trace[1893738698] 'process raft request' (duration: 147.812915ms)","trace[1893738698] 'compare' (duration: 93.065335ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T15:38:43.3881Z","caller":"traceutil/trace.go:171","msg":"trace[1548944138] transaction","detail":"{read_only:false; response_revision:384; number_of_response:1; }","duration":"163.110939ms","start":"2026-01-29T15:38:43.224978Z","end":"2026-01-29T15:38:43.388089Z","steps":["trace[1548944138] 'process raft request' (duration: 163.083019ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T15:38:43.388105Z","caller":"traceutil/trace.go:171","msg":"trace[494244868] transaction","detail":"{read_only:false; response_revision:382; number_of_response:1; }","duration":"164.087524ms","start":"2026-01-29T15:38:43.223995Z","end":"2026-01-29T15:38:43.388082Z","steps":["trace[494244868] 'process raft request' (duration: 142.234531ms)","trace[494244868] 'compare' (duration: 21.613001ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T15:38:43.388221Z","caller":"traceutil/trace.go:171","msg":"trace[1293903807] transaction","detail":"{read_only:false; response_revision:383; number_of_response:1; }","duration":"164.100055ms","start":"2026-01-29T15:38:43.224116Z","end":"2026-01-29T15:38:43.388216Z","steps":["trace[1293903807] 'process raft request' (duration: 163.918234ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T15:38:43.624531Z","caller":"traceutil/trace.go:171","msg":"trace[797462102] linearizableReadLoop","detail":"{readStateIndex:401; appliedIndex:399; }","duration":"144.037711ms","start":"2026-01-29T15:38:43.480456Z","end":"2026-01-29T15:38:43.624494Z","steps":["trace[797462102] 'read index received' (duration: 69.849153ms)","trace[797462102] 'applied index is now lower than readState.Index' (duration: 74.186998ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T15:38:43.624707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.230022ms","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-01-29T15:38:43.624741Z","caller":"traceutil/trace.go:171","msg":"trace[1087768212] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:390; }","duration":"144.300323ms","start":"2026-01-29T15:38:43.480431Z","end":"2026-01-29T15:38:43.624731Z","steps":["trace[1087768212] 'agreement among raft nodes before linearized reading' (duration: 144.181022ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T15:38:43.624927Z","caller":"traceutil/trace.go:171","msg":"trace[1426330781] transaction","detail":"{read_only:false; response_revision:389; number_of_response:1; }","duration":"161.694152ms","start":"2026-01-29T15:38:43.463211Z","end":"2026-01-29T15:38:43.624905Z","steps":["trace[1426330781] 'process raft request' (duration: 87.086951ms)","trace[1426330781] 'compare' (duration: 73.251973ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T15:38:43.624945Z","caller":"traceutil/trace.go:171","msg":"trace[1766386605] transaction","detail":"{read_only:false; response_revision:390; number_of_response:1; }","duration":"153.925218ms","start":"2026-01-29T15:38:43.47101Z","end":"2026-01-29T15:38:43.624935Z","steps":["trace[1766386605] 'process raft request' (duration: 153.382115ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T15:38:45.018228Z","caller":"traceutil/trace.go:171","msg":"trace[885723551] linearizableReadLoop","detail":"{readStateIndex:414; appliedIndex:410; }","duration":"231.721806ms","start":"2026-01-29T15:38:44.786485Z","end":"2026-01-29T15:38:45.018207Z","steps":["trace[885723551] 'read index received' (duration: 202.954075ms)","trace[885723551] 'applied index is now lower than readState.Index' (duration: 28.766661ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T15:38:45.018253Z","caller":"traceutil/trace.go:171","msg":"trace[1280589972] transaction","detail":"{read_only:false; response_revision:400; number_of_response:1; }","duration":"251.166315ms","start":"2026-01-29T15:38:44.767066Z","end":"2026-01-29T15:38:45.018233Z","steps":["trace[1280589972] 'process raft request' (duration: 250.906112ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:38:45.018379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.855597ms","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-01-29T15:38:45.018414Z","caller":"traceutil/trace.go:171","msg":"trace[1897074613] transaction","detail":"{read_only:false; response_revision:399; number_of_response:1; }","duration":"257.909044ms","start":"2026-01-29T15:38:44.760495Z","end":"2026-01-29T15:38:45.018404Z","steps":["trace[1897074613] 'process raft request' (duration: 228.860211ms)","trace[1897074613] 'compare' (duration: 28.444079ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T15:38:45.018411Z","caller":"traceutil/trace.go:171","msg":"trace[1055428342] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:402; }","duration":"231.947248ms","start":"2026-01-29T15:38:44.786454Z","end":"2026-01-29T15:38:45.018401Z","steps":["trace[1055428342] 'agreement among raft nodes before linearized reading' (duration: 231.837217ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T15:38:45.01863Z","caller":"traceutil/trace.go:171","msg":"trace[301585248] transaction","detail":"{read_only:false; response_revision:401; number_of_response:1; }","duration":"251.359766ms","start":"2026-01-29T15:38:44.767265Z","end":"2026-01-29T15:38:45.018624Z","steps":["trace[301585248] 'process raft request' (duration: 250.797332ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T15:38:45.018657Z","caller":"traceutil/trace.go:171","msg":"trace[1036580856] transaction","detail":"{read_only:false; response_revision:402; number_of_response:1; }","duration":"251.338246ms","start":"2026-01-29T15:38:44.767308Z","end":"2026-01-29T15:38:45.018646Z","steps":["trace[1036580856] 'process raft request' (duration: 250.840122ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T15:38:47.199291Z","caller":"traceutil/trace.go:171","msg":"trace[2063456578] linearizableReadLoop","detail":"{readStateIndex:420; appliedIndex:418; }","duration":"156.445107ms","start":"2026-01-29T15:38:47.042828Z","end":"2026-01-29T15:38:47.199273Z","steps":["trace[2063456578] 'read index received' (duration: 24.921947ms)","trace[2063456578] 'applied index is now lower than readState.Index' (duration: 131.52238ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T15:38:47.199365Z","caller":"traceutil/trace.go:171","msg":"trace[310252685] transaction","detail":"{read_only:false; response_revision:407; number_of_response:1; }","duration":"166.678063ms","start":"2026-01-29T15:38:47.032681Z","end":"2026-01-29T15:38:47.199359Z","steps":["trace[310252685] 'process raft request' (duration: 70.621587ms)","trace[310252685] 'compare' (duration: 95.904245ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T15:38:47.199566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.732709ms","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-01-29T15:38:47.199586Z","caller":"traceutil/trace.go:171","msg":"trace[1327745835] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:407; }","duration":"156.760469ms","start":"2026-01-29T15:38:47.042817Z","end":"2026-01-29T15:38:47.199578Z","steps":["trace[1327745835] 'agreement among raft nodes before linearized reading' (duration: 156.712288ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:38:47.19968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.894829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T15:38:47.199707Z","caller":"traceutil/trace.go:171","msg":"trace[1418785451] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:407; }","duration":"127.93058ms","start":"2026-01-29T15:38:47.071758Z","end":"2026-01-29T15:38:47.199688Z","steps":["trace[1418785451] 'agreement among raft nodes before linearized reading' (duration: 127.91051ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T15:39:05.37639Z","caller":"traceutil/trace.go:171","msg":"trace[1995523114] linearizableReadLoop","detail":"{readStateIndex:533; appliedIndex:532; }","duration":"304.974616ms","start":"2026-01-29T15:39:05.071394Z","end":"2026-01-29T15:39:05.376369Z","steps":["trace[1995523114] 'read index received' (duration: 304.819585ms)","trace[1995523114] 'applied index is now lower than readState.Index' (duration: 154.141µs)"],"step_count":2} {"level":"warn","ts":"2026-01-29T15:39:05.376993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.953723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-01-29T15:39:05.377162Z","caller":"traceutil/trace.go:171","msg":"trace[267951592] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:516; }","duration":"220.040983ms","start":"2026-01-29T15:39:05.15699Z","end":"2026-01-29T15:39:05.377031Z","steps":["trace[267951592] 'agreement among raft nodes before linearized reading' (duration: 219.946203ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T15:39:05.378007Z","caller":"traceutil/trace.go:171","msg":"trace[420373502] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"339.469588ms","start":"2026-01-29T15:39:05.038524Z","end":"2026-01-29T15:39:05.377993Z","steps":["trace[420373502] 'process raft request' (duration: 337.740249ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:39:05.378212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.815754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T15:39:05.382794Z","caller":"traceutil/trace.go:171","msg":"trace[675894591] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:516; }","duration":"306.906046ms","start":"2026-01-29T15:39:05.071362Z","end":"2026-01-29T15:39:05.378268Z","steps":["trace[675894591] 'agreement among raft nodes before linearized reading' (duration: 305.248597ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:39:05.382826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T15:39:05.071351Z","time spent":"311.467148ms","remote":"127.0.0.1:41156","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-01-29T15:39:05.383321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T15:39:05.038507Z","time spent":"339.541048ms","remote":"127.0.0.1:50086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-29T15:39:07.569413Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":296564718984063590,"retry-timeout":"500ms"} {"level":"info","ts":"2026-01-29T15:39:07.581052Z","caller":"traceutil/trace.go:171","msg":"trace[1300547515] linearizableReadLoop","detail":"{readStateIndex:538; appliedIndex:537; }","duration":"513.646393ms","start":"2026-01-29T15:39:07.067392Z","end":"2026-01-29T15:39:07.581038Z","steps":["trace[1300547515] 'read index received' (duration: 513.531902ms)","trace[1300547515] 'applied index is now lower than readState.Index' (duration: 113.211µs)"],"step_count":2} {"level":"warn","ts":"2026-01-29T15:39:07.581069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T15:39:06.793831Z","time spent":"787.232936ms","remote":"127.0.0.1:49878","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-01-29T15:39:07.581142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"513.735913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T15:39:07.581161Z","caller":"traceutil/trace.go:171","msg":"trace[861145992] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:520; }","duration":"513.809385ms","start":"2026-01-29T15:39:07.067346Z","end":"2026-01-29T15:39:07.581155Z","steps":["trace[861145992] 'agreement among raft nodes before linearized reading' (duration: 513.753884ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:39:07.581181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T15:39:07.067332Z","time spent":"513.843715ms","remote":"127.0.0.1:41156","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-01-29T15:39:07.581396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.400609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T15:39:07.581425Z","caller":"traceutil/trace.go:171","msg":"trace[1843248807] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:0; response_revision:520; }","duration":"273.459099ms","start":"2026-01-29T15:39:07.307953Z","end":"2026-01-29T15:39:07.581412Z","steps":["trace[1843248807] 'agreement among raft nodes before linearized reading' (duration: 273.399918ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:39:07.581473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.089283ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-01-29T15:39:07.58149Z","caller":"traceutil/trace.go:171","msg":"trace[298401515] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:520; }","duration":"187.127973ms","start":"2026-01-29T15:39:07.394357Z","end":"2026-01-29T15:39:07.581485Z","steps":["trace[298401515] 'agreement among raft nodes before linearized reading' (duration: 187.058433ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:39:07.581569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.576649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-01-29T15:39:07.581583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.123937ms","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-01-29T15:39:07.581585Z","caller":"traceutil/trace.go:171","msg":"trace[398355866] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:520; }","duration":"190.610079ms","start":"2026-01-29T15:39:07.39097Z","end":"2026-01-29T15:39:07.58158Z","steps":["trace[398355866] 'agreement among raft nodes before linearized reading' (duration: 190.531599ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T15:39:07.581602Z","caller":"traceutil/trace.go:171","msg":"trace[437626461] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:520; }","duration":"186.163088ms","start":"2026-01-29T15:39:07.395435Z","end":"2026-01-29T15:39:07.581598Z","steps":["trace[437626461] 'agreement among raft nodes before linearized reading' (duration: 186.118887ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:39:08.325299Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":296564718984063597,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-29T15:39:08.754441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.077977835s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T15:39:08.75451Z","caller":"traceutil/trace.go:171","msg":"trace[834742909] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:520; }","duration":"1.078071775s","start":"2026-01-29T15:39:07.676422Z","end":"2026-01-29T15:39:08.754494Z","steps":["trace[834742909] 'range keys from in-memory index tree' (duration: 1.076605289s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:39:08.754546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T15:39:07.67641Z","time spent":"1.078128345s","remote":"127.0.0.1:41160","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-01-29T15:39:08.75481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.831609ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-29T15:39:08.754897Z","caller":"traceutil/trace.go:171","msg":"trace[482229295] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"1.078388487s","start":"2026-01-29T15:39:07.67649Z","end":"2026-01-29T15:39:08.754879Z","steps":["trace[482229295] 'process raft request' (duration: 685.382967ms)","trace[482229295] 'compare' (duration: 392.737979ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T15:39:08.754954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T15:39:07.676483Z","time spent":"1.078443987s","remote":"127.0.0.1:49878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-29T15:39:08.760194Z","caller":"traceutil/trace.go:171","msg":"trace[798659984] linearizableReadLoop","detail":"{readStateIndex:542; appliedIndex:538; }","duration":"935.097127ms","start":"2026-01-29T15:39:07.825083Z","end":"2026-01-29T15:39:08.76018Z","steps":["trace[798659984] 'read index received' (duration: 536.797811ms)","trace[798659984] 'applied index is now lower than readState.Index' (duration: 398.298666ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T15:39:08.760365Z","caller":"traceutil/trace.go:171","msg":"trace[1120210263] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"1.082580468s","start":"2026-01-29T15:39:07.677777Z","end":"2026-01-29T15:39:08.760358Z","steps":["trace[1120210263] 'process raft request' (duration: 1.082272796s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:39:08.760412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T15:39:07.677771Z","time spent":"1.082611928s","remote":"127.0.0.1:50086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-29T15:39:08.760505Z","caller":"traceutil/trace.go:171","msg":"trace[1767687854] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"1.079616093s","start":"2026-01-29T15:39:07.680882Z","end":"2026-01-29T15:39:08.760498Z","steps":["trace[1767687854] 'process raft request' (duration: 1.079254462s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:39:08.760535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T15:39:07.680874Z","time spent":"1.079645133s","remote":"127.0.0.1:50086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-29T15:39:08.760603Z","caller":"traceutil/trace.go:171","msg":"trace[1828459278] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"1.052504041s","start":"2026-01-29T15:39:07.708094Z","end":"2026-01-29T15:39:08.760598Z","steps":["trace[1828459278] 'process raft request' (duration: 1.052064249s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:39:08.760633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T15:39:07.70807Z","time spent":"1.052547371s","remote":"127.0.0.1:50086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-29T15:39:08.760754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"935.669039ms","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-01-29T15:39:08.760773Z","caller":"traceutil/trace.go:171","msg":"trace[330510066] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:524; }","duration":"935.71068ms","start":"2026-01-29T15:39:07.825057Z","end":"2026-01-29T15:39:08.760767Z","steps":["trace[330510066] 'agreement among raft nodes before linearized reading' (duration: 935.632589ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:39:08.760793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T15:39:07.825043Z","time spent":"935.74569ms","remote":"127.0.0.1:50086","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-01-29T15:39:08.760865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.534921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T15:39:08.760897Z","caller":"traceutil/trace.go:171","msg":"trace[140411565] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:0; response_revision:524; }","duration":"468.597921ms","start":"2026-01-29T15:39:08.292291Z","end":"2026-01-29T15:39:08.760888Z","steps":["trace[140411565] 'agreement among raft nodes before linearized reading' (duration: 468.542981ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:39:08.760921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T15:39:08.292273Z","time spent":"468.641041ms","remote":"127.0.0.1:49914","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":28,"request content":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" "} {"level":"info","ts":"2026-01-29T15:39:09.018825Z","caller":"traceutil/trace.go:171","msg":"trace[952843802] linearizableReadLoop","detail":"{readStateIndex:543; appliedIndex:542; }","duration":"246.119739ms","start":"2026-01-29T15:39:08.772685Z","end":"2026-01-29T15:39:09.018805Z","steps":["trace[952843802] 'read index received' (duration: 224.159551ms)","trace[952843802] 'applied index is now lower than readState.Index' (duration: 21.959248ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T15:39:09.019143Z","caller":"traceutil/trace.go:171","msg":"trace[1462677702] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"246.52243ms","start":"2026-01-29T15:39:08.772608Z","end":"2026-01-29T15:39:09.019131Z","steps":["trace[1462677702] 'process raft request' (duration: 224.295601ms)","trace[1462677702] 'compare' (duration: 21.792507ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T15:39:09.019363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.782222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T15:39:09.019396Z","caller":"traceutil/trace.go:171","msg":"trace[1270559919] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:525; }","duration":"246.828882ms","start":"2026-01-29T15:39:08.772557Z","end":"2026-01-29T15:39:09.019385Z","steps":["trace[1270559919] 'agreement among raft nodes before linearized reading' (duration: 246.749242ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:39:09.019537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.502445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T15:39:09.019563Z","caller":"traceutil/trace.go:171","msg":"trace[1995876505] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:0; response_revision:525; }","duration":"245.537645ms","start":"2026-01-29T15:39:08.774018Z","end":"2026-01-29T15:39:09.019555Z","steps":["trace[1995876505] 'agreement among raft nodes before linearized reading' (duration: 245.493715ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:39:09.019837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.762946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-01-29T15:39:09.019909Z","caller":"traceutil/trace.go:171","msg":"trace[1471734086] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:525; }","duration":"245.855967ms","start":"2026-01-29T15:39:08.774036Z","end":"2026-01-29T15:39:09.019892Z","steps":["trace[1471734086] 'agreement among raft nodes before linearized reading' (duration: 245.656066ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T15:39:13.247445Z","caller":"traceutil/trace.go:171","msg":"trace[90154111] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"471.248211ms","start":"2026-01-29T15:39:12.776176Z","end":"2026-01-29T15:39:13.247424Z","steps":["trace[90154111] 'process raft request' (duration: 471.119776ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:39:13.247589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T15:39:12.776162Z","time spent":"471.348945ms","remote":"127.0.0.1:50000","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16799,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-29T15:39:13.255036Z","caller":"traceutil/trace.go:171","msg":"trace[1024089886] linearizableReadLoop","detail":"{readStateIndex:576; appliedIndex:575; }","duration":"473.569147ms","start":"2026-01-29T15:39:12.781451Z","end":"2026-01-29T15:39:13.25502Z","steps":["trace[1024089886] 'read index received' (duration: 466.645222ms)","trace[1024089886] 'applied index is now lower than readState.Index' (duration: 6.923265ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T15:39:13.255116Z","caller":"traceutil/trace.go:171","msg":"trace[26793855] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"475.837345ms","start":"2026-01-29T15:39:12.779272Z","end":"2026-01-29T15:39:13.25511Z","steps":["trace[26793855] 'process raft request' (duration: 475.674258ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:39:13.255188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T15:39:12.779256Z","time spent":"475.877257ms","remote":"127.0.0.1:50086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-29T15:39:13.255322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.339183ms","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-01-29T15:39:13.255368Z","caller":"traceutil/trace.go:171","msg":"trace[546707562] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:557; }","duration":"472.472598ms","start":"2026-01-29T15:39:12.782882Z","end":"2026-01-29T15:39:13.255355Z","steps":["trace[546707562] 'agreement among raft nodes before linearized reading' (duration: 472.353793ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:39:13.25539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T15:39:12.782872Z","time spent":"472.51392ms","remote":"127.0.0.1:50086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-01-29T15:39:13.255437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.640521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T15:39:13.255464Z","caller":"traceutil/trace.go:171","msg":"trace[1935902319] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:557; }","duration":"185.695543ms","start":"2026-01-29T15:39:13.069761Z","end":"2026-01-29T15:39:13.255457Z","steps":["trace[1935902319] 'agreement among raft nodes before linearized reading' (duration: 185.650801ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:39:13.25551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.733349ms","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-01-29T15:39:13.255526Z","caller":"traceutil/trace.go:171","msg":"trace[247808055] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:557; }","duration":"224.74872ms","start":"2026-01-29T15:39:13.030773Z","end":"2026-01-29T15:39:13.255521Z","steps":["trace[247808055] 'agreement among raft nodes before linearized reading' (duration: 224.705108ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:39:13.255613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.159512ms","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-01-29T15:39:13.255633Z","caller":"traceutil/trace.go:171","msg":"trace[916078529] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:557; }","duration":"474.199343ms","start":"2026-01-29T15:39:12.781428Z","end":"2026-01-29T15:39:13.255627Z","steps":["trace[916078529] 'agreement among raft nodes before linearized reading' (duration: 474.12166ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:39:13.255651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T15:39:12.781418Z","time spent":"474.228264ms","remote":"127.0.0.1:50086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-01-29T15:39:13.522342Z","caller":"traceutil/trace.go:171","msg":"trace[565367336] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"262.25096ms","start":"2026-01-29T15:39:13.260073Z","end":"2026-01-29T15:39:13.522324Z","steps":["trace[565367336] 'process raft request' (duration: 262.221229ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T15:39:13.522646Z","caller":"traceutil/trace.go:171","msg":"trace[2016443478] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"262.914328ms","start":"2026-01-29T15:39:13.259721Z","end":"2026-01-29T15:39:13.522636Z","steps":["trace[2016443478] 'process raft request' (duration: 247.212708ms)","trace[2016443478] 'compare' (duration: 15.002911ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T15:39:13.522747Z","caller":"traceutil/trace.go:171","msg":"trace[1902749506] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"262.739882ms","start":"2026-01-29T15:39:13.259999Z","end":"2026-01-29T15:39:13.522739Z","steps":["trace[1902749506] 'process raft request' (duration: 262.255191ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:39:15.133097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.062971415s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T15:39:15.133171Z","caller":"traceutil/trace.go:171","msg":"trace[727148674] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:560; }","duration":"1.0630999s","start":"2026-01-29T15:39:14.070054Z","end":"2026-01-29T15:39:15.133153Z","steps":["trace[727148674] 'range keys from in-memory index tree' (duration: 1.062924373s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:39:15.133213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T15:39:14.070041Z","time spent":"1.063163964s","remote":"127.0.0.1:41156","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-01-29T15:39:17.492004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.907342ms","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-01-29T15:39:17.492095Z","caller":"traceutil/trace.go:171","msg":"trace[1462179976] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:577; }","duration":"226.049447ms","start":"2026-01-29T15:39:17.266031Z","end":"2026-01-29T15:39:17.492081Z","steps":["trace[1462179976] 'range keys from in-memory index tree' (duration: 225.752235ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T15:40:14.088947Z","caller":"traceutil/trace.go:171","msg":"trace[204520921] transaction","detail":"{read_only:false; response_revision:883; number_of_response:1; }","duration":"142.292ms","start":"2026-01-29T15:40:13.946637Z","end":"2026-01-29T15:40:14.088929Z","steps":["trace[204520921] 'process raft request' (duration: 137.389593ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T15:40:16.300288Z","caller":"traceutil/trace.go:171","msg":"trace[244697743] transaction","detail":"{read_only:false; response_revision:889; number_of_response:1; }","duration":"181.965864ms","start":"2026-01-29T15:40:16.118298Z","end":"2026-01-29T15:40:16.300264Z","steps":["trace[244697743] 'process raft request' (duration: 161.450138ms)","trace[244697743] 'compare' (duration: 20.355953ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T15:40:18.036197Z","caller":"traceutil/trace.go:171","msg":"trace[1282249974] transaction","detail":"{read_only:false; response_revision:893; number_of_response:1; }","duration":"164.875212ms","start":"2026-01-29T15:40:17.871304Z","end":"2026-01-29T15:40:18.036179Z","steps":["trace[1282249974] 'process raft request' (duration: 164.616237ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:40:18.481947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.036431ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-29T15:40:18.482011Z","caller":"traceutil/trace.go:171","msg":"trace[494644019] transaction","detail":"{read_only:false; response_revision:897; number_of_response:1; }","duration":"146.065902ms","start":"2026-01-29T15:40:18.335925Z","end":"2026-01-29T15:40:18.481991Z","steps":["trace[494644019] 'process raft request' (duration: 34.933289ms)","trace[494644019] 'compare' (duration: 110.897849ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T15:40:20.501234Z","caller":"traceutil/trace.go:171","msg":"trace[1473640844] transaction","detail":"{read_only:false; response_revision:901; number_of_response:1; }","duration":"174.87072ms","start":"2026-01-29T15:40:20.326347Z","end":"2026-01-29T15:40:20.501218Z","steps":["trace[1473640844] 'process raft request' (duration: 131.571661ms)","trace[1473640844] 'compare' (duration: 43.047094ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T15:40:22.610889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.823423ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-01-29T15:40:22.610981Z","caller":"traceutil/trace.go:171","msg":"trace[2132458865] linearizableReadLoop","detail":"{readStateIndex:941; appliedIndex:940; }","duration":"104.729501ms","start":"2026-01-29T15:40:22.506235Z","end":"2026-01-29T15:40:22.610965Z","steps":["trace[2132458865] 'read index received' (duration: 878.107µs)","trace[2132458865] 'applied index is now lower than readState.Index' (duration: 103.850134ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T15:40:22.611158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.926234ms","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-01-29T15:40:22.611184Z","caller":"traceutil/trace.go:171","msg":"trace[558976136] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:906; }","duration":"104.999706ms","start":"2026-01-29T15:40:22.506175Z","end":"2026-01-29T15:40:22.611175Z","steps":["trace[558976136] 'agreement among raft nodes before linearized reading' (duration: 104.870564ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T15:40:24.331525Z","caller":"traceutil/trace.go:171","msg":"trace[1423075773] linearizableReadLoop","detail":"{readStateIndex:944; appliedIndex:943; }","duration":"141.382309ms","start":"2026-01-29T15:40:24.190122Z","end":"2026-01-29T15:40:24.331504Z","steps":["trace[1423075773] 'read index received' (duration: 141.185556ms)","trace[1423075773] 'applied index is now lower than readState.Index' (duration: 195.843µs)"],"step_count":2} {"level":"info","ts":"2026-01-29T15:40:24.331669Z","caller":"traceutil/trace.go:171","msg":"trace[679074574] transaction","detail":"{read_only:false; response_revision:909; number_of_response:1; }","duration":"149.353037ms","start":"2026-01-29T15:40:24.182306Z","end":"2026-01-29T15:40:24.331659Z","steps":["trace[679074574] 'process raft request' (duration: 149.046111ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:40:24.332118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.976771ms","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-01-29T15:40:24.33218Z","caller":"traceutil/trace.go:171","msg":"trace[62875890] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:909; }","duration":"142.059792ms","start":"2026-01-29T15:40:24.190102Z","end":"2026-01-29T15:40:24.332162Z","steps":["trace[62875890] 'agreement among raft nodes before linearized reading' (duration: 141.94104ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T15:40:27.013153Z","caller":"traceutil/trace.go:171","msg":"trace[1765346778] linearizableReadLoop","detail":"{readStateIndex:955; appliedIndex:953; }","duration":"185.330394ms","start":"2026-01-29T15:40:26.8278Z","end":"2026-01-29T15:40:27.01313Z","steps":["trace[1765346778] 'read index received' (duration: 131.413687ms)","trace[1765346778] 'applied index is now lower than readState.Index' (duration: 53.915757ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T15:40:27.013199Z","caller":"traceutil/trace.go:171","msg":"trace[739095144] transaction","detail":"{read_only:false; response_revision:918; number_of_response:1; }","duration":"205.089198ms","start":"2026-01-29T15:40:26.80809Z","end":"2026-01-29T15:40:27.013179Z","steps":["trace[739095144] 'process raft request' (duration: 151.117351ms)","trace[739095144] 'compare' (duration: 53.726303ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T15:40:27.013306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.479907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-01-29T15:40:27.013342Z","caller":"traceutil/trace.go:171","msg":"trace[1143637056] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:919; }","duration":"185.557858ms","start":"2026-01-29T15:40:26.827774Z","end":"2026-01-29T15:40:27.013332Z","steps":["trace[1143637056] 'agreement among raft nodes before linearized reading' (duration: 185.444556ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T15:40:27.013409Z","caller":"traceutil/trace.go:171","msg":"trace[1558840412] transaction","detail":"{read_only:false; response_revision:919; number_of_response:1; }","duration":"202.667641ms","start":"2026-01-29T15:40:26.810734Z","end":"2026-01-29T15:40:27.013402Z","steps":["trace[1558840412] 'process raft request' (duration: 202.352135ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T15:40:28.649005Z","caller":"traceutil/trace.go:171","msg":"trace[783715240] transaction","detail":"{read_only:false; response_revision:925; number_of_response:1; }","duration":"168.944391ms","start":"2026-01-29T15:40:28.480047Z","end":"2026-01-29T15:40:28.648992Z","steps":["trace[783715240] 'process raft request' (duration: 168.91317ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T15:40:28.649219Z","caller":"traceutil/trace.go:171","msg":"trace[864134013] transaction","detail":"{read_only:false; response_revision:924; number_of_response:1; }","duration":"170.582101ms","start":"2026-01-29T15:40:28.478631Z","end":"2026-01-29T15:40:28.649214Z","steps":["trace[864134013] 'process raft request' (duration: 138.05304ms)","trace[864134013] 'compare' (duration: 32.011311ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T15:40:28.939064Z","caller":"traceutil/trace.go:171","msg":"trace[2070040422] transaction","detail":"{read_only:false; response_revision:929; number_of_response:1; }","duration":"113.095323ms","start":"2026-01-29T15:40:28.825949Z","end":"2026-01-29T15:40:28.939044Z","steps":["trace[2070040422] 'process raft request' (duration: 113.037512ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T15:40:28.939223Z","caller":"traceutil/trace.go:171","msg":"trace[216988350] transaction","detail":"{read_only:false; response_revision:928; number_of_response:1; }","duration":"113.46073ms","start":"2026-01-29T15:40:28.825732Z","end":"2026-01-29T15:40:28.939193Z","steps":["trace[216988350] 'process raft request' (duration: 87.668127ms)","trace[216988350] 'compare' (duration: 25.284484ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T15:40:29.807196Z","caller":"traceutil/trace.go:171","msg":"trace[979492792] transaction","detail":"{read_only:false; response_revision:936; number_of_response:1; }","duration":"158.14443ms","start":"2026-01-29T15:40:29.649021Z","end":"2026-01-29T15:40:29.807165Z","steps":["trace[979492792] 'process raft request' (duration: 122.34346ms)","trace[979492792] 'compare' (duration: 35.628736ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T15:40:30.592169Z","caller":"traceutil/trace.go:171","msg":"trace[1090948125] transaction","detail":"{read_only:false; response_revision:938; number_of_response:1; }","duration":"109.457818ms","start":"2026-01-29T15:40:30.482683Z","end":"2026-01-29T15:40:30.592141Z","steps":["trace[1090948125] 'process raft request' (duration: 109.321196ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:40:31.175968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.818846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T15:40:31.176023Z","caller":"traceutil/trace.go:171","msg":"trace[89598758] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:942; }","duration":"102.927028ms","start":"2026-01-29T15:40:31.073084Z","end":"2026-01-29T15:40:31.176011Z","steps":["trace[89598758] 'range keys from in-memory index tree' (duration: 102.727574ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T15:40:32.871158Z","caller":"traceutil/trace.go:171","msg":"trace[799594750] linearizableReadLoop","detail":"{readStateIndex:982; appliedIndex:981; }","duration":"127.62526ms","start":"2026-01-29T15:40:32.743512Z","end":"2026-01-29T15:40:32.871137Z","steps":["trace[799594750] 'read index received' (duration: 127.447445ms)","trace[799594750] 'applied index is now lower than readState.Index' (duration: 177.005µs)"],"step_count":2} {"level":"info","ts":"2026-01-29T15:40:32.871658Z","caller":"traceutil/trace.go:171","msg":"trace[844582654] transaction","detail":"{read_only:false; response_revision:944; number_of_response:1; }","duration":"272.430527ms","start":"2026-01-29T15:40:32.599212Z","end":"2026-01-29T15:40:32.871642Z","steps":["trace[844582654] 'process raft request' (duration: 271.801414ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:40:32.871985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.454945ms","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-01-29T15:40:32.874648Z","caller":"traceutil/trace.go:171","msg":"trace[1972994383] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:944; }","duration":"131.157985ms","start":"2026-01-29T15:40:32.743476Z","end":"2026-01-29T15:40:32.874634Z","steps":["trace[1972994383] 'agreement among raft nodes before linearized reading' (duration: 128.226941ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T15:40:34.79406Z","caller":"traceutil/trace.go:171","msg":"trace[1717717708] transaction","detail":"{read_only:false; response_revision:952; number_of_response:1; }","duration":"642.963871ms","start":"2026-01-29T15:40:34.151078Z","end":"2026-01-29T15:40:34.794042Z","steps":["trace[1717717708] 'process raft request' (duration: 566.229286ms)","trace[1717717708] 'compare' (duration: 76.393778ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T15:40:34.79433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T15:40:34.151068Z","time spent":"643.054813ms","remote":"127.0.0.1:50000","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3620,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-29T15:40:34.795371Z","caller":"traceutil/trace.go:171","msg":"trace[1984991570] linearizableReadLoop","detail":"{readStateIndex:990; appliedIndex:989; }","duration":"575.008925ms","start":"2026-01-29T15:40:34.218808Z","end":"2026-01-29T15:40:34.793817Z","steps":["trace[1984991570] 'read index received' (duration: 498.395372ms)","trace[1984991570] 'applied index is now lower than readState.Index' (duration: 76.612683ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T15:40:34.795561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"576.680094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b1dc1306-2ee5-4021-9bb9-0fbd4fa1cd95\" ","response":"range_response_count:1 size:3635"} {"level":"info","ts":"2026-01-29T15:40:34.795688Z","caller":"traceutil/trace.go:171","msg":"trace[393785701] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-b1dc1306-2ee5-4021-9bb9-0fbd4fa1cd95; range_end:; response_count:1; response_revision:952; }","duration":"576.91803ms","start":"2026-01-29T15:40:34.218757Z","end":"2026-01-29T15:40:34.795676Z","steps":["trace[393785701] 'agreement among raft nodes before linearized reading' (duration: 576.671904ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:40:34.79589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T15:40:34.218714Z","time spent":"577.091303ms","remote":"127.0.0.1:50000","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3658,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b1dc1306-2ee5-4021-9bb9-0fbd4fa1cd95\" "} {"level":"warn","ts":"2026-01-29T15:40:34.796173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"559.617195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T15:40:34.796259Z","caller":"traceutil/trace.go:171","msg":"trace[2071661732] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:952; }","duration":"559.681616ms","start":"2026-01-29T15:40:34.236523Z","end":"2026-01-29T15:40:34.796205Z","steps":["trace[2071661732] 'agreement among raft nodes before linearized reading' (duration: 559.603405ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:40:34.796343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T15:40:34.236513Z","time spent":"559.777999ms","remote":"127.0.0.1:50372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":28,"request content":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-01-29T15:40:34.796584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.144878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-01-29T15:40:34.796935Z","caller":"traceutil/trace.go:171","msg":"trace[553794958] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:952; }","duration":"179.518194ms","start":"2026-01-29T15:40:34.617401Z","end":"2026-01-29T15:40:34.79692Z","steps":["trace[553794958] 'agreement among raft nodes before linearized reading' (duration: 179.136827ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:40:34.79673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.658267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T15:40:34.797419Z","caller":"traceutil/trace.go:171","msg":"trace[667816645] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:952; }","duration":"202.507872ms","start":"2026-01-29T15:40:34.594897Z","end":"2026-01-29T15:40:34.797405Z","steps":["trace[667816645] 'agreement among raft nodes before linearized reading' (duration: 201.674047ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T15:40:38.05188Z","caller":"traceutil/trace.go:171","msg":"trace[943445232] transaction","detail":"{read_only:false; response_revision:970; number_of_response:1; }","duration":"130.296376ms","start":"2026-01-29T15:40:37.921555Z","end":"2026-01-29T15:40:38.051852Z","steps":["trace[943445232] 'process raft request' (duration: 60.8526ms)","trace[943445232] 'compare' (duration: 69.343894ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T15:40:38.968235Z","caller":"traceutil/trace.go:171","msg":"trace[1250124619] linearizableReadLoop","detail":"{readStateIndex:1015; appliedIndex:1014; }","duration":"125.629131ms","start":"2026-01-29T15:40:38.842584Z","end":"2026-01-29T15:40:38.968213Z","steps":["trace[1250124619] 'read index received' (duration: 96.486449ms)","trace[1250124619] 'applied index is now lower than readState.Index' (duration: 29.141842ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T15:40:38.968338Z","caller":"traceutil/trace.go:171","msg":"trace[1525878130] transaction","detail":"{read_only:false; response_revision:976; number_of_response:1; }","duration":"126.003808ms","start":"2026-01-29T15:40:38.842283Z","end":"2026-01-29T15:40:38.968287Z","steps":["trace[1525878130] 'process raft request' (duration: 96.820405ms)","trace[1525878130] 'compare' (duration: 28.977999ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T15:40:38.968464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.856825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:1536"} {"level":"info","ts":"2026-01-29T15:40:38.968499Z","caller":"traceutil/trace.go:171","msg":"trace[1377247770] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:976; }","duration":"125.918616ms","start":"2026-01-29T15:40:38.842569Z","end":"2026-01-29T15:40:38.968488Z","steps":["trace[1377247770] 'agreement among raft nodes before linearized reading' (duration: 125.762254ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:40:39.237983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.310197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T15:40:39.238057Z","caller":"traceutil/trace.go:171","msg":"trace[1028298241] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:978; }","duration":"165.417789ms","start":"2026-01-29T15:40:39.072621Z","end":"2026-01-29T15:40:39.238039Z","steps":["trace[1028298241] 'range keys from in-memory index tree' (duration: 164.986582ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:40:39.238356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.361351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/default/default\" ","response":"range_response_count:1 size:171"} {"level":"info","ts":"2026-01-29T15:40:39.238389Z","caller":"traceutil/trace.go:171","msg":"trace[1996576596] range","detail":"{range_begin:/registry/serviceaccounts/default/default; range_end:; response_count:1; response_revision:978; }","duration":"144.418141ms","start":"2026-01-29T15:40:39.093959Z","end":"2026-01-29T15:40:39.238378Z","steps":["trace[1996576596] 'range keys from in-memory index tree' (duration: 144.270149ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:40:39.238603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.933002ms","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-01-29T15:40:39.238664Z","caller":"traceutil/trace.go:171","msg":"trace[1400754868] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:978; }","duration":"125.029454ms","start":"2026-01-29T15:40:39.113623Z","end":"2026-01-29T15:40:39.238653Z","steps":["trace[1400754868] 'range keys from in-memory index tree' (duration: 124.804179ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T15:40:39.367892Z","caller":"traceutil/trace.go:171","msg":"trace[2136018645] transaction","detail":"{read_only:false; response_revision:979; number_of_response:1; }","duration":"122.651602ms","start":"2026-01-29T15:40:39.245187Z","end":"2026-01-29T15:40:39.367839Z","steps":["trace[2136018645] 'process raft request' (duration: 122.54552ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T15:40:39.394013Z","caller":"traceutil/trace.go:171","msg":"trace[817989453] transaction","detail":"{read_only:false; response_revision:980; number_of_response:1; }","duration":"144.721347ms","start":"2026-01-29T15:40:39.24926Z","end":"2026-01-29T15:40:39.393982Z","steps":["trace[817989453] 'process raft request' (duration: 144.574035ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T15:40:39.394568Z","caller":"traceutil/trace.go:171","msg":"trace[544046772] transaction","detail":"{read_only:false; response_revision:981; number_of_response:1; }","duration":"142.214074ms","start":"2026-01-29T15:40:39.252343Z","end":"2026-01-29T15:40:39.394557Z","steps":["trace[544046772] 'process raft request' (duration: 141.574563ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T15:40:39.97929Z","caller":"traceutil/trace.go:171","msg":"trace[1674661376] transaction","detail":"{read_only:false; response_revision:984; number_of_response:1; }","duration":"239.659516ms","start":"2026-01-29T15:40:39.739599Z","end":"2026-01-29T15:40:39.979259Z","steps":["trace[1674661376] 'process raft request' (duration: 172.267889ms)","trace[1674661376] 'compare' (duration: 66.307029ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T15:40:41.517507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.155351ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-29T15:40:41.517781Z","caller":"traceutil/trace.go:171","msg":"trace[860766731] transaction","detail":"{read_only:false; response_revision:987; number_of_response:1; }","duration":"312.028494ms","start":"2026-01-29T15:40:41.205736Z","end":"2026-01-29T15:40:41.517764Z","steps":["trace[860766731] 'process raft request' (duration: 104.550147ms)","trace[860766731] 'compare' (duration: 206.877997ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T15:40:41.517852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T15:40:41.205718Z","time spent":"312.102535ms","remote":"127.0.0.1:50086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-29T15:40:41.518015Z","caller":"traceutil/trace.go:171","msg":"trace[515525215] linearizableReadLoop","detail":"{readStateIndex:1027; appliedIndex:1025; }","duration":"155.829348ms","start":"2026-01-29T15:40:41.362177Z","end":"2026-01-29T15:40:41.518006Z","steps":["trace[515525215] 'read index received' (duration: 149.988768ms)","trace[515525215] 'applied index is now lower than readState.Index' (duration: 5.83974ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T15:40:41.518641Z","caller":"traceutil/trace.go:171","msg":"trace[137057125] transaction","detail":"{read_only:false; response_revision:988; number_of_response:1; }","duration":"273.728585ms","start":"2026-01-29T15:40:41.2449Z","end":"2026-01-29T15:40:41.518628Z","steps":["trace[137057125] 'process raft request' (duration: 272.696958ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:40:41.51881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.633412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-01-29T15:40:41.518844Z","caller":"traceutil/trace.go:171","msg":"trace[756752383] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:988; }","duration":"156.700643ms","start":"2026-01-29T15:40:41.362134Z","end":"2026-01-29T15:40:41.518834Z","steps":["trace[756752383] 'agreement among raft nodes before linearized reading' (duration: 156.591881ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:40:41.519188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.900516ms","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-01-29T15:40:41.519227Z","caller":"traceutil/trace.go:171","msg":"trace[399118891] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:988; }","duration":"146.978797ms","start":"2026-01-29T15:40:41.372239Z","end":"2026-01-29T15:40:41.519218Z","steps":["trace[399118891] 'agreement among raft nodes before linearized reading' (duration: 146.860885ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:40:41.51968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.078595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-01-29T15:40:41.519767Z","caller":"traceutil/trace.go:171","msg":"trace[2038498015] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:988; }","duration":"120.186806ms","start":"2026-01-29T15:40:41.399571Z","end":"2026-01-29T15:40:41.519758Z","steps":["trace[2038498015] 'agreement among raft nodes before linearized reading' (duration: 120.055574ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:40:41.520084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.732684ms","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-01-29T15:40:41.520118Z","caller":"traceutil/trace.go:171","msg":"trace[186038942] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:988; }","duration":"121.787404ms","start":"2026-01-29T15:40:41.398321Z","end":"2026-01-29T15:40:41.520109Z","steps":["trace[186038942] 'agreement among raft nodes before linearized reading' (duration: 121.54957ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:40:42.125152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.440578ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-01-29T15:40:43.502031Z","caller":"traceutil/trace.go:171","msg":"trace[1563462929] transaction","detail":"{read_only:false; response_revision:993; number_of_response:1; }","duration":"293.462558ms","start":"2026-01-29T15:40:43.208552Z","end":"2026-01-29T15:40:43.502014Z","steps":["trace[1563462929] 'process raft request' (duration: 293.288635ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:40:43.958181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.020775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:2475"} {"level":"info","ts":"2026-01-29T15:40:43.958258Z","caller":"traceutil/trace.go:171","msg":"trace[681744432] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:997; }","duration":"109.147748ms","start":"2026-01-29T15:40:43.849091Z","end":"2026-01-29T15:40:43.958239Z","steps":["trace[681744432] 'range keys from in-memory index tree' (duration: 108.841512ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T15:40:45.912365Z","caller":"traceutil/trace.go:171","msg":"trace[1250299488] linearizableReadLoop","detail":"{readStateIndex:1039; appliedIndex:1038; }","duration":"193.163941ms","start":"2026-01-29T15:40:45.719178Z","end":"2026-01-29T15:40:45.912342Z","steps":["trace[1250299488] 'read index received' (duration: 192.975917ms)","trace[1250299488] 'applied index is now lower than readState.Index' (duration: 182.613µs)"],"step_count":2} {"level":"info","ts":"2026-01-29T15:40:45.912767Z","caller":"traceutil/trace.go:171","msg":"trace[1411420629] transaction","detail":"{read_only:false; response_revision:999; number_of_response:1; }","duration":"211.68804ms","start":"2026-01-29T15:40:45.701049Z","end":"2026-01-29T15:40:45.912737Z","steps":["trace[1411420629] 'process raft request' (duration: 211.161381ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:40:45.913188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.987524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-01-29T15:40:45.913365Z","caller":"traceutil/trace.go:171","msg":"trace[2116659572] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:999; }","duration":"194.111847ms","start":"2026-01-29T15:40:45.719155Z","end":"2026-01-29T15:40:45.913267Z","steps":["trace[2116659572] 'agreement among raft nodes before linearized reading' (duration: 193.637299ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:40:45.913512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.510716ms","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-01-29T15:40:45.913581Z","caller":"traceutil/trace.go:171","msg":"trace[540742804] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:999; }","duration":"193.620269ms","start":"2026-01-29T15:40:45.719948Z","end":"2026-01-29T15:40:45.913569Z","steps":["trace[540742804] 'agreement among raft nodes before linearized reading' (duration: 193.268713ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:40:45.913964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.522337ms","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-01-29T15:40:45.914004Z","caller":"traceutil/trace.go:171","msg":"trace[137895522] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:999; }","duration":"193.588778ms","start":"2026-01-29T15:40:45.720406Z","end":"2026-01-29T15:40:45.913994Z","steps":["trace[137895522] 'agreement among raft nodes before linearized reading' (duration: 193.491056ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:40:47.629972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"560.64152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T15:40:47.630161Z","caller":"traceutil/trace.go:171","msg":"trace[2083307365] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1003; }","duration":"560.916184ms","start":"2026-01-29T15:40:47.069224Z","end":"2026-01-29T15:40:47.63014Z","steps":["trace[2083307365] 'range keys from in-memory index tree' (duration: 560.579559ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:40:47.63029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T15:40:47.069206Z","time spent":"561.071486ms","remote":"127.0.0.1:41156","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-01-29T15:40:47.973128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.157143ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-29T15:40:47.97418Z","caller":"traceutil/trace.go:171","msg":"trace[1102709850] linearizableReadLoop","detail":"{readStateIndex:1046; appliedIndex:1045; }","duration":"222.550066ms","start":"2026-01-29T15:40:47.751617Z","end":"2026-01-29T15:40:47.974167Z","steps":["trace[1102709850] 'read index received' (duration: 84.197983ms)","trace[1102709850] 'applied index is now lower than readState.Index' (duration: 138.351043ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T15:40:47.97429Z","caller":"traceutil/trace.go:171","msg":"trace[1031949110] transaction","detail":"{read_only:false; response_revision:1005; number_of_response:1; }","duration":"339.525928ms","start":"2026-01-29T15:40:47.634751Z","end":"2026-01-29T15:40:47.974277Z","steps":["trace[1031949110] 'process raft request' (duration: 201.129364ms)","trace[1031949110] 'compare' (duration: 137.066491ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T15:40:47.975993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T15:40:47.634733Z","time spent":"341.210325ms","remote":"127.0.0.1:50086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-29T15:40:47.975373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.778036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-01-29T15:40:47.97619Z","caller":"traceutil/trace.go:171","msg":"trace[1003694303] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:1005; }","duration":"224.585411ms","start":"2026-01-29T15:40:47.751543Z","end":"2026-01-29T15:40:47.976129Z","steps":["trace[1003694303] 'agreement among raft nodes before linearized reading' (duration: 222.76851ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T15:40:48.252817Z","caller":"traceutil/trace.go:171","msg":"trace[528695799] transaction","detail":"{read_only:false; response_revision:1007; number_of_response:1; }","duration":"185.421593ms","start":"2026-01-29T15:40:48.067369Z","end":"2026-01-29T15:40:48.252791Z","steps":["trace[528695799] 'process raft request' (duration: 165.60701ms)","trace[528695799] 'compare' (duration: 19.675861ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T15:40:48.255939Z","caller":"traceutil/trace.go:171","msg":"trace[717698483] linearizableReadLoop","detail":"{readStateIndex:1050; appliedIndex:1047; }","duration":"183.835068ms","start":"2026-01-29T15:40:48.072092Z","end":"2026-01-29T15:40:48.255927Z","steps":["trace[717698483] 'read index received' (duration: 160.812392ms)","trace[717698483] 'applied index is now lower than readState.Index' (duration: 23.022026ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T15:40:48.256026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.920249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T15:40:48.256044Z","caller":"traceutil/trace.go:171","msg":"trace[1483340836] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1009; }","duration":"183.949909ms","start":"2026-01-29T15:40:48.072088Z","end":"2026-01-29T15:40:48.256038Z","steps":["trace[1483340836] 'agreement among raft nodes before linearized reading' (duration: 183.894358ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T15:40:48.256294Z","caller":"traceutil/trace.go:171","msg":"trace[714564213] transaction","detail":"{read_only:false; response_revision:1009; number_of_response:1; }","duration":"186.925718ms","start":"2026-01-29T15:40:48.06936Z","end":"2026-01-29T15:40:48.256286Z","steps":["trace[714564213] 'process raft request' (duration: 186.543341ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T15:40:48.255962Z","caller":"traceutil/trace.go:171","msg":"trace[527463396] transaction","detail":"{read_only:false; response_revision:1008; number_of_response:1; }","duration":"187.676191ms","start":"2026-01-29T15:40:48.068268Z","end":"2026-01-29T15:40:48.255945Z","steps":["trace[527463396] 'process raft request' (duration: 187.561069ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:40:50.30061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.687704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T15:40:50.300671Z","caller":"traceutil/trace.go:171","msg":"trace[629876790] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1010; }","duration":"228.773975ms","start":"2026-01-29T15:40:50.071883Z","end":"2026-01-29T15:40:50.300657Z","steps":["trace[629876790] 'range keys from in-memory index tree' (duration: 228.646344ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:40:50.300852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.942294ms","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-01-29T15:40:50.300883Z","caller":"traceutil/trace.go:171","msg":"trace[1800135967] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1010; }","duration":"235.006366ms","start":"2026-01-29T15:40:50.065866Z","end":"2026-01-29T15:40:50.300873Z","steps":["trace[1800135967] 'range keys from in-memory index tree' (duration: 234.843203ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T15:40:50.629521Z","caller":"traceutil/trace.go:171","msg":"trace[1956181897] linearizableReadLoop","detail":"{readStateIndex:1052; appliedIndex:1052; }","duration":"324.18068ms","start":"2026-01-29T15:40:50.305324Z","end":"2026-01-29T15:40:50.629505Z","steps":["trace[1956181897] 'read index received' (duration: 324.17209ms)","trace[1956181897] 'applied index is now lower than readState.Index' (duration: 7.41µs)"],"step_count":2} {"level":"warn","ts":"2026-01-29T15:40:50.62977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.396463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T15:40:50.629936Z","caller":"traceutil/trace.go:171","msg":"trace[1412639303] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1011; }","duration":"324.621747ms","start":"2026-01-29T15:40:50.305301Z","end":"2026-01-29T15:40:50.629923Z","steps":["trace[1412639303] 'agreement among raft nodes before linearized reading' (duration: 324.389334ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:40:50.630033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T15:40:50.30529Z","time spent":"324.726859ms","remote":"127.0.0.1:41160","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-01-29T15:40:50.628722Z","caller":"traceutil/trace.go:171","msg":"trace[1787876401] transaction","detail":"{read_only:false; response_revision:1011; number_of_response:1; }","duration":"323.449987ms","start":"2026-01-29T15:40:50.305059Z","end":"2026-01-29T15:40:50.628509Z","steps":["trace[1787876401] 'process raft request' (duration: 323.323775ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:40:50.631576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T15:40:50.30504Z","time spent":"325.702624ms","remote":"127.0.0.1:50086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-29T15:40:50.6822Z","caller":"traceutil/trace.go:171","msg":"trace[802489230] transaction","detail":"{read_only:false; response_revision:1014; number_of_response:1; }","duration":"372.844812ms","start":"2026-01-29T15:40:50.309334Z","end":"2026-01-29T15:40:50.682178Z","steps":["trace[802489230] 'process raft request' (duration: 372.805672ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:40:50.682414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T15:40:50.309329Z","time spent":"372.932084ms","remote":"127.0.0.1:50086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-29T15:40:50.682573Z","caller":"traceutil/trace.go:171","msg":"trace[219237032] transaction","detail":"{read_only:false; response_revision:1012; number_of_response:1; }","duration":"376.435891ms","start":"2026-01-29T15:40:50.306128Z","end":"2026-01-29T15:40:50.682564Z","steps":["trace[219237032] 'process raft request' (duration: 375.883041ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:40:50.682653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T15:40:50.306115Z","time spent":"376.482441ms","remote":"127.0.0.1:50086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-29T15:40:50.682767Z","caller":"traceutil/trace.go:171","msg":"trace[1835299756] transaction","detail":"{read_only:false; response_revision:1013; number_of_response:1; }","duration":"374.462338ms","start":"2026-01-29T15:40:50.308296Z","end":"2026-01-29T15:40:50.682758Z","steps":["trace[1835299756] 'process raft request' (duration: 373.815096ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:40:50.682842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T15:40:50.308283Z","time spent":"374.507129ms","remote":"127.0.0.1:50086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-29T15:40:52.85412Z","caller":"traceutil/trace.go:171","msg":"trace[211117563] linearizableReadLoop","detail":"{readStateIndex:1059; appliedIndex:1058; }","duration":"167.278111ms","start":"2026-01-29T15:40:52.686822Z","end":"2026-01-29T15:40:52.8541Z","steps":["trace[211117563] 'read index received' (duration: 167.041067ms)","trace[211117563] 'applied index is now lower than readState.Index' (duration: 236.254µs)"],"step_count":2} {"level":"info","ts":"2026-01-29T15:40:52.85423Z","caller":"traceutil/trace.go:171","msg":"trace[1213992066] transaction","detail":"{read_only:false; response_revision:1017; number_of_response:1; }","duration":"214.767733ms","start":"2026-01-29T15:40:52.639447Z","end":"2026-01-29T15:40:52.854214Z","steps":["trace[1213992066] 'process raft request' (duration: 214.511269ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:40:52.854612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.814708ms","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-01-29T15:40:52.854654Z","caller":"traceutil/trace.go:171","msg":"trace[766640580] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1017; }","duration":"167.90781ms","start":"2026-01-29T15:40:52.686736Z","end":"2026-01-29T15:40:52.854643Z","steps":["trace[766640580] 'agreement among raft nodes before linearized reading' (duration: 167.769488ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:40:52.854967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.610069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-01-29T15:40:52.855177Z","caller":"traceutil/trace.go:171","msg":"trace[1539082138] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1017; }","duration":"166.760271ms","start":"2026-01-29T15:40:52.688313Z","end":"2026-01-29T15:40:52.855073Z","steps":["trace[1539082138] 'agreement among raft nodes before linearized reading' (duration: 166.397945ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:40:52.855438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.777951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-01-29T15:40:52.855481Z","caller":"traceutil/trace.go:171","msg":"trace[1620450983] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1017; }","duration":"166.838362ms","start":"2026-01-29T15:40:52.688633Z","end":"2026-01-29T15:40:52.855471Z","steps":["trace[1620450983] 'agreement among raft nodes before linearized reading' (duration: 166.73305ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T15:40:55.069077Z","caller":"traceutil/trace.go:171","msg":"trace[396270202] transaction","detail":"{read_only:false; response_revision:1028; number_of_response:1; }","duration":"116.97703ms","start":"2026-01-29T15:40:54.952078Z","end":"2026-01-29T15:40:55.069055Z","steps":["trace[396270202] 'process raft request' (duration: 116.935759ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T15:40:55.069424Z","caller":"traceutil/trace.go:171","msg":"trace[742403522] transaction","detail":"{read_only:false; response_revision:1027; number_of_response:1; }","duration":"117.429797ms","start":"2026-01-29T15:40:54.951981Z","end":"2026-01-29T15:40:55.069411Z","steps":["trace[742403522] 'process raft request' (duration: 116.916198ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T15:40:57.184625Z","caller":"traceutil/trace.go:171","msg":"trace[983035856] transaction","detail":"{read_only:false; response_revision:1035; number_of_response:1; }","duration":"105.579836ms","start":"2026-01-29T15:40:57.079021Z","end":"2026-01-29T15:40:57.184601Z","steps":["trace[983035856] 'process raft request' (duration: 105.497625ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T15:40:57.185057Z","caller":"traceutil/trace.go:171","msg":"trace[645400150] transaction","detail":"{read_only:false; response_revision:1034; number_of_response:1; }","duration":"107.84239ms","start":"2026-01-29T15:40:57.077182Z","end":"2026-01-29T15:40:57.185024Z","steps":["trace[645400150] 'process raft request' (duration: 77.599938ms)","trace[645400150] 'compare' (duration: 29.39896ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T15:40:59.754669Z","caller":"traceutil/trace.go:171","msg":"trace[1802612486] transaction","detail":"{read_only:false; response_revision:1042; number_of_response:1; }","duration":"132.282487ms","start":"2026-01-29T15:40:59.622366Z","end":"2026-01-29T15:40:59.754649Z","steps":["trace[1802612486] 'process raft request' (duration: 132.181045ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:41:00.5236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.304883ms","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-01-29T15:41:00.523676Z","caller":"traceutil/trace.go:171","msg":"trace[2093875519] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1043; }","duration":"310.425375ms","start":"2026-01-29T15:41:00.213234Z","end":"2026-01-29T15:41:00.523659Z","steps":["trace[2093875519] 'range keys from in-memory index tree' (duration: 310.138801ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:41:00.523747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T15:41:00.213217Z","time spent":"310.519546ms","remote":"127.0.0.1:50086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-01-29T15:41:00.523751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.657884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T15:41:00.523798Z","caller":"traceutil/trace.go:171","msg":"trace[1118109222] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1043; }","duration":"451.739175ms","start":"2026-01-29T15:41:00.072046Z","end":"2026-01-29T15:41:00.523785Z","steps":["trace[1118109222] 'range keys from in-memory index tree' (duration: 451.608043ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:41:00.52382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T15:41:00.072032Z","time spent":"451.784126ms","remote":"127.0.0.1:41156","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-01-29T15:41:02.383556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"941.285306ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T15:41:02.383677Z","caller":"traceutil/trace.go:171","msg":"trace[1518997476] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1044; }","duration":"941.419018ms","start":"2026-01-29T15:41:01.442238Z","end":"2026-01-29T15:41:02.383657Z","steps":["trace[1518997476] 'range keys from in-memory index tree' (duration: 941.269186ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:41:02.385727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.332007066s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T15:41:02.385779Z","caller":"traceutil/trace.go:171","msg":"trace[1190274049] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:1044; }","duration":"1.332097038s","start":"2026-01-29T15:41:01.053668Z","end":"2026-01-29T15:41:02.385765Z","steps":["trace[1190274049] 'count revisions from in-memory index tree' (duration: 1.331917245s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:41:02.385917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T15:41:01.053649Z","time spent":"1.33225433s","remote":"127.0.0.1:50438","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-01-29T15:41:02.386242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.54053785s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T15:41:02.386373Z","caller":"traceutil/trace.go:171","msg":"trace[690571999] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:1044; }","duration":"1.54328082s","start":"2026-01-29T15:41:00.84307Z","end":"2026-01-29T15:41:02.386351Z","steps":["trace[690571999] 'range keys from in-memory index tree' (duration: 1.540485169s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:41:02.387481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T15:41:00.843057Z","time spent":"1.544411687s","remote":"127.0.0.1:49956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumes/test-pv\" "} {"level":"warn","ts":"2026-01-29T15:41:02.387866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.062667014s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T15:41:02.388283Z","caller":"traceutil/trace.go:171","msg":"trace[155553570] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1044; }","duration":"1.06309986s","start":"2026-01-29T15:41:01.325163Z","end":"2026-01-29T15:41:02.388263Z","steps":["trace[155553570] 'range keys from in-memory index tree' (duration: 1.062617433s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:41:02.388383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T15:41:01.325148Z","time spent":"1.063224603s","remote":"127.0.0.1:41156","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-01-29T15:41:02.38638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.327178466s","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-01-29T15:41:02.388822Z","caller":"traceutil/trace.go:171","msg":"trace[1240626967] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1044; }","duration":"1.329659871s","start":"2026-01-29T15:41:01.059151Z","end":"2026-01-29T15:41:02.388811Z","steps":["trace[1240626967] 'range keys from in-memory index tree' (duration: 1.326891581s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:41:02.389039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T15:41:01.059133Z","time spent":"1.329893065s","remote":"127.0.0.1:50086","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-01-29T15:41:02.386583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.241441099s","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-01-29T15:41:02.39017Z","caller":"traceutil/trace.go:171","msg":"trace[60702609] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1044; }","duration":"1.245047462s","start":"2026-01-29T15:41:01.14511Z","end":"2026-01-29T15:41:02.390158Z","steps":["trace[60702609] 'range keys from in-memory index tree' (duration: 1.241339956s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:41:02.390751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T15:41:01.145101Z","time spent":"1.245638491s","remote":"127.0.0.1:50086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-01-29T15:41:02.386651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.168690875s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-01-29T15:41:02.391007Z","caller":"traceutil/trace.go:171","msg":"trace[983225152] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1044; }","duration":"1.17307409s","start":"2026-01-29T15:41:01.217922Z","end":"2026-01-29T15:41:02.390997Z","steps":["trace[983225152] 'range keys from in-memory index tree' (duration: 1.168578552s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:41:02.391079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T15:41:01.217904Z","time spent":"1.173164491s","remote":"127.0.0.1:50086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-01-29T15:41:02.386708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.141289957s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-01-29T15:41:02.391374Z","caller":"traceutil/trace.go:171","msg":"trace[969911833] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1044; }","duration":"1.145985885s","start":"2026-01-29T15:41:01.245378Z","end":"2026-01-29T15:41:02.391364Z","steps":["trace[969911833] 'range keys from in-memory index tree' (duration: 1.141204875s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:41:02.39145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T15:41:01.24536Z","time spent":"1.146079467s","remote":"127.0.0.1:50086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-01-29T15:41:02.529563Z","caller":"traceutil/trace.go:171","msg":"trace[232307145] transaction","detail":"{read_only:false; response_revision:1045; number_of_response:1; }","duration":"737.600758ms","start":"2026-01-29T15:41:01.791932Z","end":"2026-01-29T15:41:02.529533Z","steps":["trace[232307145] 'process raft request' (duration: 737.474357ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:41:02.529994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T15:41:01.791907Z","time spent":"737.769532ms","remote":"127.0.0.1:50086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-29T15:41:02.659085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.66267ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-01-29T15:41:02.659461Z","caller":"traceutil/trace.go:171","msg":"trace[398892038] transaction","detail":"{read_only:false; response_revision:1046; number_of_response:1; }","duration":"263.995307ms","start":"2026-01-29T15:41:02.395448Z","end":"2026-01-29T15:41:02.659444Z","steps":["trace[398892038] 'process raft request' (duration: 263.712143ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T15:41:02.681306Z","caller":"traceutil/trace.go:171","msg":"trace[2068252237] transaction","detail":"{read_only:false; response_revision:1047; number_of_response:1; }","duration":"283.83154ms","start":"2026-01-29T15:41:02.397456Z","end":"2026-01-29T15:41:02.681287Z","steps":["trace[2068252237] 'process raft request' (duration: 261.978817ms)","trace[2068252237] 'compare' (duration: 21.61892ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T15:41:02.681534Z","caller":"traceutil/trace.go:171","msg":"trace[1118584141] transaction","detail":"{read_only:false; response_revision:1048; number_of_response:1; }","duration":"283.993813ms","start":"2026-01-29T15:41:02.397495Z","end":"2026-01-29T15:41:02.681489Z","steps":["trace[1118584141] 'process raft request' (duration: 283.76462ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T15:41:02.681711Z","caller":"traceutil/trace.go:171","msg":"trace[1127648479] linearizableReadLoop","detail":"{readStateIndex:1089; appliedIndex:1088; }","duration":"611.929836ms","start":"2026-01-29T15:41:02.069773Z","end":"2026-01-29T15:41:02.681703Z","steps":["trace[1127648479] 'read index received' (duration: 460.11903ms)","trace[1127648479] 'applied index is now lower than readState.Index' (duration: 151.810236ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T15:41:02.681844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.21092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-01-29T15:41:02.681862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"612.075448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T15:41:02.681881Z","caller":"traceutil/trace.go:171","msg":"trace[1064460074] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1049; }","duration":"612.14032ms","start":"2026-01-29T15:41:02.069736Z","end":"2026-01-29T15:41:02.681876Z","steps":["trace[1064460074] 'agreement among raft nodes before linearized reading' (duration: 612.095909ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T15:41:02.681884Z","caller":"traceutil/trace.go:171","msg":"trace[1385082274] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:1049; }","duration":"287.260441ms","start":"2026-01-29T15:41:02.394611Z","end":"2026-01-29T15:41:02.681871Z","steps":["trace[1385082274] 'agreement among raft nodes before linearized reading' (duration: 287.19274ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:41:02.681896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T15:41:02.06972Z","time spent":"612.17268ms","remote":"127.0.0.1:41160","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-01-29T15:41:02.681981Z","caller":"traceutil/trace.go:171","msg":"trace[300456506] transaction","detail":"{read_only:false; response_revision:1049; number_of_response:1; }","duration":"284.453959ms","start":"2026-01-29T15:41:02.39752Z","end":"2026-01-29T15:41:02.681974Z","steps":["trace[300456506] 'process raft request' (duration: 283.894231ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T15:41:08.986089Z","caller":"traceutil/trace.go:171","msg":"trace[2131080259] transaction","detail":"{read_only:false; response_revision:1070; number_of_response:1; }","duration":"137.442999ms","start":"2026-01-29T15:41:08.84862Z","end":"2026-01-29T15:41:08.986063Z","steps":["trace[2131080259] 'process raft request' (duration: 111.224685ms)","trace[2131080259] 'compare' (duration: 25.856589ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T15:41:08.986527Z","caller":"traceutil/trace.go:171","msg":"trace[1086544983] transaction","detail":"{read_only:false; response_revision:1071; number_of_response:1; }","duration":"135.983138ms","start":"2026-01-29T15:41:08.850533Z","end":"2026-01-29T15:41:08.986516Z","steps":["trace[1086544983] 'process raft request' (duration: 135.406249ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T15:41:08.986106Z","caller":"traceutil/trace.go:171","msg":"trace[115973540] linearizableReadLoop","detail":"{readStateIndex:1116; appliedIndex:1114; }","duration":"122.810019ms","start":"2026-01-29T15:41:08.863272Z","end":"2026-01-29T15:41:08.986082Z","steps":["trace[115973540] 'read index received' (duration: 96.533355ms)","trace[115973540] 'applied index is now lower than readState.Index' (duration: 26.275554ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T15:41:08.988114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.811608ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" limit:500 ","response":"range_response_count:1 size:5052"} {"level":"info","ts":"2026-01-29T15:41:08.988152Z","caller":"traceutil/trace.go:171","msg":"trace[1994844283] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:1; response_revision:1071; }","duration":"124.88904ms","start":"2026-01-29T15:41:08.863251Z","end":"2026-01-29T15:41:08.98814Z","steps":["trace[1994844283] 'agreement among raft nodes before linearized reading' (duration: 124.679027ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T15:41:08.988722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.31315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" limit:500 ","response":"range_response_count:67 size:63039"} {"level":"info","ts":"2026-01-29T15:41:08.988752Z","caller":"traceutil/trace.go:171","msg":"trace[72651758] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:67; response_revision:1071; }","duration":"105.365351ms","start":"2026-01-29T15:41:08.883378Z","end":"2026-01-29T15:41:08.988744Z","steps":["trace[72651758] 'agreement among raft nodes before linearized reading' (duration: 104.987666ms)"],"step_count":1}