{"level":"warn","ts":"2026-02-02T17:41:50.751636Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-02T17:41:50.751719Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.231: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.19.213.231:2380","--initial-cluster=instance=https://199.19.213.231:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.231:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.231: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-02-02T17:41:50.75189Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-02T17:41:50.751905Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.231:2380"]} {"level":"info","ts":"2026-02-02T17:41:50.751991Z","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-02-02T17:41:50.75268Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.231:2379"]} {"level":"info","ts":"2026-02-02T17:41:50.752952Z","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.19.213.231:2380"],"listen-peer-urls":["https://199.19.213.231:2380"],"advertise-client-urls":["https://199.19.213.231:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.231:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.231: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-02-02T17:41:50.758263Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.087864ms"} {"level":"info","ts":"2026-02-02T17:41:50.778222Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"8bb149ac537fafcb","cluster-id":"6a60f8f1d4e49112"} {"level":"info","ts":"2026-02-02T17:41:50.778283Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8bb149ac537fafcb switched to configuration voters=()"} {"level":"info","ts":"2026-02-02T17:41:50.7783Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8bb149ac537fafcb became follower at term 0"} {"level":"info","ts":"2026-02-02T17:41:50.778309Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 8bb149ac537fafcb [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-02T17:41:50.778321Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8bb149ac537fafcb became follower at term 1"} {"level":"info","ts":"2026-02-02T17:41:50.77835Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8bb149ac537fafcb switched to configuration voters=(10065907646633848779)"} {"level":"warn","ts":"2026-02-02T17:41:50.785119Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-02T17:41:50.788537Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-02T17:41:50.789716Z","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-02-02T17:41:50.793951Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"8bb149ac537fafcb","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-02T17:41:50.800438Z","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-02-02T17:41:50.800488Z","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-02-02T17:41:50.800496Z","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-02-02T17:41:50.801144Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"8bb149ac537fafcb","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-02T17:41:50.801554Z","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-02-02T17:41:50.801796Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"8bb149ac537fafcb","initial-advertise-peer-urls":["https://199.19.213.231:2380"],"listen-peer-urls":["https://199.19.213.231:2380"],"advertise-client-urls":["https://199.19.213.231:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.231:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-02T17:41:50.801821Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-02T17:41:50.801899Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.231:2380"} {"level":"info","ts":"2026-02-02T17:41:50.801971Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.231:2380"} {"level":"info","ts":"2026-02-02T17:41:50.802969Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8bb149ac537fafcb switched to configuration voters=(10065907646633848779)"} {"level":"info","ts":"2026-02-02T17:41:50.803029Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"6a60f8f1d4e49112","local-member-id":"8bb149ac537fafcb","added-peer-id":"8bb149ac537fafcb","added-peer-peer-urls":["https://199.19.213.231:2380"]} {"level":"info","ts":"2026-02-02T17:41:50.878912Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8bb149ac537fafcb is starting a new election at term 1"} {"level":"info","ts":"2026-02-02T17:41:50.878949Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8bb149ac537fafcb became pre-candidate at term 1"} {"level":"info","ts":"2026-02-02T17:41:50.878967Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8bb149ac537fafcb received MsgPreVoteResp from 8bb149ac537fafcb at term 1"} {"level":"info","ts":"2026-02-02T17:41:50.878977Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8bb149ac537fafcb became candidate at term 2"} {"level":"info","ts":"2026-02-02T17:41:50.878983Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8bb149ac537fafcb received MsgVoteResp from 8bb149ac537fafcb at term 2"} {"level":"info","ts":"2026-02-02T17:41:50.879203Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8bb149ac537fafcb became leader at term 2"} {"level":"info","ts":"2026-02-02T17:41:50.879218Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 8bb149ac537fafcb elected leader 8bb149ac537fafcb at term 2"} {"level":"info","ts":"2026-02-02T17:41:50.880578Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"8bb149ac537fafcb","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.231:2379]}","request-path":"/0/members/8bb149ac537fafcb/attributes","cluster-id":"6a60f8f1d4e49112","publish-timeout":"7s"} {"level":"info","ts":"2026-02-02T17:41:50.88323Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-02T17:41:50.883647Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-02T17:41:50.883662Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-02T17:41:50.885224Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-02T17:41:50.886085Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-02T17:41:50.886287Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-02T17:41:50.889111Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.231:2379"} {"level":"info","ts":"2026-02-02T17:41:50.897384Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"6a60f8f1d4e49112","local-member-id":"8bb149ac537fafcb","cluster-version":"3.5"} {"level":"info","ts":"2026-02-02T17:41:50.898158Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-02T17:41:50.898182Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-02-02T17:42:04.050479Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12667390035785301229,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-02T17:42:04.551245Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12667390035785301229,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-02T17:42:05.052295Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12667390035785301229,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-02T17:42:05.553062Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12667390035785301229,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-02T17:42:06.054152Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12667390035785301229,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-02T17:42:06.554449Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12667390035785301229,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-02T17:42:07.054862Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12667390035785301229,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-02T17:42:07.55589Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12667390035785301229,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-02T17:42:08.056736Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12667390035785301229,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-02T17:42:08.546011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.995833122s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-02T17:42:08.546117Z","caller":"traceutil/trace.go:171","msg":"trace[760936977] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; }","duration":"4.995980942s","start":"2026-02-02T17:42:03.550107Z","end":"2026-02-02T17:42:08.546087Z","steps":["trace[760936977] 'agreement among raft nodes before linearized reading' (duration: 4.995864822s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:08.54618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:03.550089Z","time spent":"4.996081584s","remote":"127.0.0.1:51044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} 2026/02/02 17:42:08 WARNING: [core] [Server #5] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-02T17:42:08.557573Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12667390035785301229,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-02T17:42:09.057833Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12667390035785301229,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-02T17:42:09.514554Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"6.027743783s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-02T17:42:09.524399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.896533507s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:1 size:197"} {"level":"info","ts":"2026-02-02T17:42:09.524472Z","caller":"traceutil/trace.go:171","msg":"trace[1825609489] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:304; }","duration":"7.896679519s","start":"2026-02-02T17:42:01.627776Z","end":"2026-02-02T17:42:09.524456Z","steps":["trace[1825609489] 'range keys from in-memory index tree' (duration: 7.896426906s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:09.524559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:01.627757Z","time spent":"7.896793759s","remote":"127.0.0.1:50966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":220,"request content":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" "} {"level":"warn","ts":"2026-02-02T17:42:09.524822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.845084126s","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-02-02T17:42:09.524862Z","caller":"traceutil/trace.go:171","msg":"trace[270604417] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:304; }","duration":"7.845117956s","start":"2026-02-02T17:42:01.679729Z","end":"2026-02-02T17:42:09.524847Z","steps":["trace[270604417] 'range keys from in-memory index tree' (duration: 7.845017126s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:09.524888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:01.679684Z","time spent":"7.845198398s","remote":"127.0.0.1:50966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":1,"response size":241,"request content":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" "} {"level":"warn","ts":"2026-02-02T17:42:09.525009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.797413544s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"info","ts":"2026-02-02T17:42:09.525041Z","caller":"traceutil/trace.go:171","msg":"trace[1126618393] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:304; }","duration":"7.797477386s","start":"2026-02-02T17:42:01.727556Z","end":"2026-02-02T17:42:09.525033Z","steps":["trace[1126618393] 'range keys from in-memory index tree' (duration: 7.797258222s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:09.525071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:01.727518Z","time spent":"7.797547286s","remote":"127.0.0.1:50966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":257,"request content":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" "} {"level":"warn","ts":"2026-02-02T17:42:09.525188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.747867879s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-02-02T17:42:09.525219Z","caller":"traceutil/trace.go:171","msg":"trace[620836361] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:304; }","duration":"7.747953449s","start":"2026-02-02T17:42:01.777257Z","end":"2026-02-02T17:42:09.525211Z","steps":["trace[620836361] 'range keys from in-memory index tree' (duration: 7.747775588s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:09.525243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:01.777237Z","time spent":"7.747999869s","remote":"127.0.0.1:50966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":232,"request content":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" "} {"level":"warn","ts":"2026-02-02T17:42:09.525369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.701756669s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6898"} {"level":"info","ts":"2026-02-02T17:42:09.525401Z","caller":"traceutil/trace.go:171","msg":"trace[1874077159] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:304; }","duration":"7.701839909s","start":"2026-02-02T17:42:01.823552Z","end":"2026-02-02T17:42:09.525392Z","steps":["trace[1874077159] 'range keys from in-memory index tree' (duration: 7.701661248s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:09.525431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:01.823536Z","time spent":"7.701889189s","remote":"127.0.0.1:50948","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":6921,"request content":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" "} {"level":"warn","ts":"2026-02-02T17:42:09.525562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.697850548s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-02-02T17:42:09.525591Z","caller":"traceutil/trace.go:171","msg":"trace[2006209224] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:304; }","duration":"7.697893108s","start":"2026-02-02T17:42:01.82769Z","end":"2026-02-02T17:42:09.525583Z","steps":["trace[2006209224] 'range keys from in-memory index tree' (duration: 7.697815588s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:09.525616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:01.827684Z","time spent":"7.697926779s","remote":"127.0.0.1:50966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":230,"request content":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" "} {"level":"warn","ts":"2026-02-02T17:42:09.525863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.64805195s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-02-02T17:42:09.525898Z","caller":"traceutil/trace.go:171","msg":"trace[1951051452] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:304; }","duration":"7.648150601s","start":"2026-02-02T17:42:01.877739Z","end":"2026-02-02T17:42:09.525889Z","steps":["trace[1951051452] 'range keys from in-memory index tree' (duration: 7.647941139s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:09.525923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:01.877722Z","time spent":"7.648195172s","remote":"127.0.0.1:50966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":232,"request content":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" "} {"level":"warn","ts":"2026-02-02T17:42:09.526217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.59801434s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-02-02T17:42:09.526279Z","caller":"traceutil/trace.go:171","msg":"trace[1797649044] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:304; }","duration":"7.598092241s","start":"2026-02-02T17:42:01.928154Z","end":"2026-02-02T17:42:09.526246Z","steps":["trace[1797649044] 'range keys from in-memory index tree' (duration: 7.597920649s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:09.526316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:01.928131Z","time spent":"7.598176661s","remote":"127.0.0.1:50966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":218,"request content":"key:\"/registry/serviceaccounts/kube-system/node-controller\" "} {"level":"warn","ts":"2026-02-02T17:42:09.52651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.549021888s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-02-02T17:42:09.52673Z","caller":"traceutil/trace.go:171","msg":"trace[833795253] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:304; }","duration":"7.54927246s","start":"2026-02-02T17:42:01.977446Z","end":"2026-02-02T17:42:09.526719Z","steps":["trace[833795253] 'range keys from in-memory index tree' (duration: 7.548958868s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:09.526772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:01.97743Z","time spent":"7.549332422s","remote":"127.0.0.1:50966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":216,"request content":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" "} {"level":"warn","ts":"2026-02-02T17:42:09.52718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.499380264s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-02-02T17:42:09.527219Z","caller":"traceutil/trace.go:171","msg":"trace[185747641] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:304; }","duration":"7.499504195s","start":"2026-02-02T17:42:02.027705Z","end":"2026-02-02T17:42:09.527209Z","steps":["trace[185747641] 'range keys from in-memory index tree' (duration: 7.499304313s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:09.527282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:02.027687Z","time spent":"7.499584805s","remote":"127.0.0.1:50966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":226,"request content":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" "} {"level":"warn","ts":"2026-02-02T17:42:09.527985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.448690932s","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-02-02T17:42:09.528025Z","caller":"traceutil/trace.go:171","msg":"trace[603102771] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:304; }","duration":"7.448801042s","start":"2026-02-02T17:42:02.079215Z","end":"2026-02-02T17:42:09.528016Z","steps":["trace[603102771] 'range keys from in-memory index tree' (duration: 7.448581011s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:09.528152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:02.079195Z","time spent":"7.448914813s","remote":"127.0.0.1:50966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":237,"request content":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" "} {"level":"warn","ts":"2026-02-02T17:42:09.528387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.40054742s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-02-02T17:42:09.528446Z","caller":"traceutil/trace.go:171","msg":"trace[2075651048] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:304; }","duration":"7.400623811s","start":"2026-02-02T17:42:02.127786Z","end":"2026-02-02T17:42:09.528409Z","steps":["trace[2075651048] 'range keys from in-memory index tree' (duration: 7.40045231s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:09.528486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:02.127762Z","time spent":"7.400714781s","remote":"127.0.0.1:50966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":230,"request content":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" "} {"level":"warn","ts":"2026-02-02T17:42:09.528803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.330852562s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4260"} {"level":"info","ts":"2026-02-02T17:42:09.52884Z","caller":"traceutil/trace.go:171","msg":"trace[1462325326] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:304; }","duration":"7.330940602s","start":"2026-02-02T17:42:02.197891Z","end":"2026-02-02T17:42:09.528831Z","steps":["trace[1462325326] 'range keys from in-memory index tree' (duration: 7.330666039s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:09.52891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:02.197882Z","time spent":"7.331016712s","remote":"127.0.0.1:50948","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":4283,"request content":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" "} {"level":"warn","ts":"2026-02-02T17:42:09.529189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.330991262s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:5270"} {"level":"info","ts":"2026-02-02T17:42:09.529224Z","caller":"traceutil/trace.go:171","msg":"trace[820586377] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:304; }","duration":"7.331042092s","start":"2026-02-02T17:42:02.198173Z","end":"2026-02-02T17:42:09.529215Z","steps":["trace[820586377] 'range keys from in-memory index tree' (duration: 7.330946902s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:09.52925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:02.197655Z","time spent":"7.331589127s","remote":"127.0.0.1:50948","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":5293,"request content":"key:\"/registry/pods/kube-system/etcd-instance\" "} {"level":"warn","ts":"2026-02-02T17:42:09.529478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.331156153s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6898"} {"level":"info","ts":"2026-02-02T17:42:09.529513Z","caller":"traceutil/trace.go:171","msg":"trace[1861633825] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:304; }","duration":"7.331203973s","start":"2026-02-02T17:42:02.1983Z","end":"2026-02-02T17:42:09.529504Z","steps":["trace[1861633825] 'range keys from in-memory index tree' (duration: 7.331011782s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:09.52954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:02.198295Z","time spent":"7.331239324s","remote":"127.0.0.1:50948","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":6921,"request content":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" "} {"level":"warn","ts":"2026-02-02T17:42:09.529774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.331501616s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:4597"} {"level":"info","ts":"2026-02-02T17:42:09.529809Z","caller":"traceutil/trace.go:171","msg":"trace[1325522667] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:304; }","duration":"7.331657258s","start":"2026-02-02T17:42:02.198143Z","end":"2026-02-02T17:42:09.5298Z","steps":["trace[1325522667] 'range keys from in-memory index tree' (duration: 7.331292514s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:09.529834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:02.198137Z","time spent":"7.331691958s","remote":"127.0.0.1:50948","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":4620,"request content":"key:\"/registry/pods/kube-system/kube-vip-instance\" "} {"level":"warn","ts":"2026-02-02T17:42:09.529984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.331153523s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7237"} {"level":"warn","ts":"2026-02-02T17:42:09.530012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"6.308969441s","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-02-02T17:42:09.530038Z","caller":"traceutil/trace.go:171","msg":"trace[626280457] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:304; }","duration":"7.331223883s","start":"2026-02-02T17:42:02.198801Z","end":"2026-02-02T17:42:09.530025Z","steps":["trace[626280457] 'range keys from in-memory index tree' (duration: 7.331060822s)"],"step_count":1} {"level":"info","ts":"2026-02-02T17:42:09.530043Z","caller":"traceutil/trace.go:171","msg":"trace[1098617397] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:304; }","duration":"6.309044471s","start":"2026-02-02T17:42:03.220988Z","end":"2026-02-02T17:42:09.530033Z","steps":["trace[1098617397] 'range keys from in-memory index tree' (duration: 6.30888221s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:09.530071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:03.220968Z","time spent":"6.309097431s","remote":"127.0.0.1:51044","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-02-02T17:42:09.53009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:02.198791Z","time spent":"7.331275523s","remote":"127.0.0.1:50948","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":7260,"request content":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" "} {"level":"warn","ts":"2026-02-02T17:42:09.530193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"6.308288695s","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-02-02T17:42:09.530219Z","caller":"traceutil/trace.go:171","msg":"trace[822011745] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:304; }","duration":"6.308356076s","start":"2026-02-02T17:42:03.221855Z","end":"2026-02-02T17:42:09.530211Z","steps":["trace[822011745] 'range keys from in-memory index tree' (duration: 6.308238295s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:09.530246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:03.221844Z","time spent":"6.308396927s","remote":"127.0.0.1:51044","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":"warn","ts":"2026-02-02T17:42:09.530446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"6.257864509s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T17:42:09.530473Z","caller":"traceutil/trace.go:171","msg":"trace[2001259272] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:304; }","duration":"6.257937239s","start":"2026-02-02T17:42:03.272527Z","end":"2026-02-02T17:42:09.530464Z","steps":["trace[2001259272] 'range keys from in-memory index tree' (duration: 6.257784918s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:09.530496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:03.272508Z","time spent":"6.257982849s","remote":"127.0.0.1:50766","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-02-02T17:42:09.530575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"6.118023798s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"","error":"rangeKeys: context cancelled: context deadline exceeded"} {"level":"info","ts":"2026-02-02T17:42:09.530601Z","caller":"traceutil/trace.go:171","msg":"trace[171496863] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; }","duration":"6.118075468s","start":"2026-02-02T17:42:03.412517Z","end":"2026-02-02T17:42:09.530593Z","steps":["trace[171496863] 'range keys from in-memory index tree' (duration: 6.117889537s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:09.53064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:03.412498Z","time spent":"6.118137148s","remote":"127.0.0.1:51044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} 2026/02/02 17:42:09 WARNING: [core] [Server #5] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"info","ts":"2026-02-02T17:42:09.53162Z","caller":"traceutil/trace.go:171","msg":"trace[570137192] transaction","detail":"{read_only:false; response_revision:305; number_of_response:1; }","duration":"6.044971118s","start":"2026-02-02T17:42:03.486638Z","end":"2026-02-02T17:42:09.531609Z","steps":["trace[570137192] 'process raft request' (duration: 6.028226196s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:09.531976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:03.486594Z","time spent":"6.045055619s","remote":"127.0.0.1:51044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-02T17:42:09.533796Z","caller":"traceutil/trace.go:171","msg":"trace[817260904] linearizableReadLoop","detail":"{readStateIndex:317; appliedIndex:314; }","duration":"5.983631068s","start":"2026-02-02T17:42:03.550158Z","end":"2026-02-02T17:42:09.533789Z","steps":["trace[817260904] 'read index received' (duration: 5.964634168s)","trace[817260904] 'applied index is now lower than readState.Index' (duration: 18.99637ms)"],"step_count":2} {"level":"info","ts":"2026-02-02T17:42:09.533997Z","caller":"traceutil/trace.go:171","msg":"trace[837801592] transaction","detail":"{read_only:false; response_revision:306; number_of_response:1; }","duration":"4.257950481s","start":"2026-02-02T17:42:05.276042Z","end":"2026-02-02T17:42:09.533992Z","steps":["trace[837801592] 'process raft request' (duration: 4.257681239s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:09.534023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:05.275997Z","time spent":"4.258011202s","remote":"127.0.0.1:50850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":757,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-02T17:42:09.534115Z","caller":"traceutil/trace.go:171","msg":"trace[683038980] transaction","detail":"{read_only:false; response_revision:307; number_of_response:1; }","duration":"3.258051968s","start":"2026-02-02T17:42:06.276059Z","end":"2026-02-02T17:42:09.534111Z","steps":["trace[683038980] 'process raft request' (duration: 3.257710305s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:09.534139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:06.276035Z","time spent":"3.258090088s","remote":"127.0.0.1:51044","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-02-02T17:42:09.534204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.919265461s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.231\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-02T17:42:09.534217Z","caller":"traceutil/trace.go:171","msg":"trace[1933173150] range","detail":"{range_begin:/registry/masterleases/199.19.213.231; range_end:; response_count:1; response_revision:307; }","duration":"4.919306172s","start":"2026-02-02T17:42:04.614906Z","end":"2026-02-02T17:42:09.534213Z","steps":["trace[1933173150] 'agreement among raft nodes before linearized reading' (duration: 4.919281381s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:09.534228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:04.614885Z","time spent":"4.919340252s","remote":"127.0.0.1:50796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.231\" "} {"level":"warn","ts":"2026-02-02T17:42:09.53441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.689403817s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T17:42:09.534481Z","caller":"traceutil/trace.go:171","msg":"trace[1015320421] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:307; }","duration":"1.689476817s","start":"2026-02-02T17:42:07.845Z","end":"2026-02-02T17:42:09.534477Z","steps":["trace[1015320421] 'agreement among raft nodes before linearized reading' (duration: 1.689368787s)"],"step_count":1} {"level":"info","ts":"2026-02-02T17:42:09.776765Z","caller":"traceutil/trace.go:171","msg":"trace[1195238700] linearizableReadLoop","detail":"{readStateIndex:322; appliedIndex:318; }","duration":"200.792931ms","start":"2026-02-02T17:42:09.575951Z","end":"2026-02-02T17:42:09.776744Z","steps":["trace[1195238700] 'read index received' (duration: 174.085355ms)","trace[1195238700] 'applied index is now lower than readState.Index' (duration: 26.706396ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T17:42:09.777092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.438786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-02-02T17:42:09.777185Z","caller":"traceutil/trace.go:171","msg":"trace[517137995] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:311; }","duration":"223.551297ms","start":"2026-02-02T17:42:09.55362Z","end":"2026-02-02T17:42:09.777171Z","steps":["trace[517137995] 'agreement among raft nodes before linearized reading' (duration: 223.335645ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:09.778069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.794578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"info","ts":"2026-02-02T17:42:09.778162Z","caller":"traceutil/trace.go:171","msg":"trace[1373845961] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:311; }","duration":"224.539883ms","start":"2026-02-02T17:42:09.553609Z","end":"2026-02-02T17:42:09.778149Z","steps":["trace[1373845961] 'agreement among raft nodes before linearized reading' (duration: 223.760688ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:09.778344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.730595ms","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-02-02T17:42:09.778426Z","caller":"traceutil/trace.go:171","msg":"trace[36641276] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:311; }","duration":"224.818965ms","start":"2026-02-02T17:42:09.553599Z","end":"2026-02-02T17:42:09.778418Z","steps":["trace[36641276] 'agreement among raft nodes before linearized reading' (duration: 224.708524ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:09.778515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.914517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-02-02T17:42:09.778577Z","caller":"traceutil/trace.go:171","msg":"trace[334107766] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:311; }","duration":"224.981407ms","start":"2026-02-02T17:42:09.553589Z","end":"2026-02-02T17:42:09.77857Z","steps":["trace[334107766] 'agreement among raft nodes before linearized reading' (duration: 224.903657ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:09.778732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.149469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-02-02T17:42:09.778803Z","caller":"traceutil/trace.go:171","msg":"trace[809633537] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:311; }","duration":"225.20675ms","start":"2026-02-02T17:42:09.55357Z","end":"2026-02-02T17:42:09.778776Z","steps":["trace[809633537] 'agreement among raft nodes before linearized reading' (duration: 225.122579ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:09.778936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.365391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3841"} {"level":"info","ts":"2026-02-02T17:42:09.779001Z","caller":"traceutil/trace.go:171","msg":"trace[1219394981] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:311; }","duration":"225.435091ms","start":"2026-02-02T17:42:09.553559Z","end":"2026-02-02T17:42:09.778994Z","steps":["trace[1219394981] 'agreement among raft nodes before linearized reading' (duration: 225.349971ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:09.779158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.598732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3841"} {"level":"info","ts":"2026-02-02T17:42:09.779224Z","caller":"traceutil/trace.go:171","msg":"trace[1139579753] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:311; }","duration":"225.669163ms","start":"2026-02-02T17:42:09.553548Z","end":"2026-02-02T17:42:09.779217Z","steps":["trace[1139579753] 'agreement among raft nodes before linearized reading' (duration: 225.582822ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:09.779416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.863314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-02-02T17:42:09.780673Z","caller":"traceutil/trace.go:171","msg":"trace[525980326] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:311; }","duration":"227.126843ms","start":"2026-02-02T17:42:09.553537Z","end":"2026-02-02T17:42:09.780664Z","steps":["trace[525980326] 'agreement among raft nodes before linearized reading' (duration: 225.801314ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:09.780952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.414175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3841"} {"level":"info","ts":"2026-02-02T17:42:09.781022Z","caller":"traceutil/trace.go:171","msg":"trace[608301795] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:311; }","duration":"227.497515ms","start":"2026-02-02T17:42:09.553516Z","end":"2026-02-02T17:42:09.781014Z","steps":["trace[608301795] 'agreement among raft nodes before linearized reading' (duration: 227.397635ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T17:42:09.780986Z","caller":"traceutil/trace.go:171","msg":"trace[1171816582] transaction","detail":"{read_only:false; response_revision:309; number_of_response:1; }","duration":"227.481015ms","start":"2026-02-02T17:42:09.55339Z","end":"2026-02-02T17:42:09.780871Z","steps":["trace[1171816582] 'process raft request' (duration: 223.238815ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T17:42:09.781904Z","caller":"traceutil/trace.go:171","msg":"trace[1399838401] transaction","detail":"{read_only:false; response_revision:310; number_of_response:1; }","duration":"225.868845ms","start":"2026-02-02T17:42:09.556023Z","end":"2026-02-02T17:42:09.781892Z","steps":["trace[1399838401] 'process raft request' (duration: 220.651826ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T17:42:09.782058Z","caller":"traceutil/trace.go:171","msg":"trace[1438424463] transaction","detail":"{read_only:false; response_revision:311; number_of_response:1; }","duration":"225.987395ms","start":"2026-02-02T17:42:09.556063Z","end":"2026-02-02T17:42:09.78205Z","steps":["trace[1438424463] 'process raft request' (duration: 220.647455ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:09.782177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.524234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-02-02T17:42:09.782242Z","caller":"traceutil/trace.go:171","msg":"trace[1930137042] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:311; }","duration":"228.593024ms","start":"2026-02-02T17:42:09.553641Z","end":"2026-02-02T17:42:09.782234Z","steps":["trace[1930137042] 'agreement among raft nodes before linearized reading' (duration: 228.508623ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:09.782399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.757145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-02-02T17:42:09.782468Z","caller":"traceutil/trace.go:171","msg":"trace[263174784] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:311; }","duration":"228.828796ms","start":"2026-02-02T17:42:09.55363Z","end":"2026-02-02T17:42:09.782459Z","steps":["trace[263174784] 'agreement among raft nodes before linearized reading' (duration: 228.743805ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:09.782986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.968236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3841"} {"level":"info","ts":"2026-02-02T17:42:09.783059Z","caller":"traceutil/trace.go:171","msg":"trace[1903048454] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:311; }","duration":"233.053307ms","start":"2026-02-02T17:42:09.549997Z","end":"2026-02-02T17:42:09.78305Z","steps":["trace[1903048454] 'agreement among raft nodes before linearized reading' (duration: 226.833461ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:09.783374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.935524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3841"} {"level":"info","ts":"2026-02-02T17:42:09.783452Z","caller":"traceutil/trace.go:171","msg":"trace[1750569797] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:311; }","duration":"230.021834ms","start":"2026-02-02T17:42:09.553422Z","end":"2026-02-02T17:42:09.783444Z","steps":["trace[1750569797] 'agreement among raft nodes before linearized reading' (duration: 229.926234ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:09.783756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.289886ms","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-02-02T17:42:09.785762Z","caller":"traceutil/trace.go:171","msg":"trace[857203896] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:311; }","duration":"232.297831ms","start":"2026-02-02T17:42:09.553449Z","end":"2026-02-02T17:42:09.785747Z","steps":["trace[857203896] 'agreement among raft nodes before linearized reading' (duration: 228.405103ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T17:42:09.778103Z","caller":"traceutil/trace.go:171","msg":"trace[1439486532] transaction","detail":"{read_only:false; response_revision:308; number_of_response:1; }","duration":"228.12273ms","start":"2026-02-02T17:42:09.549947Z","end":"2026-02-02T17:42:09.778069Z","steps":["trace[1439486532] 'process raft request' (duration: 200.077675ms)","trace[1439486532] 'compare' (duration: 26.498744ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T17:42:10.194711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.547767ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-02T17:42:10.194957Z","caller":"traceutil/trace.go:171","msg":"trace[664685210] transaction","detail":"{read_only:false; response_revision:313; number_of_response:1; }","duration":"609.935076ms","start":"2026-02-02T17:42:09.585006Z","end":"2026-02-02T17:42:10.194941Z","steps":["trace[664685210] 'process raft request' (duration: 609.800715ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:10.195026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:09.584974Z","time spent":"610.024506ms","remote":"127.0.0.1:51274","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-02-02T17:42:10.195254Z","caller":"traceutil/trace.go:171","msg":"trace[2077884022] transaction","detail":"{read_only:false; response_revision:312; number_of_response:1; }","duration":"617.945804ms","start":"2026-02-02T17:42:09.577294Z","end":"2026-02-02T17:42:10.19524Z","steps":["trace[2077884022] 'process raft request' (duration: 412.681322ms)","trace[2077884022] 'compare' (duration: 204.420596ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T17:42:10.195315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:09.577276Z","time spent":"618.008914ms","remote":"127.0.0.1:50796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-02T17:42:10.220668Z","caller":"traceutil/trace.go:171","msg":"trace[334107097] transaction","detail":"{read_only:false; response_revision:314; number_of_response:1; }","duration":"417.179633ms","start":"2026-02-02T17:42:09.803469Z","end":"2026-02-02T17:42:10.220648Z","steps":["trace[334107097] 'process raft request' (duration: 416.77795ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T17:42:10.220991Z","caller":"traceutil/trace.go:171","msg":"trace[649010781] transaction","detail":"{read_only:false; response_revision:315; number_of_response:1; }","duration":"417.280654ms","start":"2026-02-02T17:42:09.80365Z","end":"2026-02-02T17:42:10.22093Z","steps":["trace[649010781] 'process raft request' (duration: 416.76579ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:10.221511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:09.803643Z","time spent":"417.750458ms","remote":"127.0.0.1:50944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3999,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-02T17:42:10.221967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:09.803407Z","time spent":"418.519443ms","remote":"127.0.0.1:50934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":596,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-02T17:42:10.49117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.660719ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-02T17:42:10.491683Z","caller":"traceutil/trace.go:171","msg":"trace[2026890999] transaction","detail":"{read_only:false; response_revision:320; number_of_response:1; }","duration":"672.000085ms","start":"2026-02-02T17:42:09.819664Z","end":"2026-02-02T17:42:10.491664Z","steps":["trace[2026890999] 'process raft request' (duration: 671.818254ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T17:42:10.492008Z","caller":"traceutil/trace.go:171","msg":"trace[417411823] transaction","detail":"{read_only:false; response_revision:321; number_of_response:1; }","duration":"672.299987ms","start":"2026-02-02T17:42:09.819691Z","end":"2026-02-02T17:42:10.491991Z","steps":["trace[417411823] 'process raft request' (duration: 671.826053ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T17:42:10.492627Z","caller":"traceutil/trace.go:171","msg":"trace[2125106131] transaction","detail":"{read_only:false; response_revision:319; number_of_response:1; }","duration":"673.044342ms","start":"2026-02-02T17:42:09.819576Z","end":"2026-02-02T17:42:10.49262Z","steps":["trace[2125106131] 'process raft request' (duration: 671.872944ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:10.492784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:09.819569Z","time spent":"673.088372ms","remote":"127.0.0.1:51124","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2094,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-02T17:42:10.493024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:09.819688Z","time spent":"672.938061ms","remote":"127.0.0.1:51124","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2191,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-02T17:42:10.493036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:09.81966Z","time spent":"672.083476ms","remote":"127.0.0.1:51124","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":899,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-02T17:42:10.492564Z","caller":"traceutil/trace.go:171","msg":"trace[101642600] transaction","detail":"{read_only:false; response_revision:317; number_of_response:1; }","duration":"676.181506ms","start":"2026-02-02T17:42:09.816374Z","end":"2026-02-02T17:42:10.492555Z","steps":["trace[101642600] 'process raft request' (duration: 674.995117ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T17:42:10.492596Z","caller":"traceutil/trace.go:171","msg":"trace[625477058] transaction","detail":"{read_only:false; response_revision:318; number_of_response:1; }","duration":"676.173175ms","start":"2026-02-02T17:42:09.816415Z","end":"2026-02-02T17:42:10.492588Z","steps":["trace[625477058] 'process raft request' (duration: 674.992407ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:10.497505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:09.816412Z","time spent":"681.04891ms","remote":"127.0.0.1:51028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1251,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-02T17:42:10.492477Z","caller":"traceutil/trace.go:171","msg":"trace[942877729] transaction","detail":"{read_only:false; response_revision:316; number_of_response:1; }","duration":"676.137485ms","start":"2026-02-02T17:42:09.816325Z","end":"2026-02-02T17:42:10.492462Z","steps":["trace[942877729] 'process raft request' (duration: 674.937357ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:10.497998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:09.816316Z","time spent":"681.643325ms","remote":"127.0.0.1:51050","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1050,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-02T17:42:10.498589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:09.816369Z","time spent":"681.040991ms","remote":"127.0.0.1:50966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":156,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-02T17:42:10.829386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.361653ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-02T17:42:10.829568Z","caller":"traceutil/trace.go:171","msg":"trace[1667282490] transaction","detail":"{read_only:false; response_revision:322; number_of_response:1; }","duration":"1.00973028s","start":"2026-02-02T17:42:09.819827Z","end":"2026-02-02T17:42:10.829557Z","steps":["trace[1667282490] 'process raft request' (duration: 672.153836ms)","trace[1667282490] 'compare' (duration: 337.16841ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T17:42:10.829605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:09.819822Z","time spent":"1.00977112s","remote":"127.0.0.1:50850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":649,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-02T17:42:10.829846Z","caller":"traceutil/trace.go:171","msg":"trace[1115276962] transaction","detail":"{read_only:false; response_revision:327; number_of_response:1; }","duration":"328.035864ms","start":"2026-02-02T17:42:10.501805Z","end":"2026-02-02T17:42:10.829841Z","steps":["trace[1115276962] 'process raft request' (duration: 328.014104ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:10.829877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:10.501791Z","time spent":"328.073365ms","remote":"127.0.0.1:50966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":172,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-02T17:42:10.829894Z","caller":"traceutil/trace.go:171","msg":"trace[505621686] transaction","detail":"{read_only:false; response_revision:324; number_of_response:1; }","duration":"1.004021409s","start":"2026-02-02T17:42:09.825824Z","end":"2026-02-02T17:42:10.829845Z","steps":["trace[505621686] 'process raft request' (duration: 1.003919258s)"],"step_count":1} {"level":"info","ts":"2026-02-02T17:42:10.829942Z","caller":"traceutil/trace.go:171","msg":"trace[2003724438] linearizableReadLoop","detail":"{readStateIndex:326; appliedIndex:322; }","duration":"1.025093403s","start":"2026-02-02T17:42:09.804842Z","end":"2026-02-02T17:42:10.829936Z","steps":["trace[2003724438] 'read index received' (duration: 185.144016ms)","trace[2003724438] 'applied index is now lower than readState.Index' (duration: 839.948827ms)"],"step_count":2} {"level":"info","ts":"2026-02-02T17:42:10.829982Z","caller":"traceutil/trace.go:171","msg":"trace[1837763943] transaction","detail":"{read_only:false; response_revision:325; number_of_response:1; }","duration":"628.230817ms","start":"2026-02-02T17:42:10.201748Z","end":"2026-02-02T17:42:10.829978Z","steps":["trace[1837763943] 'process raft request' (duration: 628.022406ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:10.830001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:10.201733Z","time spent":"628.257877ms","remote":"127.0.0.1:50850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":680,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-02T17:42:10.83Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:09.825818Z","time spent":"1.00412964s","remote":"127.0.0.1:51278","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2125,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-02T17:42:10.830145Z","caller":"traceutil/trace.go:171","msg":"trace[1043537620] transaction","detail":"{read_only:false; response_revision:326; number_of_response:1; }","duration":"626.988948ms","start":"2026-02-02T17:42:10.203151Z","end":"2026-02-02T17:42:10.83014Z","steps":["trace[1043537620] 'process raft request' (duration: 626.637776ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:10.830179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:10.203144Z","time spent":"627.013858ms","remote":"127.0.0.1:51244","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3669,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-02T17:42:10.830232Z","caller":"traceutil/trace.go:171","msg":"trace[1206500288] transaction","detail":"{read_only:false; response_revision:323; number_of_response:1; }","duration":"1.010287475s","start":"2026-02-02T17:42:09.819932Z","end":"2026-02-02T17:42:10.830219Z","steps":["trace[1206500288] 'process raft request' (duration: 1.009513179s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:10.830282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.025437005s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:5270"} {"level":"warn","ts":"2026-02-02T17:42:10.830279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:09.819928Z","time spent":"1.010329315s","remote":"127.0.0.1:50882","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1723,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-02T17:42:10.830297Z","caller":"traceutil/trace.go:171","msg":"trace[1823719681] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:327; }","duration":"1.025451945s","start":"2026-02-02T17:42:09.80484Z","end":"2026-02-02T17:42:10.830292Z","steps":["trace[1823719681] 'agreement among raft nodes before linearized reading' (duration: 1.025420915s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:10.830311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:09.804834Z","time spent":"1.025474265s","remote":"127.0.0.1:50948","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":5293,"request content":"key:\"/registry/pods/kube-system/etcd-instance\" "} {"level":"warn","ts":"2026-02-02T17:42:10.830385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"630.829886ms","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-02-02T17:42:10.830398Z","caller":"traceutil/trace.go:171","msg":"trace[1189772874] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:327; }","duration":"630.879197ms","start":"2026-02-02T17:42:10.199515Z","end":"2026-02-02T17:42:10.830394Z","steps":["trace[1189772874] 'agreement among raft nodes before linearized reading' (duration: 630.856587ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:10.830411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:10.199495Z","time spent":"630.911127ms","remote":"127.0.0.1:50966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":230,"request content":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" "} {"level":"warn","ts":"2026-02-02T17:42:10.830547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.42781ms","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-02-02T17:42:10.830562Z","caller":"traceutil/trace.go:171","msg":"trace[1521901681] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:327; }","duration":"283.46686ms","start":"2026-02-02T17:42:10.547091Z","end":"2026-02-02T17:42:10.830557Z","steps":["trace[1521901681] 'agreement among raft nodes before linearized reading' (duration: 283.4402ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:10.830626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.343128ms","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-02-02T17:42:10.830638Z","caller":"traceutil/trace.go:171","msg":"trace[328323379] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:327; }","duration":"416.358098ms","start":"2026-02-02T17:42:10.414276Z","end":"2026-02-02T17:42:10.830634Z","steps":["trace[328323379] 'agreement among raft nodes before linearized reading' (duration: 416.337887ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:10.830649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:10.414229Z","time spent":"416.417308ms","remote":"127.0.0.1:51044","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-02-02T17:42:10.830699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"630.665675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-02-02T17:42:10.830736Z","caller":"traceutil/trace.go:171","msg":"trace[1256455352] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:327; }","duration":"631.068528ms","start":"2026-02-02T17:42:10.199656Z","end":"2026-02-02T17:42:10.830725Z","steps":["trace[1256455352] 'agreement among raft nodes before linearized reading' (duration: 630.996978ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:10.830764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:10.199651Z","time spent":"631.106978ms","remote":"127.0.0.1:50934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":445,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"warn","ts":"2026-02-02T17:42:11.459994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.067374ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-02T17:42:11.460429Z","caller":"traceutil/trace.go:171","msg":"trace[371180052] linearizableReadLoop","detail":"{readStateIndex:348; appliedIndex:340; }","duration":"585.960457ms","start":"2026-02-02T17:42:10.874453Z","end":"2026-02-02T17:42:11.460413Z","steps":["trace[371180052] 'read index received' (duration: 273.355139ms)","trace[371180052] 'applied index is now lower than readState.Index' (duration: 312.604428ms)"],"step_count":2} {"level":"info","ts":"2026-02-02T17:42:11.460687Z","caller":"traceutil/trace.go:171","msg":"trace[251808332] transaction","detail":"{read_only:false; response_revision:329; number_of_response:1; }","duration":"611.290611ms","start":"2026-02-02T17:42:10.849384Z","end":"2026-02-02T17:42:11.460674Z","steps":["trace[251808332] 'process raft request' (duration: 298.465592ms)","trace[251808332] 'compare' (duration: 311.965214ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T17:42:11.460753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:10.849379Z","time spent":"611.340682ms","remote":"127.0.0.1:51044","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-02-02T17:42:11.460896Z","caller":"traceutil/trace.go:171","msg":"trace[346278490] transaction","detail":"{read_only:false; response_revision:330; number_of_response:1; }","duration":"611.461712ms","start":"2026-02-02T17:42:10.849426Z","end":"2026-02-02T17:42:11.460888Z","steps":["trace[346278490] 'process raft request' (duration: 610.653026ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:11.460949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:10.849423Z","time spent":"611.501653ms","remote":"127.0.0.1:51044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-02T17:42:11.461126Z","caller":"traceutil/trace.go:171","msg":"trace[189780992] transaction","detail":"{read_only:false; response_revision:331; number_of_response:1; }","duration":"607.886676ms","start":"2026-02-02T17:42:10.853229Z","end":"2026-02-02T17:42:11.461116Z","steps":["trace[189780992] 'process raft request' (duration: 606.92581ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:11.46118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:10.853222Z","time spent":"607.932617ms","remote":"127.0.0.1:50948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5083,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-02T17:42:11.461382Z","caller":"traceutil/trace.go:171","msg":"trace[1621940970] transaction","detail":"{read_only:false; response_revision:332; number_of_response:1; }","duration":"606.334215ms","start":"2026-02-02T17:42:10.855039Z","end":"2026-02-02T17:42:11.461374Z","steps":["trace[1621940970] 'process raft request' (duration: 605.153497ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:11.461438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:10.855034Z","time spent":"606.377885ms","remote":"127.0.0.1:51124","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3461,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-02T17:42:11.46159Z","caller":"traceutil/trace.go:171","msg":"trace[852656970] transaction","detail":"{read_only:false; response_revision:333; number_of_response:1; }","duration":"606.459646ms","start":"2026-02-02T17:42:10.855122Z","end":"2026-02-02T17:42:11.461581Z","steps":["trace[852656970] 'process raft request' (duration: 605.107736ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:11.461635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:10.855118Z","time spent":"606.498626ms","remote":"127.0.0.1:50882","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1739,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-02T17:42:11.463217Z","caller":"traceutil/trace.go:171","msg":"trace[1730211915] transaction","detail":"{read_only:false; response_revision:334; number_of_response:1; }","duration":"605.902342ms","start":"2026-02-02T17:42:10.857301Z","end":"2026-02-02T17:42:11.463203Z","steps":["trace[1730211915] 'process raft request' (duration: 602.962951ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:11.463357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:10.857295Z","time spent":"605.961652ms","remote":"127.0.0.1:51124","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2264,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-02T17:42:11.463643Z","caller":"traceutil/trace.go:171","msg":"trace[388826992] transaction","detail":"{read_only:false; response_revision:335; number_of_response:1; }","duration":"603.467644ms","start":"2026-02-02T17:42:10.860163Z","end":"2026-02-02T17:42:11.463631Z","steps":["trace[388826992] 'process raft request' (duration: 600.12868ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:11.463704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:10.860157Z","time spent":"603.521584ms","remote":"127.0.0.1:50948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3355,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-02T17:42:11.464109Z","caller":"traceutil/trace.go:171","msg":"trace[1203056379] transaction","detail":"{read_only:false; response_revision:336; number_of_response:1; }","duration":"602.319476ms","start":"2026-02-02T17:42:10.861771Z","end":"2026-02-02T17:42:11.464091Z","steps":["trace[1203056379] 'process raft request' (duration: 598.542659ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:11.464182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:10.861764Z","time spent":"602.382876ms","remote":"127.0.0.1:50966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":164,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-02T17:42:11.464312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"602.457257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:3684"} {"level":"info","ts":"2026-02-02T17:42:11.464329Z","caller":"traceutil/trace.go:171","msg":"trace[306094458] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:336; }","duration":"602.485228ms","start":"2026-02-02T17:42:10.861839Z","end":"2026-02-02T17:42:11.464324Z","steps":["trace[306094458] 'agreement among raft nodes before linearized reading' (duration: 602.433407ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:11.464382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:10.861835Z","time spent":"602.543158ms","remote":"127.0.0.1:51244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":3707,"request content":"key:\"/registry/deployments/kube-system/coredns\" "} {"level":"warn","ts":"2026-02-02T17:42:11.46446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"609.442077ms","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-02-02T17:42:11.464472Z","caller":"traceutil/trace.go:171","msg":"trace[1997777037] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:336; }","duration":"609.474348ms","start":"2026-02-02T17:42:10.854994Z","end":"2026-02-02T17:42:11.464468Z","steps":["trace[1997777037] 'agreement among raft nodes before linearized reading' (duration: 609.448667ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:11.464484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:10.854988Z","time spent":"609.493378ms","remote":"127.0.0.1:50966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":230,"request content":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" "} {"level":"warn","ts":"2026-02-02T17:42:11.464558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"582.498592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-02-02T17:42:11.464571Z","caller":"traceutil/trace.go:171","msg":"trace[2061618037] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:336; }","duration":"582.539732ms","start":"2026-02-02T17:42:10.882026Z","end":"2026-02-02T17:42:11.464566Z","steps":["trace[2061618037] 'agreement among raft nodes before linearized reading' (duration: 582.517912ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:11.464583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:10.882013Z","time spent":"582.567562ms","remote":"127.0.0.1:51050","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/endpointslices/default/kubernetes\" "} {"level":"warn","ts":"2026-02-02T17:42:11.843726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.104011ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-02T17:42:11.843847Z","caller":"traceutil/trace.go:171","msg":"trace[443553425] linearizableReadLoop","detail":"{readStateIndex:349; appliedIndex:348; }","duration":"364.951566ms","start":"2026-02-02T17:42:11.478878Z","end":"2026-02-02T17:42:11.843829Z","steps":["trace[443553425] 'read index received' (duration: 220.841814ms)","trace[443553425] 'applied index is now lower than readState.Index' (duration: 144.108442ms)"],"step_count":2} {"level":"info","ts":"2026-02-02T17:42:11.844025Z","caller":"traceutil/trace.go:171","msg":"trace[324035617] transaction","detail":"{read_only:false; response_revision:337; number_of_response:1; }","duration":"366.035144ms","start":"2026-02-02T17:42:11.477978Z","end":"2026-02-02T17:42:11.844014Z","steps":["trace[324035617] 'process raft request' (duration: 221.57263ms)","trace[324035617] 'compare' (duration: 144.001851ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T17:42:11.844085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:11.477964Z","time spent":"366.089444ms","remote":"127.0.0.1:51244","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3980,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-02T17:42:11.845328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.62132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6898"} {"level":"info","ts":"2026-02-02T17:42:11.84552Z","caller":"traceutil/trace.go:171","msg":"trace[1175640203] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:337; }","duration":"366.519527ms","start":"2026-02-02T17:42:11.47885Z","end":"2026-02-02T17:42:11.845369Z","steps":["trace[1175640203] 'agreement among raft nodes before linearized reading' (duration: 365.46576ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:11.845584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:11.478839Z","time spent":"366.734239ms","remote":"127.0.0.1:50948","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":6921,"request content":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" "} {"level":"info","ts":"2026-02-02T17:42:11.880231Z","caller":"traceutil/trace.go:171","msg":"trace[1080919529] transaction","detail":"{read_only:false; response_revision:339; number_of_response:1; }","duration":"397.077808ms","start":"2026-02-02T17:42:11.483068Z","end":"2026-02-02T17:42:11.880146Z","steps":["trace[1080919529] 'process raft request' (duration: 396.717405ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:11.880508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:11.483059Z","time spent":"397.35427ms","remote":"127.0.0.1:50966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":164,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-02T17:42:11.88398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.584594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/admin\" ","response":"range_response_count:1 size:2278"} {"level":"info","ts":"2026-02-02T17:42:11.884127Z","caller":"traceutil/trace.go:171","msg":"trace[719654996] range","detail":"{range_begin:/registry/clusterroles/admin; range_end:; response_count:1; response_revision:343; }","duration":"396.721075ms","start":"2026-02-02T17:42:11.487366Z","end":"2026-02-02T17:42:11.884087Z","steps":["trace[719654996] 'agreement among raft nodes before linearized reading' (duration: 396.257292ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:11.88434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:11.487361Z","time spent":"396.891816ms","remote":"127.0.0.1:51124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":30,"response count":1,"response size":2301,"request content":"key:\"/registry/clusterroles/admin\" "} {"level":"info","ts":"2026-02-02T17:42:11.884847Z","caller":"traceutil/trace.go:171","msg":"trace[1937896458] transaction","detail":"{read_only:false; response_revision:338; number_of_response:1; }","duration":"405.882991ms","start":"2026-02-02T17:42:11.478952Z","end":"2026-02-02T17:42:11.884835Z","steps":["trace[1937896458] 'process raft request' (duration: 400.733335ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:11.885071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:11.478943Z","time spent":"406.082433ms","remote":"127.0.0.1:50948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3401,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-02T17:42:11.887833Z","caller":"traceutil/trace.go:171","msg":"trace[1846930376] transaction","detail":"{read_only:false; response_revision:342; number_of_response:1; }","duration":"397.986004ms","start":"2026-02-02T17:42:11.489834Z","end":"2026-02-02T17:42:11.88782Z","steps":["trace[1846930376] 'process raft request' (duration: 390.053907ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:11.887935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:11.489827Z","time spent":"398.080515ms","remote":"127.0.0.1:50948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3505,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-02T17:42:11.888487Z","caller":"traceutil/trace.go:171","msg":"trace[2070185022] transaction","detail":"{read_only:false; response_revision:340; number_of_response:1; }","duration":"404.052829ms","start":"2026-02-02T17:42:11.484108Z","end":"2026-02-02T17:42:11.888161Z","steps":["trace[2070185022] 'process raft request' (duration: 395.715749ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:11.888649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:11.484099Z","time spent":"404.526022ms","remote":"127.0.0.1:50850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":669,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-02T17:42:11.89174Z","caller":"traceutil/trace.go:171","msg":"trace[516142812] transaction","detail":"{read_only:false; response_revision:343; number_of_response:1; }","duration":"401.863652ms","start":"2026-02-02T17:42:11.489864Z","end":"2026-02-02T17:42:11.891728Z","steps":["trace[516142812] 'process raft request' (duration: 393.713574ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T17:42:11.888037Z","caller":"traceutil/trace.go:171","msg":"trace[816451536] transaction","detail":"{read_only:false; response_revision:341; number_of_response:1; }","duration":"400.577493ms","start":"2026-02-02T17:42:11.487449Z","end":"2026-02-02T17:42:11.888026Z","steps":["trace[816451536] 'process raft request' (duration: 392.408185ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:11.892265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.303756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/edit\" ","response":"range_response_count:1 size:3475"} {"level":"info","ts":"2026-02-02T17:42:11.892899Z","caller":"traceutil/trace.go:171","msg":"trace[806727555] range","detail":"{range_begin:/registry/clusterroles/edit; range_end:; response_count:1; response_revision:343; }","duration":"402.94557ms","start":"2026-02-02T17:42:11.489941Z","end":"2026-02-02T17:42:11.892887Z","steps":["trace[806727555] 'agreement among raft nodes before linearized reading' (duration: 402.284076ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:11.896479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:11.489938Z","time spent":"406.516406ms","remote":"127.0.0.1:51124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":3498,"request content":"key:\"/registry/clusterroles/edit\" "} {"level":"warn","ts":"2026-02-02T17:42:11.906189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:11.489861Z","time spent":"416.262896ms","remote":"127.0.0.1:51262","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2864,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-02T17:42:11.906345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:11.487444Z","time spent":"418.887615ms","remote":"127.0.0.1:50882","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1731,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-02T17:42:12.034041Z","caller":"traceutil/trace.go:171","msg":"trace[572158535] transaction","detail":"{read_only:false; response_revision:344; number_of_response:1; }","duration":"179.723877ms","start":"2026-02-02T17:42:11.854296Z","end":"2026-02-02T17:42:12.03402Z","steps":["trace[572158535] 'process raft request' (duration: 94.22255ms)","trace[572158535] 'compare' (duration: 84.910212ms)"],"step_count":2} {"level":"info","ts":"2026-02-02T17:42:12.035709Z","caller":"traceutil/trace.go:171","msg":"trace[167483086] transaction","detail":"{read_only:false; response_revision:345; number_of_response:1; }","duration":"128.824159ms","start":"2026-02-02T17:42:11.906872Z","end":"2026-02-02T17:42:12.035697Z","steps":["trace[167483086] 'process raft request' (duration: 127.111247ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T17:42:12.03589Z","caller":"traceutil/trace.go:171","msg":"trace[1449917440] transaction","detail":"{read_only:false; response_revision:346; number_of_response:1; }","duration":"128.328665ms","start":"2026-02-02T17:42:11.907556Z","end":"2026-02-02T17:42:12.035885Z","steps":["trace[1449917440] 'process raft request' (duration: 128.096204ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T17:42:12.03606Z","caller":"traceutil/trace.go:171","msg":"trace[156247173] transaction","detail":"{read_only:false; response_revision:347; number_of_response:1; }","duration":"128.454886ms","start":"2026-02-02T17:42:11.9076Z","end":"2026-02-02T17:42:12.036055Z","steps":["trace[156247173] 'process raft request' (duration: 128.081354ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T17:42:12.036339Z","caller":"traceutil/trace.go:171","msg":"trace[370518871] transaction","detail":"{read_only:false; response_revision:348; number_of_response:1; }","duration":"121.846387ms","start":"2026-02-02T17:42:11.914487Z","end":"2026-02-02T17:42:12.036334Z","steps":["trace[370518871] 'process raft request' (duration: 121.368364ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T17:42:12.036439Z","caller":"traceutil/trace.go:171","msg":"trace[687736275] transaction","detail":"{read_only:false; response_revision:349; number_of_response:1; }","duration":"118.639475ms","start":"2026-02-02T17:42:11.917795Z","end":"2026-02-02T17:42:12.036435Z","steps":["trace[687736275] 'process raft request' (duration: 118.436004ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T17:42:12.036583Z","caller":"traceutil/trace.go:171","msg":"trace[454399242] transaction","detail":"{read_only:false; response_revision:350; number_of_response:1; }","duration":"118.739126ms","start":"2026-02-02T17:42:11.91784Z","end":"2026-02-02T17:42:12.036579Z","steps":["trace[454399242] 'process raft request' (duration: 118.418053ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T17:42:12.036838Z","caller":"traceutil/trace.go:171","msg":"trace[168857880] transaction","detail":"{read_only:false; response_revision:351; number_of_response:1; }","duration":"118.564135ms","start":"2026-02-02T17:42:11.918269Z","end":"2026-02-02T17:42:12.036833Z","steps":["trace[168857880] 'process raft request' (duration: 118.260412ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T17:42:12.036936Z","caller":"traceutil/trace.go:171","msg":"trace[1225239576] transaction","detail":"{read_only:false; response_revision:352; number_of_response:1; }","duration":"117.535727ms","start":"2026-02-02T17:42:11.919396Z","end":"2026-02-02T17:42:12.036932Z","steps":["trace[1225239576] 'process raft request' (duration: 117.156265ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T17:42:12.239206Z","caller":"traceutil/trace.go:171","msg":"trace[1771259951] transaction","detail":"{read_only:false; response_revision:359; number_of_response:1; }","duration":"103.078109ms","start":"2026-02-02T17:42:12.136103Z","end":"2026-02-02T17:42:12.239181Z","steps":["trace[1771259951] 'process raft request' (duration: 41.513358ms)","trace[1771259951] 'compare' (duration: 61.41795ms)"],"step_count":2} {"level":"info","ts":"2026-02-02T17:42:12.272011Z","caller":"traceutil/trace.go:171","msg":"trace[1055674924] transaction","detail":"{read_only:false; response_revision:360; number_of_response:1; }","duration":"134.351744ms","start":"2026-02-02T17:42:12.137633Z","end":"2026-02-02T17:42:12.271984Z","steps":["trace[1055674924] 'process raft request' (duration: 134.197042ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T17:42:12.42293Z","caller":"traceutil/trace.go:171","msg":"trace[496060451] transaction","detail":"{read_only:false; response_revision:361; number_of_response:1; }","duration":"171.418669ms","start":"2026-02-02T17:42:12.251464Z","end":"2026-02-02T17:42:12.422883Z","steps":["trace[496060451] 'process raft request' (duration: 94.553898ms)","trace[496060451] 'compare' (duration: 76.76673ms)"],"step_count":2} {"level":"info","ts":"2026-02-02T17:42:13.528758Z","caller":"traceutil/trace.go:171","msg":"trace[910151189] transaction","detail":"{read_only:false; response_revision:363; number_of_response:1; }","duration":"243.843178ms","start":"2026-02-02T17:42:13.284892Z","end":"2026-02-02T17:42:13.528735Z","steps":["trace[910151189] 'process raft request' (duration: 243.288464ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T17:42:13.590675Z","caller":"traceutil/trace.go:171","msg":"trace[1904112027] linearizableReadLoop","detail":"{readStateIndex:376; appliedIndex:374; }","duration":"154.815613ms","start":"2026-02-02T17:42:13.435837Z","end":"2026-02-02T17:42:13.590652Z","steps":["trace[1904112027] 'read index received' (duration: 92.460799ms)","trace[1904112027] 'applied index is now lower than readState.Index' (duration: 62.353874ms)"],"step_count":2} {"level":"info","ts":"2026-02-02T17:42:13.590792Z","caller":"traceutil/trace.go:171","msg":"trace[767898459] transaction","detail":"{read_only:false; response_revision:364; number_of_response:1; }","duration":"267.512196ms","start":"2026-02-02T17:42:13.32327Z","end":"2026-02-02T17:42:13.590782Z","steps":["trace[767898459] 'process raft request' (duration: 267.271105ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:13.591424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.85271ms","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-02-02T17:42:13.591478Z","caller":"traceutil/trace.go:171","msg":"trace[882579383] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:364; }","duration":"121.9451ms","start":"2026-02-02T17:42:13.46952Z","end":"2026-02-02T17:42:13.591465Z","steps":["trace[882579383] 'agreement among raft nodes before linearized reading' (duration: 121.825609ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:13.591636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.793511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4013"} {"level":"info","ts":"2026-02-02T17:42:13.591704Z","caller":"traceutil/trace.go:171","msg":"trace[420046198] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:364; }","duration":"155.887072ms","start":"2026-02-02T17:42:13.435806Z","end":"2026-02-02T17:42:13.591693Z","steps":["trace[420046198] 'agreement among raft nodes before linearized reading' (duration: 155.348437ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:13.591954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.895055ms","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-02-02T17:42:13.591994Z","caller":"traceutil/trace.go:171","msg":"trace[1121479484] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:364; }","duration":"105.978596ms","start":"2026-02-02T17:42:13.486007Z","end":"2026-02-02T17:42:13.591985Z","steps":["trace[1121479484] 'agreement among raft nodes before linearized reading' (duration: 105.897605ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T17:42:13.768386Z","caller":"traceutil/trace.go:171","msg":"trace[206700579] transaction","detail":"{read_only:false; response_revision:365; number_of_response:1; }","duration":"171.20691ms","start":"2026-02-02T17:42:13.597164Z","end":"2026-02-02T17:42:13.768371Z","steps":["trace[206700579] 'process raft request' (duration: 79.384895ms)","trace[206700579] 'compare' (duration: 91.479352ms)"],"step_count":2} {"level":"info","ts":"2026-02-02T17:42:13.768472Z","caller":"traceutil/trace.go:171","msg":"trace[2097537991] transaction","detail":"{read_only:false; response_revision:366; number_of_response:1; }","duration":"169.316897ms","start":"2026-02-02T17:42:13.599151Z","end":"2026-02-02T17:42:13.768468Z","steps":["trace[2097537991] 'process raft request' (duration: 168.966675ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:14.28158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.354929ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-02T17:42:14.281963Z","caller":"traceutil/trace.go:171","msg":"trace[1140076044] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"221.446588ms","start":"2026-02-02T17:42:14.060499Z","end":"2026-02-02T17:42:14.281945Z","steps":["trace[1140076044] 'process raft request' (duration: 37.666506ms)","trace[1140076044] 'compare' (duration: 182.698255ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T17:42:14.660811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.78151ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-02T17:42:14.661128Z","caller":"traceutil/trace.go:171","msg":"trace[486103923] transaction","detail":"{read_only:false; response_revision:371; number_of_response:1; }","duration":"345.128244ms","start":"2026-02-02T17:42:14.315989Z","end":"2026-02-02T17:42:14.661117Z","steps":["trace[486103923] 'process raft request' (duration: 228.986592ms)","trace[486103923] 'compare' (duration: 115.705839ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T17:42:14.661287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:14.31597Z","time spent":"345.295155ms","remote":"127.0.0.1:50948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4400,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-02T17:42:14.938383Z","caller":"traceutil/trace.go:171","msg":"trace[1207582569] transaction","detail":"{read_only:false; response_revision:372; number_of_response:1; }","duration":"263.609738ms","start":"2026-02-02T17:42:14.673945Z","end":"2026-02-02T17:42:14.937555Z","steps":["trace[1207582569] 'process raft request' (duration: 263.246355ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:15.431264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.174057ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-02T17:42:15.431386Z","caller":"traceutil/trace.go:171","msg":"trace[1929434303] linearizableReadLoop","detail":"{readStateIndex:387; appliedIndex:385; }","duration":"715.116966ms","start":"2026-02-02T17:42:14.716257Z","end":"2026-02-02T17:42:15.431374Z","steps":["trace[1929434303] 'read index received' (duration: 221.038396ms)","trace[1929434303] 'applied index is now lower than readState.Index' (duration: 494.07767ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T17:42:15.431441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"715.189556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T17:42:15.431456Z","caller":"traceutil/trace.go:171","msg":"trace[449175709] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:373; }","duration":"715.225426ms","start":"2026-02-02T17:42:14.716225Z","end":"2026-02-02T17:42:15.431451Z","steps":["trace[449175709] 'agreement among raft nodes before linearized reading' (duration: 715.180066ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:15.431478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:14.716218Z","time spent":"715.255607ms","remote":"127.0.0.1:50766","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-02-02T17:42:15.431514Z","caller":"traceutil/trace.go:171","msg":"trace[404713348] transaction","detail":"{read_only:false; response_revision:373; number_of_response:1; }","duration":"728.26997ms","start":"2026-02-02T17:42:14.703208Z","end":"2026-02-02T17:42:15.431478Z","steps":["trace[404713348] 'process raft request' (duration: 459.826241ms)","trace[404713348] 'compare' (duration: 268.048106ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T17:42:15.431702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:14.70319Z","time spent":"728.39465ms","remote":"127.0.0.1:50796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-02T17:42:15.903718Z","caller":"traceutil/trace.go:171","msg":"trace[1187158929] transaction","detail":"{read_only:false; response_revision:375; number_of_response:1; }","duration":"126.056917ms","start":"2026-02-02T17:42:15.777638Z","end":"2026-02-02T17:42:15.903694Z","steps":["trace[1187158929] 'process raft request' (duration: 125.932767ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:17.218649Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12667390035785301345,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-02T17:42:17.719585Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12667390035785301345,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-02T17:42:18.201Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.896562354s","expected-duration":"1s"} {"level":"info","ts":"2026-02-02T17:42:18.201616Z","caller":"traceutil/trace.go:171","msg":"trace[114570795] linearizableReadLoop","detail":"{readStateIndex:391; appliedIndex:390; }","duration":"1.483859666s","start":"2026-02-02T17:42:16.717716Z","end":"2026-02-02T17:42:18.201576Z","steps":["trace[114570795] 'read index received' (duration: 1.483404943s)","trace[114570795] 'applied index is now lower than readState.Index' (duration: 453.703µs)"],"step_count":2} {"level":"info","ts":"2026-02-02T17:42:18.201727Z","caller":"traceutil/trace.go:171","msg":"trace[729509974] transaction","detail":"{read_only:false; response_revision:377; number_of_response:1; }","duration":"1.911991892s","start":"2026-02-02T17:42:16.289723Z","end":"2026-02-02T17:42:18.201715Z","steps":["trace[729509974] 'process raft request' (duration: 1.911462778s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:18.201879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:16.289666Z","time spent":"1.912103562s","remote":"127.0.0.1:51044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-02T17:42:18.2019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.921101ms","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-02-02T17:42:18.201978Z","caller":"traceutil/trace.go:171","msg":"trace[1352439780] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:377; }","duration":"335.025852ms","start":"2026-02-02T17:42:17.866913Z","end":"2026-02-02T17:42:18.201939Z","steps":["trace[1352439780] 'agreement among raft nodes before linearized reading' (duration: 334.926142ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:18.202019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:17.866889Z","time spent":"335.120243ms","remote":"127.0.0.1:51044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-02T17:42:18.202242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.168601ms","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-02-02T17:42:18.202281Z","caller":"traceutil/trace.go:171","msg":"trace[1492070398] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:377; }","duration":"293.240921ms","start":"2026-02-02T17:42:17.909028Z","end":"2026-02-02T17:42:18.202269Z","steps":["trace[1492070398] 'agreement among raft nodes before linearized reading' (duration: 293.172621ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:18.202281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.484558921s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T17:42:18.202326Z","caller":"traceutil/trace.go:171","msg":"trace[835060516] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:377; }","duration":"1.484652112s","start":"2026-02-02T17:42:16.717666Z","end":"2026-02-02T17:42:18.202318Z","steps":["trace[835060516] 'agreement among raft nodes before linearized reading' (duration: 1.48458547s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:18.202351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:16.717651Z","time spent":"1.484693952s","remote":"127.0.0.1:50772","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-02-02T17:42:18.715537Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12667390035785301348,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-02T17:42:18.785955Z","caller":"traceutil/trace.go:171","msg":"trace[1845269479] transaction","detail":"{read_only:false; response_revision:378; number_of_response:1; }","duration":"571.732005ms","start":"2026-02-02T17:42:18.214203Z","end":"2026-02-02T17:42:18.785935Z","steps":["trace[1845269479] 'process raft request' (duration: 571.619564ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:18.786066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:18.213937Z","time spent":"572.065537ms","remote":"127.0.0.1:51044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-02T17:42:18.811061Z","caller":"traceutil/trace.go:171","msg":"trace[57287632] linearizableReadLoop","detail":"{readStateIndex:394; appliedIndex:392; }","duration":"596.524426ms","start":"2026-02-02T17:42:18.214518Z","end":"2026-02-02T17:42:18.811042Z","steps":["trace[57287632] 'read index received' (duration: 571.908146ms)","trace[57287632] 'applied index is now lower than readState.Index' (duration: 24.61558ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T17:42:18.811163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"596.631597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T17:42:18.811185Z","caller":"traceutil/trace.go:171","msg":"trace[1218313893] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:380; }","duration":"596.698168ms","start":"2026-02-02T17:42:18.21448Z","end":"2026-02-02T17:42:18.811178Z","steps":["trace[1218313893] 'agreement among raft nodes before linearized reading' (duration: 596.624528ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:18.811211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:18.214468Z","time spent":"596.738238ms","remote":"127.0.0.1:50766","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-02-02T17:42:18.811461Z","caller":"traceutil/trace.go:171","msg":"trace[1198623408] transaction","detail":"{read_only:false; response_revision:379; number_of_response:1; }","duration":"596.390116ms","start":"2026-02-02T17:42:18.215065Z","end":"2026-02-02T17:42:18.811455Z","steps":["trace[1198623408] 'process raft request' (duration: 595.880623ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:18.811515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:18.215054Z","time spent":"596.426406ms","remote":"127.0.0.1:51044","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-02-02T17:42:18.81158Z","caller":"traceutil/trace.go:171","msg":"trace[1950962980] transaction","detail":"{read_only:false; response_revision:380; number_of_response:1; }","duration":"596.011693ms","start":"2026-02-02T17:42:18.215564Z","end":"2026-02-02T17:42:18.811576Z","steps":["trace[1950962980] 'process raft request' (duration: 595.458989ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:18.811607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:18.215554Z","time spent":"596.041263ms","remote":"127.0.0.1:51044","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-02-02T17:42:19.909859Z","caller":"traceutil/trace.go:171","msg":"trace[638087075] transaction","detail":"{read_only:false; response_revision:382; number_of_response:1; }","duration":"162.931611ms","start":"2026-02-02T17:42:19.746912Z","end":"2026-02-02T17:42:19.909844Z","steps":["trace[638087075] 'process raft request' (duration: 162.83437ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:20.533885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.853709ms","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-02-02T17:42:20.533966Z","caller":"traceutil/trace.go:171","msg":"trace[1425710326] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:382; }","duration":"322.986029ms","start":"2026-02-02T17:42:20.21096Z","end":"2026-02-02T17:42:20.533946Z","steps":["trace[1425710326] 'range keys from in-memory index tree' (duration: 322.701447ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:20.534015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:20.210913Z","time spent":"323.092591ms","remote":"127.0.0.1:51044","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":"warn","ts":"2026-02-02T17:42:27.339971Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12667390035785301437,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-02T17:42:27.840838Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12667390035785301437,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-02T17:42:28.341196Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12667390035785301437,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-02T17:42:28.842229Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12667390035785301437,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-02T17:42:29.343001Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12667390035785301437,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-02T17:42:29.714732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.00006037s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-02T17:42:29.714805Z","caller":"traceutil/trace.go:171","msg":"trace[1042848661] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000180211s","start":"2026-02-02T17:42:27.714609Z","end":"2026-02-02T17:42:29.714789Z","steps":["trace[1042848661] 'agreement among raft nodes before linearized reading' (duration: 2.00009428s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:29.714854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:27.71459Z","time spent":"2.000251371s","remote":"127.0.0.1:50766","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-02T17:42:29.843777Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12667390035785301437,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-02T17:42:30.344284Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12667390035785301437,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-02T17:42:30.845418Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12667390035785301437,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-02T17:42:31.345759Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12667390035785301437,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-02T17:42:31.715898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000568543s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-02T17:42:31.715983Z","caller":"traceutil/trace.go:171","msg":"trace[1428469448] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000696274s","start":"2026-02-02T17:42:29.715269Z","end":"2026-02-02T17:42:31.715965Z","steps":["trace[1428469448] 'agreement among raft nodes before linearized reading' (duration: 2.000586893s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:31.716078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:29.715256Z","time spent":"2.000764276s","remote":"127.0.0.1:50772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-02T17:42:31.839218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.99925299s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-02T17:42:31.839294Z","caller":"traceutil/trace.go:171","msg":"trace[751042564] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; }","duration":"4.99937366s","start":"2026-02-02T17:42:26.839902Z","end":"2026-02-02T17:42:31.839275Z","steps":["trace[751042564] 'agreement among raft nodes before linearized reading' (duration: 4.99928319s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:31.83934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:26.839885Z","time spent":"4.999444641s","remote":"127.0.0.1:51044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-02T17:42:31.846745Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12667390035785301437,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-02T17:42:31.857534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.999207926s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-02T17:42:31.857728Z","caller":"traceutil/trace.go:171","msg":"trace[308475722] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; }","duration":"4.999486358s","start":"2026-02-02T17:42:26.85819Z","end":"2026-02-02T17:42:31.857677Z","steps":["trace[308475722] 'agreement among raft nodes before linearized reading' (duration: 4.999321677s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:31.857819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:26.858178Z","time spent":"4.999628109s","remote":"127.0.0.1:51044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} 2026/02/02 17:42:31 WARNING: [core] [Server #5] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-02T17:42:32.346993Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12667390035785301437,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-02T17:42:32.848363Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12667390035785301437,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-02T17:42:33.025205Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"6.421679397s","expected-duration":"1s"} {"level":"info","ts":"2026-02-02T17:42:33.025543Z","caller":"traceutil/trace.go:171","msg":"trace[868093141] transaction","detail":"{read_only:false; response_revision:427; number_of_response:1; }","duration":"6.422058379s","start":"2026-02-02T17:42:26.603464Z","end":"2026-02-02T17:42:33.025522Z","steps":["trace[868093141] 'process raft request' (duration: 6.421908678s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:33.025656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:26.603455Z","time spent":"6.42214253s","remote":"127.0.0.1:51044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-02T17:42:33.131705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000473989s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-02T17:42:33.131781Z","caller":"traceutil/trace.go:171","msg":"trace[257093017] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.00059963s","start":"2026-02-02T17:42:31.131163Z","end":"2026-02-02T17:42:33.131763Z","steps":["trace[257093017] 'agreement among raft nodes before linearized reading' (duration: 2.000505599s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:33.131826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:31.131143Z","time spent":"2.00067393s","remote":"127.0.0.1:50766","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/02 17:42:33 WARNING: [core] [Server #5] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"info","ts":"2026-02-02T17:42:33.295694Z","caller":"traceutil/trace.go:171","msg":"trace[1111967185] transaction","detail":"{read_only:false; response_revision:428; number_of_response:1; }","duration":"3.599407412s","start":"2026-02-02T17:42:29.696268Z","end":"2026-02-02T17:42:33.295675Z","steps":["trace[1111967185] 'process raft request' (duration: 3.59910156s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:33.295818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:29.696245Z","time spent":"3.599516823s","remote":"127.0.0.1:51044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-02T17:42:33.361394Z","caller":"traceutil/trace.go:171","msg":"trace[1466761809] transaction","detail":"{read_only:false; response_revision:429; number_of_response:1; }","duration":"3.207717593s","start":"2026-02-02T17:42:30.153643Z","end":"2026-02-02T17:42:33.361361Z","steps":["trace[1466761809] 'process raft request' (duration: 3.206502135s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:33.361516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:30.153621Z","time spent":"3.207831554s","remote":"127.0.0.1:51044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-02T17:42:33.431125Z","caller":"traceutil/trace.go:171","msg":"trace[1640839283] linearizableReadLoop","detail":"{readStateIndex:448; appliedIndex:444; }","duration":"6.591165707s","start":"2026-02-02T17:42:26.83994Z","end":"2026-02-02T17:42:33.431106Z","steps":["trace[1640839283] 'read index received' (duration: 6.186053152s)","trace[1640839283] 'applied index is now lower than readState.Index' (duration: 405.111595ms)"],"step_count":2} {"level":"info","ts":"2026-02-02T17:42:33.43122Z","caller":"traceutil/trace.go:171","msg":"trace[684483618] transaction","detail":"{read_only:false; response_revision:430; number_of_response:1; }","duration":"1.711348115s","start":"2026-02-02T17:42:31.719862Z","end":"2026-02-02T17:42:33.431211Z","steps":["trace[684483618] 'process raft request' (duration: 1.641488214s)","trace[684483618] 'compare' (duration: 69.604269ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T17:42:33.431281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:31.719841Z","time spent":"1.711398135s","remote":"127.0.0.1:50850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-02T17:42:33.431455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"6.565949439s","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-02-02T17:42:33.431504Z","caller":"traceutil/trace.go:171","msg":"trace[1696539461] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:430; }","duration":"6.566026009s","start":"2026-02-02T17:42:26.86547Z","end":"2026-02-02T17:42:33.431496Z","steps":["trace[1696539461] 'agreement among raft nodes before linearized reading' (duration: 6.565929119s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:33.431527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:26.865463Z","time spent":"6.566056259s","remote":"127.0.0.1:51044","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-02-02T17:42:33.431648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.707037515s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T17:42:33.431667Z","caller":"traceutil/trace.go:171","msg":"trace[418509589] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:430; }","duration":"1.707074805s","start":"2026-02-02T17:42:31.724587Z","end":"2026-02-02T17:42:33.431661Z","steps":["trace[418509589] 'agreement among raft nodes before linearized reading' (duration: 1.707044265s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:33.431683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:31.724577Z","time spent":"1.707102065s","remote":"127.0.0.1:50772","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-02-02T17:42:34.015202Z","caller":"traceutil/trace.go:171","msg":"trace[460262366] transaction","detail":"{read_only:false; response_revision:435; number_of_response:1; }","duration":"142.789661ms","start":"2026-02-02T17:42:33.872395Z","end":"2026-02-02T17:42:34.015185Z","steps":["trace[460262366] 'process raft request' (duration: 142.487039ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T17:42:37.266162Z","caller":"traceutil/trace.go:171","msg":"trace[794387433] linearizableReadLoop","detail":"{readStateIndex:460; appliedIndex:459; }","duration":"171.998539ms","start":"2026-02-02T17:42:37.094139Z","end":"2026-02-02T17:42:37.266138Z","steps":["trace[794387433] 'read index received' (duration: 171.818262ms)","trace[794387433] 'applied index is now lower than readState.Index' (duration: 179.097µs)"],"step_count":2} {"level":"info","ts":"2026-02-02T17:42:37.266562Z","caller":"traceutil/trace.go:171","msg":"trace[1902514417] transaction","detail":"{read_only:false; response_revision:441; number_of_response:1; }","duration":"172.620436ms","start":"2026-02-02T17:42:37.093914Z","end":"2026-02-02T17:42:37.266534Z","steps":["trace[1902514417] 'process raft request' (duration: 172.090494ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:37.266608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.442079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:3 size:12669"} {"level":"info","ts":"2026-02-02T17:42:37.26694Z","caller":"traceutil/trace.go:171","msg":"trace[86018243] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:3; response_revision:441; }","duration":"172.800133ms","start":"2026-02-02T17:42:37.094123Z","end":"2026-02-02T17:42:37.266923Z","steps":["trace[86018243] 'agreement among raft nodes before linearized reading' (duration: 172.403356ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T17:42:38.383981Z","caller":"traceutil/trace.go:171","msg":"trace[1245420378] transaction","detail":"{read_only:false; response_revision:445; number_of_response:1; }","duration":"115.747857ms","start":"2026-02-02T17:42:38.268209Z","end":"2026-02-02T17:42:38.383957Z","steps":["trace[1245420378] 'process raft request' (duration: 115.475096ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T17:42:38.652173Z","caller":"traceutil/trace.go:171","msg":"trace[494159651] transaction","detail":"{read_only:false; response_revision:448; number_of_response:1; }","duration":"213.934702ms","start":"2026-02-02T17:42:38.438212Z","end":"2026-02-02T17:42:38.652147Z","steps":["trace[494159651] 'process raft request' (duration: 122.312098ms)","trace[494159651] 'compare' (duration: 91.450256ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T17:42:38.939837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.628061ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-02T17:42:38.939932Z","caller":"traceutil/trace.go:171","msg":"trace[1450459672] transaction","detail":"{read_only:false; response_revision:452; number_of_response:1; }","duration":"199.634691ms","start":"2026-02-02T17:42:38.740279Z","end":"2026-02-02T17:42:38.939914Z","steps":["trace[1450459672] 'process raft request' (duration: 41.856254ms)","trace[1450459672] 'compare' (duration: 157.527947ms)"],"step_count":2} {"level":"info","ts":"2026-02-02T17:42:39.15363Z","caller":"traceutil/trace.go:171","msg":"trace[781212249] transaction","detail":"{read_only:false; response_revision:455; number_of_response:1; }","duration":"151.736225ms","start":"2026-02-02T17:42:39.001863Z","end":"2026-02-02T17:42:39.153599Z","steps":["trace[781212249] 'process raft request' (duration: 118.419359ms)","trace[781212249] 'compare' (duration: 33.18549ms)"],"step_count":2} {"level":"info","ts":"2026-02-02T17:42:39.32939Z","caller":"traceutil/trace.go:171","msg":"trace[1511950868] transaction","detail":"{read_only:false; response_revision:457; number_of_response:1; }","duration":"164.333336ms","start":"2026-02-02T17:42:39.164777Z","end":"2026-02-02T17:42:39.32911Z","steps":["trace[1511950868] 'process raft request' (duration: 142.425625ms)","trace[1511950868] 'compare' (duration: 21.776036ms)"],"step_count":2} {"level":"info","ts":"2026-02-02T17:42:39.392691Z","caller":"traceutil/trace.go:171","msg":"trace[1099929385] linearizableReadLoop","detail":"{readStateIndex:477; appliedIndex:475; }","duration":"121.127429ms","start":"2026-02-02T17:42:39.271544Z","end":"2026-02-02T17:42:39.392671Z","steps":["trace[1099929385] 'read index received' (duration: 35.63177ms)","trace[1099929385] 'applied index is now lower than readState.Index' (duration: 85.494709ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T17:42:39.39284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.283045ms","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-02-02T17:42:39.392872Z","caller":"traceutil/trace.go:171","msg":"trace[1724096961] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:459; }","duration":"121.325817ms","start":"2026-02-02T17:42:39.271535Z","end":"2026-02-02T17:42:39.39286Z","steps":["trace[1724096961] 'agreement among raft nodes before linearized reading' (duration: 121.212192ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T17:42:39.393046Z","caller":"traceutil/trace.go:171","msg":"trace[68044343] transaction","detail":"{read_only:false; response_revision:458; number_of_response:1; }","duration":"162.94188ms","start":"2026-02-02T17:42:39.230094Z","end":"2026-02-02T17:42:39.393036Z","steps":["trace[68044343] 'process raft request' (duration: 161.260271ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T17:42:39.398148Z","caller":"traceutil/trace.go:171","msg":"trace[470742183] transaction","detail":"{read_only:false; response_revision:459; number_of_response:1; }","duration":"100.600114ms","start":"2026-02-02T17:42:39.29745Z","end":"2026-02-02T17:42:39.39805Z","steps":["trace[470742183] 'process raft request' (duration: 94.759947ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T17:42:39.555853Z","caller":"traceutil/trace.go:171","msg":"trace[738814965] transaction","detail":"{read_only:false; response_revision:462; number_of_response:1; }","duration":"141.32211ms","start":"2026-02-02T17:42:39.414505Z","end":"2026-02-02T17:42:39.555827Z","steps":["trace[738814965] 'process raft request' (duration: 140.115882ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T17:42:39.557043Z","caller":"traceutil/trace.go:171","msg":"trace[203426882] transaction","detail":"{read_only:false; response_revision:463; number_of_response:1; }","duration":"138.967784ms","start":"2026-02-02T17:42:39.417968Z","end":"2026-02-02T17:42:39.556936Z","steps":["trace[203426882] 'process raft request' (duration: 136.685582ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T17:42:39.55747Z","caller":"traceutil/trace.go:171","msg":"trace[289492090] transaction","detail":"{read_only:false; response_revision:460; number_of_response:1; }","duration":"218.697761ms","start":"2026-02-02T17:42:39.338326Z","end":"2026-02-02T17:42:39.557023Z","steps":["trace[289492090] 'process raft request' (duration: 132.42584ms)","trace[289492090] 'compare' (duration: 83.612252ms)"],"step_count":2} {"level":"info","ts":"2026-02-02T17:42:39.558507Z","caller":"traceutil/trace.go:171","msg":"trace[248647612] transaction","detail":"{read_only:false; response_revision:461; number_of_response:1; }","duration":"159.21179ms","start":"2026-02-02T17:42:39.399123Z","end":"2026-02-02T17:42:39.558335Z","steps":["trace[248647612] 'process raft request' (duration: 155.469307ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T17:42:39.559571Z","caller":"traceutil/trace.go:171","msg":"trace[299382548] transaction","detail":"{read_only:false; response_revision:464; number_of_response:1; }","duration":"140.279769ms","start":"2026-02-02T17:42:39.419273Z","end":"2026-02-02T17:42:39.559553Z","steps":["trace[299382548] 'process raft request' (duration: 135.399321ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T17:42:39.805154Z","caller":"traceutil/trace.go:171","msg":"trace[2141735254] transaction","detail":"{read_only:false; response_revision:467; number_of_response:1; }","duration":"211.132011ms","start":"2026-02-02T17:42:39.593988Z","end":"2026-02-02T17:42:39.80512Z","steps":["trace[2141735254] 'process raft request' (duration: 211.044567ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T17:42:39.805401Z","caller":"traceutil/trace.go:171","msg":"trace[24410691] transaction","detail":"{read_only:false; response_revision:466; number_of_response:1; }","duration":"219.461511ms","start":"2026-02-02T17:42:39.585927Z","end":"2026-02-02T17:42:39.805388Z","steps":["trace[24410691] 'process raft request' (duration: 159.426948ms)","trace[24410691] 'compare' (duration: 59.548843ms)"],"step_count":2} {"level":"info","ts":"2026-02-02T17:42:39.805415Z","caller":"traceutil/trace.go:171","msg":"trace[683231846] linearizableReadLoop","detail":"{readStateIndex:490; appliedIndex:484; }","duration":"207.378059ms","start":"2026-02-02T17:42:39.598017Z","end":"2026-02-02T17:42:39.805395Z","steps":["trace[683231846] 'read index received' (duration: 55.931366ms)","trace[683231846] 'applied index is now lower than readState.Index' (duration: 151.445913ms)"],"step_count":2} {"level":"info","ts":"2026-02-02T17:42:39.805608Z","caller":"traceutil/trace.go:171","msg":"trace[50772350] transaction","detail":"{read_only:false; response_revision:468; number_of_response:1; }","duration":"211.549848ms","start":"2026-02-02T17:42:39.594049Z","end":"2026-02-02T17:42:39.805599Z","steps":["trace[50772350] 'process raft request' (duration: 211.036808ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T17:42:39.805824Z","caller":"traceutil/trace.go:171","msg":"trace[5967860] transaction","detail":"{read_only:false; response_revision:469; number_of_response:1; }","duration":"211.740186ms","start":"2026-02-02T17:42:39.594075Z","end":"2026-02-02T17:42:39.805815Z","steps":["trace[5967860] 'process raft request' (duration: 211.184994ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T17:42:39.805946Z","caller":"traceutil/trace.go:171","msg":"trace[1889223947] transaction","detail":"{read_only:false; response_revision:470; number_of_response:1; }","duration":"208.049516ms","start":"2026-02-02T17:42:39.597888Z","end":"2026-02-02T17:42:39.805938Z","steps":["trace[1889223947] 'process raft request' (duration: 207.432261ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T17:42:39.806115Z","caller":"traceutil/trace.go:171","msg":"trace[1878317354] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"208.186772ms","start":"2026-02-02T17:42:39.597919Z","end":"2026-02-02T17:42:39.806106Z","steps":["trace[1878317354] 'process raft request' (duration: 207.439912ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:39.806235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.205092ms","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-02-02T17:42:39.806261Z","caller":"traceutil/trace.go:171","msg":"trace[314432355] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:471; }","duration":"208.249374ms","start":"2026-02-02T17:42:39.598003Z","end":"2026-02-02T17:42:39.806253Z","steps":["trace[314432355] 'agreement among raft nodes before linearized reading' (duration: 208.194491ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:39.806529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.657633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:4 size:329940"} {"level":"info","ts":"2026-02-02T17:42:39.806551Z","caller":"traceutil/trace.go:171","msg":"trace[1753469034] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:4; response_revision:471; }","duration":"125.680684ms","start":"2026-02-02T17:42:39.680862Z","end":"2026-02-02T17:42:39.806543Z","steps":["trace[1753469034] 'agreement among raft nodes before linearized reading' (duration: 125.487377ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T17:42:40.062817Z","caller":"traceutil/trace.go:171","msg":"trace[1616883594] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"207.986373ms","start":"2026-02-02T17:42:39.854813Z","end":"2026-02-02T17:42:40.0628Z","steps":["trace[1616883594] 'process raft request' (duration: 170.995006ms)","trace[1616883594] 'compare' (duration: 33.096611ms)"],"step_count":2} {"level":"info","ts":"2026-02-02T17:42:40.25899Z","caller":"traceutil/trace.go:171","msg":"trace[1035694515] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"402.410792ms","start":"2026-02-02T17:42:39.856558Z","end":"2026-02-02T17:42:40.258969Z","steps":["trace[1035694515] 'process raft request' (duration: 305.6122ms)","trace[1035694515] 'compare' (duration: 96.400316ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T17:42:40.259148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:39.856547Z","time spent":"402.505386ms","remote":"127.0.0.1:50850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":671,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-02T17:42:40.259366Z","caller":"traceutil/trace.go:171","msg":"trace[1357895257] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"400.143979ms","start":"2026-02-02T17:42:39.859213Z","end":"2026-02-02T17:42:40.259357Z","steps":["trace[1357895257] 'process raft request' (duration: 399.465982ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:40.259424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:39.859205Z","time spent":"400.18386ms","remote":"127.0.0.1:51274","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4317,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-02T17:42:40.259555Z","caller":"traceutil/trace.go:171","msg":"trace[1099294260] linearizableReadLoop","detail":"{readStateIndex:495; appliedIndex:492; }","duration":"302.795428ms","start":"2026-02-02T17:42:39.95675Z","end":"2026-02-02T17:42:40.259546Z","steps":["trace[1099294260] 'read index received' (duration: 69.064009ms)","trace[1099294260] 'applied index is now lower than readState.Index' (duration: 233.730409ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T17:42:40.367636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.875479ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-02T17:42:40.367719Z","caller":"traceutil/trace.go:171","msg":"trace[223455269] linearizableReadLoop","detail":"{readStateIndex:496; appliedIndex:495; }","duration":"108.127928ms","start":"2026-02-02T17:42:40.259575Z","end":"2026-02-02T17:42:40.367703Z","steps":["trace[223455269] 'read index received' (duration: 100.774µs)","trace[223455269] 'applied index is now lower than readState.Index' (duration: 108.025794ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T17:42:40.367853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.705083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium-operator\" ","response":"range_response_count:1 size:538"} {"level":"info","ts":"2026-02-02T17:42:40.367883Z","caller":"traceutil/trace.go:171","msg":"trace[2092294771] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium-operator; range_end:; response_count:1; response_revision:476; }","duration":"351.760215ms","start":"2026-02-02T17:42:40.016111Z","end":"2026-02-02T17:42:40.367871Z","steps":["trace[2092294771] 'agreement among raft nodes before linearized reading' (duration: 351.63484ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:40.367915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:40.016099Z","time spent":"351.808847ms","remote":"127.0.0.1:50966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":561,"request content":"key:\"/registry/serviceaccounts/kube-system/cilium-operator\" "} {"level":"warn","ts":"2026-02-02T17:42:40.368273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.51855ms","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-02-02T17:42:40.368301Z","caller":"traceutil/trace.go:171","msg":"trace[2023711810] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:476; }","duration":"411.569162ms","start":"2026-02-02T17:42:39.956723Z","end":"2026-02-02T17:42:40.368292Z","steps":["trace[2023711810] 'agreement among raft nodes before linearized reading' (duration: 302.941893ms)","trace[2023711810] 'range keys from in-memory index tree' (duration: 108.582606ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T17:42:40.368324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:39.95671Z","time spent":"411.608183ms","remote":"127.0.0.1:51044","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-02-02T17:42:40.368384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.476323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-operator-7fcd8d6ffd-wgfm7\" ","response":"range_response_count:1 size:3615"} {"level":"warn","ts":"2026-02-02T17:42:40.368472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.313398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium\" ","response":"range_response_count:1 size:520"} {"level":"info","ts":"2026-02-02T17:42:40.368491Z","caller":"traceutil/trace.go:171","msg":"trace[1783253845] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium; range_end:; response_count:1; response_revision:476; }","duration":"352.338938ms","start":"2026-02-02T17:42:40.016146Z","end":"2026-02-02T17:42:40.368485Z","steps":["trace[1783253845] 'agreement among raft nodes before linearized reading' (duration: 352.279146ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T17:42:40.368486Z","caller":"traceutil/trace.go:171","msg":"trace[512389707] range","detail":"{range_begin:/registry/pods/kube-system/cilium-operator-7fcd8d6ffd-wgfm7; range_end:; response_count:1; response_revision:476; }","duration":"303.534705ms","start":"2026-02-02T17:42:40.064876Z","end":"2026-02-02T17:42:40.368411Z","steps":["trace[512389707] 'agreement among raft nodes before linearized reading' (duration: 303.460342ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:40.368513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:40.016144Z","time spent":"352.364639ms","remote":"127.0.0.1:50966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":543,"request content":"key:\"/registry/serviceaccounts/kube-system/cilium\" "} {"level":"warn","ts":"2026-02-02T17:42:40.368524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:40.064867Z","time spent":"303.6479ms","remote":"127.0.0.1:50948","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":3638,"request content":"key:\"/registry/pods/kube-system/cilium-operator-7fcd8d6ffd-wgfm7\" "} {"level":"warn","ts":"2026-02-02T17:42:40.368684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.328692ms","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-02-02T17:42:40.368703Z","caller":"traceutil/trace.go:171","msg":"trace[1785496213] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:476; }","duration":"189.381875ms","start":"2026-02-02T17:42:40.179315Z","end":"2026-02-02T17:42:40.368697Z","steps":["trace[1785496213] 'agreement among raft nodes before linearized reading' (duration: 189.348503ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T17:42:40.381222Z","caller":"traceutil/trace.go:171","msg":"trace[1172335637] transaction","detail":"{read_only:false; number_of_response:1; response_revision:476; }","duration":"117.336968ms","start":"2026-02-02T17:42:40.263871Z","end":"2026-02-02T17:42:40.381208Z","steps":["trace[1172335637] 'process raft request' (duration: 117.272366ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T17:42:41.485588Z","caller":"traceutil/trace.go:171","msg":"trace[1697250630] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"167.274557ms","start":"2026-02-02T17:42:41.318075Z","end":"2026-02-02T17:42:41.48535Z","steps":["trace[1697250630] 'process raft request' (duration: 167.08596ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T17:42:43.80533Z","caller":"traceutil/trace.go:171","msg":"trace[436536378] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"159.213879ms","start":"2026-02-02T17:42:43.646096Z","end":"2026-02-02T17:42:43.80531Z","steps":["trace[436536378] 'process raft request' (duration: 144.201287ms)","trace[436536378] 'compare' (duration: 14.923679ms)"],"step_count":2} {"level":"info","ts":"2026-02-02T17:42:44.197592Z","caller":"traceutil/trace.go:171","msg":"trace[1846411166] linearizableReadLoop","detail":"{readStateIndex:512; appliedIndex:511; }","duration":"118.024937ms","start":"2026-02-02T17:42:44.079546Z","end":"2026-02-02T17:42:44.197571Z","steps":["trace[1846411166] 'read index received' (duration: 117.873061ms)","trace[1846411166] 'applied index is now lower than readState.Index' (duration: 150.956µs)"],"step_count":2} {"level":"warn","ts":"2026-02-02T17:42:44.197783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.220145ms","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-02-02T17:42:44.197828Z","caller":"traceutil/trace.go:171","msg":"trace[592033450] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:0; response_revision:491; }","duration":"118.279647ms","start":"2026-02-02T17:42:44.079538Z","end":"2026-02-02T17:42:44.197817Z","steps":["trace[592033450] 'agreement among raft nodes before linearized reading' (duration: 118.121331ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T17:42:44.198074Z","caller":"traceutil/trace.go:171","msg":"trace[631620595] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"304.451016ms","start":"2026-02-02T17:42:43.893613Z","end":"2026-02-02T17:42:44.198064Z","steps":["trace[631620595] 'process raft request' (duration: 303.860444ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:44.198163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:43.893602Z","time spent":"304.507548ms","remote":"127.0.0.1:51044","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-02-02T17:42:44.435087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.696223ms","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-02-02T17:42:44.43518Z","caller":"traceutil/trace.go:171","msg":"trace[1700262309] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:0; response_revision:491; }","duration":"130.799867ms","start":"2026-02-02T17:42:44.304363Z","end":"2026-02-02T17:42:44.435162Z","steps":["trace[1700262309] 'range keys from in-memory index tree' (duration: 130.637241ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T17:42:44.661848Z","caller":"traceutil/trace.go:171","msg":"trace[759065199] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"124.335609ms","start":"2026-02-02T17:42:44.537495Z","end":"2026-02-02T17:42:44.661831Z","steps":["trace[759065199] 'process raft request' (duration: 45.832308ms)","trace[759065199] 'compare' (duration: 78.20118ms)"],"step_count":2} {"level":"info","ts":"2026-02-02T17:42:44.854846Z","caller":"traceutil/trace.go:171","msg":"trace[1835597470] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"110.326142ms","start":"2026-02-02T17:42:44.7445Z","end":"2026-02-02T17:42:44.854826Z","steps":["trace[1835597470] 'process raft request' (duration: 110.289721ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T17:42:44.855467Z","caller":"traceutil/trace.go:171","msg":"trace[928933786] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"116.184927ms","start":"2026-02-02T17:42:44.739265Z","end":"2026-02-02T17:42:44.85545Z","steps":["trace[928933786] 'process raft request' (duration: 38.783918ms)","trace[928933786] 'compare' (duration: 76.414642ms)"],"step_count":2} {"level":"info","ts":"2026-02-02T17:42:44.856101Z","caller":"traceutil/trace.go:171","msg":"trace[1987886533] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"116.613643ms","start":"2026-02-02T17:42:44.739474Z","end":"2026-02-02T17:42:44.856088Z","steps":["trace[1987886533] 'process raft request' (duration: 115.219ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:45.313913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.195381ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-02T17:42:45.314099Z","caller":"traceutil/trace.go:171","msg":"trace[1039075032] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"357.452411ms","start":"2026-02-02T17:42:44.956627Z","end":"2026-02-02T17:42:45.314079Z","steps":["trace[1039075032] 'process raft request' (duration: 192.004419ms)","trace[1039075032] 'compare' (duration: 164.576238ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T17:42:45.314197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:44.956609Z","time spent":"357.561636ms","remote":"127.0.0.1:51124","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1091,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-02T17:42:45.476468Z","caller":"traceutil/trace.go:171","msg":"trace[1867606552] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"125.802409ms","start":"2026-02-02T17:42:45.350631Z","end":"2026-02-02T17:42:45.476433Z","steps":["trace[1867606552] 'process raft request' (duration: 95.75317ms)","trace[1867606552] 'compare' (duration: 29.925463ms)"],"step_count":2} {"level":"info","ts":"2026-02-02T17:42:45.664281Z","caller":"traceutil/trace.go:171","msg":"trace[1348335325] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"118.983731ms","start":"2026-02-02T17:42:45.545278Z","end":"2026-02-02T17:42:45.664261Z","steps":["trace[1348335325] 'process raft request' (duration: 108.041556ms)","trace[1348335325] 'compare' (duration: 10.79996ms)"],"step_count":2} {"level":"info","ts":"2026-02-02T17:42:45.664426Z","caller":"traceutil/trace.go:171","msg":"trace[250768754] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"118.876377ms","start":"2026-02-02T17:42:45.545544Z","end":"2026-02-02T17:42:45.66442Z","steps":["trace[250768754] 'process raft request' (duration: 118.68087ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:45.942844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.775052ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-02T17:42:45.943134Z","caller":"traceutil/trace.go:171","msg":"trace[343492260] linearizableReadLoop","detail":"{readStateIndex:531; appliedIndex:528; }","duration":"317.59053ms","start":"2026-02-02T17:42:45.62553Z","end":"2026-02-02T17:42:45.94312Z","steps":["trace[343492260] 'read index received' (duration: 27.878258ms)","trace[343492260] 'applied index is now lower than readState.Index' (duration: 289.710992ms)"],"step_count":2} {"level":"info","ts":"2026-02-02T17:42:45.94329Z","caller":"traceutil/trace.go:171","msg":"trace[907972648] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"366.634159ms","start":"2026-02-02T17:42:45.576645Z","end":"2026-02-02T17:42:45.943279Z","steps":["trace[907972648] 'process raft request' (duration: 194.365739ms)","trace[907972648] 'compare' (duration: 171.540822ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T17:42:45.944451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:45.57663Z","time spent":"367.796664ms","remote":"127.0.0.1:50948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2550,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-02T17:42:45.944795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.265133ms","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:1 size:12727"} {"level":"info","ts":"2026-02-02T17:42:45.944844Z","caller":"traceutil/trace.go:171","msg":"trace[53714785] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:1; response_revision:509; }","duration":"319.344946ms","start":"2026-02-02T17:42:45.625491Z","end":"2026-02-02T17:42:45.944836Z","steps":["trace[53714785] 'agreement among raft nodes before linearized reading' (duration: 319.208281ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:45.944885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:45.62546Z","time spent":"319.417269ms","remote":"127.0.0.1:50870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":12750,"request content":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" "} {"level":"warn","ts":"2026-02-02T17:42:45.945057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.645137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/local-path-storage/local-path-provisioner\" ","response":"range_response_count:1 size:3343"} {"level":"info","ts":"2026-02-02T17:42:45.94514Z","caller":"traceutil/trace.go:171","msg":"trace[1678031889] range","detail":"{range_begin:/registry/deployments/local-path-storage/local-path-provisioner; range_end:; response_count:1; response_revision:509; }","duration":"276.753111ms","start":"2026-02-02T17:42:45.668379Z","end":"2026-02-02T17:42:45.945132Z","steps":["trace[1678031889] 'agreement among raft nodes before linearized reading' (duration: 276.616016ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:45.945312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.601603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T17:42:45.945462Z","caller":"traceutil/trace.go:171","msg":"trace[618198823] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:509; }","duration":"225.779799ms","start":"2026-02-02T17:42:45.719674Z","end":"2026-02-02T17:42:45.945454Z","steps":["trace[618198823] 'agreement among raft nodes before linearized reading' (duration: 225.619033ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:45.946902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.350667ms","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-02-02T17:42:45.947031Z","caller":"traceutil/trace.go:171","msg":"trace[997235805] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:509; }","duration":"247.454081ms","start":"2026-02-02T17:42:45.699497Z","end":"2026-02-02T17:42:45.946951Z","steps":["trace[997235805] 'agreement among raft nodes before linearized reading' (duration: 247.258423ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:46.198687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.323786ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-02T17:42:46.198813Z","caller":"traceutil/trace.go:171","msg":"trace[1746141523] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"251.599391ms","start":"2026-02-02T17:42:45.947195Z","end":"2026-02-02T17:42:46.198794Z","steps":["trace[1746141523] 'process raft request' (duration: 122.101848ms)","trace[1746141523] 'compare' (duration: 129.16115ms)"],"step_count":2} {"level":"info","ts":"2026-02-02T17:42:46.284812Z","caller":"traceutil/trace.go:171","msg":"trace[504782082] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"331.897447ms","start":"2026-02-02T17:42:45.952896Z","end":"2026-02-02T17:42:46.284793Z","steps":["trace[504782082] 'process raft request' (duration: 331.740221ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:46.284938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:45.952869Z","time spent":"332.002531ms","remote":"127.0.0.1:50948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3024,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-02T17:42:46.285328Z","caller":"traceutil/trace.go:171","msg":"trace[1129392047] linearizableReadLoop","detail":"{readStateIndex:533; appliedIndex:531; }","duration":"332.079814ms","start":"2026-02-02T17:42:45.953237Z","end":"2026-02-02T17:42:46.285317Z","steps":["trace[1129392047] 'read index received' (duration: 116.07164ms)","trace[1129392047] 'applied index is now lower than readState.Index' (duration: 216.007394ms)"],"step_count":2} {"level":"info","ts":"2026-02-02T17:42:46.28541Z","caller":"traceutil/trace.go:171","msg":"trace[723388569] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"332.020562ms","start":"2026-02-02T17:42:45.953382Z","end":"2026-02-02T17:42:46.285402Z","steps":["trace[723388569] 'process raft request' (duration: 331.369838ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:46.285465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:45.953373Z","time spent":"332.063843ms","remote":"127.0.0.1:50850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":785,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-02T17:42:46.2856Z","caller":"traceutil/trace.go:171","msg":"trace[215654740] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"325.783706ms","start":"2026-02-02T17:42:45.959808Z","end":"2026-02-02T17:42:46.285592Z","steps":["trace[215654740] 'process raft request' (duration: 325.475254ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:46.285736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:45.9598Z","time spent":"325.830947ms","remote":"127.0.0.1:51044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-02T17:42:46.285828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.589163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T17:42:46.285859Z","caller":"traceutil/trace.go:171","msg":"trace[725833065] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:515; }","duration":"332.642944ms","start":"2026-02-02T17:42:45.953208Z","end":"2026-02-02T17:42:46.285851Z","steps":["trace[725833065] 'agreement among raft nodes before linearized reading' (duration: 332.594953ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:46.285884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:45.953199Z","time spent":"332.679797ms","remote":"127.0.0.1:50772","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-02-02T17:42:46.286051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.874563ms","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:1 size:12727"} {"level":"info","ts":"2026-02-02T17:42:46.286102Z","caller":"traceutil/trace.go:171","msg":"trace[1326373836] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:1; response_revision:515; }","duration":"273.953895ms","start":"2026-02-02T17:42:46.01214Z","end":"2026-02-02T17:42:46.286094Z","steps":["trace[1326373836] 'agreement among raft nodes before linearized reading' (duration: 273.871932ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T17:42:46.286271Z","caller":"traceutil/trace.go:171","msg":"trace[1258411955] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"332.75526ms","start":"2026-02-02T17:42:45.953498Z","end":"2026-02-02T17:42:46.286253Z","steps":["trace[1258411955] 'process raft request' (duration: 331.69386ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:46.286494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:45.953491Z","time spent":"332.930977ms","remote":"127.0.0.1:51274","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3165,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-02T17:42:46.286795Z","caller":"traceutil/trace.go:171","msg":"trace[1947852375] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"329.006528ms","start":"2026-02-02T17:42:45.957773Z","end":"2026-02-02T17:42:46.28678Z","steps":["trace[1947852375] 'process raft request' (duration: 327.47612ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:46.286867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:45.957761Z","time spent":"329.07222ms","remote":"127.0.0.1:51244","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3638,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-02T17:42:46.550185Z","caller":"traceutil/trace.go:171","msg":"trace[1264581772] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"163.456605ms","start":"2026-02-02T17:42:46.386711Z","end":"2026-02-02T17:42:46.550168Z","steps":["trace[1264581772] 'process raft request' (duration: 105.237313ms)","trace[1264581772] 'compare' (duration: 56.948984ms)"],"step_count":2} {"level":"info","ts":"2026-02-02T17:42:46.711316Z","caller":"traceutil/trace.go:171","msg":"trace[1748304815] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"153.920478ms","start":"2026-02-02T17:42:46.557372Z","end":"2026-02-02T17:42:46.711292Z","steps":["trace[1748304815] 'process raft request' (duration: 153.534624ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T17:42:48.491346Z","caller":"traceutil/trace.go:171","msg":"trace[2025820051] linearizableReadLoop","detail":"{readStateIndex:543; appliedIndex:542; }","duration":"105.558185ms","start":"2026-02-02T17:42:48.385771Z","end":"2026-02-02T17:42:48.491329Z","steps":["trace[2025820051] 'read index received' (duration: 105.397359ms)","trace[2025820051] 'applied index is now lower than readState.Index' (duration: 160.146µs)"],"step_count":2} {"level":"info","ts":"2026-02-02T17:42:48.491691Z","caller":"traceutil/trace.go:171","msg":"trace[1855816840] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"186.53895ms","start":"2026-02-02T17:42:48.305143Z","end":"2026-02-02T17:42:48.491682Z","steps":["trace[1855816840] 'process raft request' (duration: 186.072593ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:48.492141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.355244ms","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-02-02T17:42:48.492175Z","caller":"traceutil/trace.go:171","msg":"trace[287768436] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:521; }","duration":"106.424586ms","start":"2026-02-02T17:42:48.385741Z","end":"2026-02-02T17:42:48.492166Z","steps":["trace[287768436] 'agreement among raft nodes before linearized reading' (duration: 105.652798ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T17:42:48.611268Z","caller":"traceutil/trace.go:171","msg":"trace[597735763] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"115.762019ms","start":"2026-02-02T17:42:48.495492Z","end":"2026-02-02T17:42:48.611254Z","steps":["trace[597735763] 'process raft request' (duration: 37.753933ms)","trace[597735763] 'compare' (duration: 77.769796ms)"],"step_count":2} {"level":"info","ts":"2026-02-02T17:42:48.779087Z","caller":"traceutil/trace.go:171","msg":"trace[1514917916] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"163.663051ms","start":"2026-02-02T17:42:48.615408Z","end":"2026-02-02T17:42:48.779071Z","steps":["trace[1514917916] 'process raft request' (duration: 101.371151ms)","trace[1514917916] 'compare' (duration: 62.069022ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T17:42:49.341529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.046827ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-02T17:42:49.34164Z","caller":"traceutil/trace.go:171","msg":"trace[1826218659] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"558.422822ms","start":"2026-02-02T17:42:48.783186Z","end":"2026-02-02T17:42:49.341608Z","steps":["trace[1826218659] 'process raft request' (duration: 165.22604ms)","trace[1826218659] 'compare' (duration: 392.781697ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T17:42:49.34176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:48.783175Z","time spent":"558.553076ms","remote":"127.0.0.1:51044","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-02-02T17:42:51.116536Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12667390035785301644,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-02T17:42:51.146791Z","caller":"traceutil/trace.go:171","msg":"trace[796816516] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"644.7173ms","start":"2026-02-02T17:42:50.502056Z","end":"2026-02-02T17:42:51.146773Z","steps":["trace[796816516] 'process raft request' (duration: 644.541464ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:51.146919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:50.502045Z","time spent":"644.796793ms","remote":"127.0.0.1:51044","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-02-02T17:42:51.147058Z","caller":"traceutil/trace.go:171","msg":"trace[758008300] linearizableReadLoop","detail":"{readStateIndex:548; appliedIndex:548; }","duration":"531.451317ms","start":"2026-02-02T17:42:50.615601Z","end":"2026-02-02T17:42:51.147052Z","steps":["trace[758008300] 'read index received' (duration: 531.448857ms)","trace[758008300] 'applied index is now lower than readState.Index' (duration: 2.1µs)"],"step_count":2} {"level":"warn","ts":"2026-02-02T17:42:51.147119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.670525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T17:42:51.147139Z","caller":"traceutil/trace.go:171","msg":"trace[716866358] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:525; }","duration":"429.718427ms","start":"2026-02-02T17:42:50.717416Z","end":"2026-02-02T17:42:51.147134Z","steps":["trace[716866358] 'agreement among raft nodes before linearized reading' (duration: 429.674755ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:51.147152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:50.717401Z","time spent":"429.748638ms","remote":"127.0.0.1:50766","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-02-02T17:42:51.147316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"531.713226ms","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-02-02T17:42:51.147336Z","caller":"traceutil/trace.go:171","msg":"trace[513471254] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:525; }","duration":"531.754978ms","start":"2026-02-02T17:42:50.615575Z","end":"2026-02-02T17:42:51.14733Z","steps":["trace[513471254] 'agreement among raft nodes before linearized reading' (duration: 531.695246ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:51.147352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:50.615563Z","time spent":"531.786338ms","remote":"127.0.0.1:51044","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-02-02T17:42:51.147429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.98905ms","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-02-02T17:42:51.147445Z","caller":"traceutil/trace.go:171","msg":"trace[210028774] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:525; }","duration":"365.038451ms","start":"2026-02-02T17:42:50.782403Z","end":"2026-02-02T17:42:51.147442Z","steps":["trace[210028774] 'agreement among raft nodes before linearized reading' (duration: 364.9961ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:51.147458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:50.782385Z","time spent":"365.069463ms","remote":"127.0.0.1:51044","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-02-02T17:42:52.00458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"478.852289ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-02T17:42:52.004659Z","caller":"traceutil/trace.go:171","msg":"trace[406753770] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"853.082267ms","start":"2026-02-02T17:42:51.151564Z","end":"2026-02-02T17:42:52.004646Z","steps":["trace[406753770] 'process raft request' (duration: 374.109665ms)","trace[406753770] 'compare' (duration: 478.759315ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T17:42:52.004699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:51.151555Z","time spent":"853.124969ms","remote":"127.0.0.1:51044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-02T17:42:52.278992Z","caller":"traceutil/trace.go:171","msg":"trace[555974872] linearizableReadLoop","detail":"{readStateIndex:550; appliedIndex:548; }","duration":"934.887011ms","start":"2026-02-02T17:42:51.344086Z","end":"2026-02-02T17:42:52.278973Z","steps":["trace[555974872] 'read index received' (duration: 181.618755ms)","trace[555974872] 'applied index is now lower than readState.Index' (duration: 753.267606ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T17:42:52.279134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"935.035427ms","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-02-02T17:42:52.279155Z","caller":"traceutil/trace.go:171","msg":"trace[1410081802] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:527; }","duration":"935.087448ms","start":"2026-02-02T17:42:51.344061Z","end":"2026-02-02T17:42:52.279148Z","steps":["trace[1410081802] 'agreement among raft nodes before linearized reading' (duration: 934.975824ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:52.279176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:51.344048Z","time spent":"935.12306ms","remote":"127.0.0.1:51044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-02T17:42:52.279425Z","caller":"traceutil/trace.go:171","msg":"trace[387536277] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"1.127777415s","start":"2026-02-02T17:42:51.15164Z","end":"2026-02-02T17:42:52.279417Z","steps":["trace[387536277] 'process raft request' (duration: 1.118804011s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:52.279532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:51.151635Z","time spent":"1.127855108s","remote":"127.0.0.1:51044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-02T17:42:54.188624Z","caller":"traceutil/trace.go:171","msg":"trace[791914971] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"178.42279ms","start":"2026-02-02T17:42:54.010184Z","end":"2026-02-02T17:42:54.188607Z","steps":["trace[791914971] 'process raft request' (duration: 178.326577ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T17:42:56.493916Z","caller":"traceutil/trace.go:171","msg":"trace[1409735399] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"110.870427ms","start":"2026-02-02T17:42:56.383026Z","end":"2026-02-02T17:42:56.493896Z","steps":["trace[1409735399] 'process raft request' (duration: 95.314807ms)","trace[1409735399] 'compare' (duration: 15.477397ms)"],"step_count":2} {"level":"info","ts":"2026-02-02T17:43:00.652904Z","caller":"traceutil/trace.go:171","msg":"trace[448748386] linearizableReadLoop","detail":"{readStateIndex:574; appliedIndex:573; }","duration":"266.580238ms","start":"2026-02-02T17:43:00.386302Z","end":"2026-02-02T17:43:00.652883Z","steps":["trace[448748386] 'read index received' (duration: 266.429424ms)","trace[448748386] 'applied index is now lower than readState.Index' (duration: 149.774µs)"],"step_count":2} {"level":"info","ts":"2026-02-02T17:43:00.653175Z","caller":"traceutil/trace.go:171","msg":"trace[1306898647] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"396.490696ms","start":"2026-02-02T17:43:00.256595Z","end":"2026-02-02T17:43:00.653085Z","steps":["trace[1306898647] 'process raft request' (duration: 396.161505ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:43:00.653281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.117048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-2jc7v\" ","response":"range_response_count:1 size:16656"} {"level":"info","ts":"2026-02-02T17:43:00.653367Z","caller":"traceutil/trace.go:171","msg":"trace[739931289] range","detail":"{range_begin:/registry/pods/kube-system/cilium-2jc7v; range_end:; response_count:1; response_revision:548; }","duration":"223.268723ms","start":"2026-02-02T17:43:00.43008Z","end":"2026-02-02T17:43:00.653348Z","steps":["trace[739931289] 'agreement among raft nodes before linearized reading' (duration: 223.100758ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:43:00.653394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:43:00.256581Z","time spent":"396.665981ms","remote":"127.0.0.1:51044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-02T17:43:00.65366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.359254ms","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-02-02T17:43:00.653745Z","caller":"traceutil/trace.go:171","msg":"trace[1473318160] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:548; }","duration":"267.480477ms","start":"2026-02-02T17:43:00.386254Z","end":"2026-02-02T17:43:00.653734Z","steps":["trace[1473318160] 'agreement among raft nodes before linearized reading' (duration: 267.318572ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:43:00.653878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.140862ms","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-02-02T17:43:00.653912Z","caller":"traceutil/trace.go:171","msg":"trace[1215118648] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:548; }","duration":"149.222774ms","start":"2026-02-02T17:43:00.504682Z","end":"2026-02-02T17:43:00.653905Z","steps":["trace[1215118648] 'agreement among raft nodes before linearized reading' (duration: 149.134611ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T17:43:01.198175Z","caller":"traceutil/trace.go:171","msg":"trace[282046267] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"536.355704ms","start":"2026-02-02T17:43:00.661797Z","end":"2026-02-02T17:43:01.198153Z","steps":["trace[282046267] 'process raft request' (duration: 536.238571ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:43:01.198393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:43:00.661762Z","time spent":"536.475418ms","remote":"127.0.0.1:51044","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-02-02T17:43:01.224704Z","caller":"traceutil/trace.go:171","msg":"trace[1063780122] linearizableReadLoop","detail":"{readStateIndex:577; appliedIndex:575; }","duration":"508.710611ms","start":"2026-02-02T17:43:00.715977Z","end":"2026-02-02T17:43:01.224687Z","steps":["trace[1063780122] 'read index received' (duration: 482.814891ms)","trace[1063780122] 'applied index is now lower than readState.Index' (duration: 25.89483ms)"],"step_count":2} {"level":"info","ts":"2026-02-02T17:43:01.224896Z","caller":"traceutil/trace.go:171","msg":"trace[1626506206] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"560.90202ms","start":"2026-02-02T17:43:00.663983Z","end":"2026-02-02T17:43:01.224885Z","steps":["trace[1626506206] 'process raft request' (duration: 560.570369ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:43:01.22498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:43:00.66397Z","time spent":"560.955701ms","remote":"127.0.0.1:51044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-02T17:43:01.225173Z","caller":"traceutil/trace.go:171","msg":"trace[1840362604] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"550.726664ms","start":"2026-02-02T17:43:00.674438Z","end":"2026-02-02T17:43:01.225165Z","steps":["trace[1840362604] 'process raft request' (duration: 550.207427ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:43:01.22522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:43:00.674424Z","time spent":"550.774245ms","remote":"127.0.0.1:50948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-02T17:43:01.225326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"509.347941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T17:43:01.225361Z","caller":"traceutil/trace.go:171","msg":"trace[1548512663] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:551; }","duration":"509.411262ms","start":"2026-02-02T17:43:00.715939Z","end":"2026-02-02T17:43:01.22535Z","steps":["trace[1548512663] 'agreement among raft nodes before linearized reading' (duration: 509.363931ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:43:01.225383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:43:00.715923Z","time spent":"509.455083ms","remote":"127.0.0.1:50766","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "}