{"level":"warn","ts":"2026-04-03T02:51:24.031986Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-03T02:51:24.032087Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.113:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.113:2380","--initial-cluster=instance=https://199.204.45.113:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.113:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.113:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-03T02:51:24.032143Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-03T02:51:24.032154Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.113:2380"]} {"level":"info","ts":"2026-04-03T02:51:24.032173Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-03T02:51:24.032934Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.113:2379"]} {"level":"info","ts":"2026-04-03T02:51:24.033004Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.113:2380"],"listen-peer-urls":["https://199.204.45.113:2380"],"advertise-client-urls":["https://199.204.45.113:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.113:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.113:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-03T02:51:24.037675Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.4496ms"} {"level":"info","ts":"2026-04-03T02:51:24.047753Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"a31e123290fcf0df","cluster-id":"37661d6ee45ed9e8"} {"level":"info","ts":"2026-04-03T02:51:24.047852Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df switched to configuration voters=()"} {"level":"info","ts":"2026-04-03T02:51:24.047887Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became follower at term 0"} {"level":"info","ts":"2026-04-03T02:51:24.047906Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft a31e123290fcf0df [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-03T02:51:24.047928Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became follower at term 1"} {"level":"info","ts":"2026-04-03T02:51:24.047979Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df switched to configuration voters=(11753852085873733855)"} {"level":"warn","ts":"2026-04-03T02:51:24.05832Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-03T02:51:24.060193Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-03T02:51:24.064017Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-03T02:51:24.066614Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"a31e123290fcf0df","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-03T02:51:24.067314Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"a31e123290fcf0df","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-03T02:51:24.067641Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-03T02:51:24.067734Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-03T02:51:24.067755Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-03T02:51:24.069292Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df switched to configuration voters=(11753852085873733855)"} {"level":"info","ts":"2026-04-03T02:51:24.069994Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"37661d6ee45ed9e8","local-member-id":"a31e123290fcf0df","added-peer-id":"a31e123290fcf0df","added-peer-peer-urls":["https://199.204.45.113:2380"]} {"level":"info","ts":"2026-04-03T02:51:24.070657Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-03T02:51:24.070864Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"a31e123290fcf0df","initial-advertise-peer-urls":["https://199.204.45.113:2380"],"listen-peer-urls":["https://199.204.45.113:2380"],"advertise-client-urls":["https://199.204.45.113:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.113:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-03T02:51:24.070899Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-03T02:51:24.071005Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.113:2380"} {"level":"info","ts":"2026-04-03T02:51:24.071015Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.113:2380"} {"level":"info","ts":"2026-04-03T02:51:24.948493Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df is starting a new election at term 1"} {"level":"info","ts":"2026-04-03T02:51:24.948702Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became pre-candidate at term 1"} {"level":"info","ts":"2026-04-03T02:51:24.94879Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df received MsgPreVoteResp from a31e123290fcf0df at term 1"} {"level":"info","ts":"2026-04-03T02:51:24.948853Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became candidate at term 2"} {"level":"info","ts":"2026-04-03T02:51:24.94898Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df received MsgVoteResp from a31e123290fcf0df at term 2"} {"level":"info","ts":"2026-04-03T02:51:24.949023Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became leader at term 2"} {"level":"info","ts":"2026-04-03T02:51:24.949095Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: a31e123290fcf0df elected leader a31e123290fcf0df at term 2"} {"level":"info","ts":"2026-04-03T02:51:24.968601Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-03T02:51:25.005202Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-03T02:51:25.005223Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"a31e123290fcf0df","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.113:2379]}","request-path":"/0/members/a31e123290fcf0df/attributes","cluster-id":"37661d6ee45ed9e8","publish-timeout":"7s"} {"level":"info","ts":"2026-04-03T02:51:25.00554Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-03T02:51:25.005743Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-03T02:51:25.005838Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-03T02:51:25.00676Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"37661d6ee45ed9e8","local-member-id":"a31e123290fcf0df","cluster-version":"3.5"} {"level":"info","ts":"2026-04-03T02:51:25.006926Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-03T02:51:25.007014Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-03T02:51:25.009126Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-03T02:51:25.01056Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.113:2379"} {"level":"info","ts":"2026-04-03T02:51:33.978493Z","caller":"traceutil/trace.go:171","msg":"trace[188621520] linearizableReadLoop","detail":"{readStateIndex:349; appliedIndex:348; }","duration":"124.86338ms","start":"2026-04-03T02:51:33.853606Z","end":"2026-04-03T02:51:33.978469Z","steps":["trace[188621520] 'read index received' (duration: 73.458617ms)","trace[188621520] 'applied index is now lower than readState.Index' (duration: 51.403793ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T02:51:33.978584Z","caller":"traceutil/trace.go:171","msg":"trace[125518793] transaction","detail":"{read_only:false; response_revision:336; number_of_response:1; }","duration":"151.767308ms","start":"2026-04-03T02:51:33.826776Z","end":"2026-04-03T02:51:33.978543Z","steps":["trace[125518793] 'process raft request' (duration: 100.236943ms)","trace[125518793] 'compare' (duration: 51.352651ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T02:51:33.978629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.997343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-04-03T02:51:33.978696Z","caller":"traceutil/trace.go:171","msg":"trace[722046756] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:336; }","duration":"125.115645ms","start":"2026-04-03T02:51:33.85357Z","end":"2026-04-03T02:51:33.978686Z","steps":["trace[722046756] 'agreement among raft nodes before linearized reading' (duration: 124.999203ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:51:34.681404Z","caller":"traceutil/trace.go:171","msg":"trace[1132978687] transaction","detail":"{read_only:false; response_revision:343; number_of_response:1; }","duration":"118.400413ms","start":"2026-04-03T02:51:34.56298Z","end":"2026-04-03T02:51:34.681381Z","steps":["trace[1132978687] 'process raft request' (duration: 118.077068ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:51:35.444742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"650.75046ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-03T02:51:35.444892Z","caller":"traceutil/trace.go:171","msg":"trace[700262754] transaction","detail":"{read_only:false; response_revision:344; number_of_response:1; }","duration":"757.097592ms","start":"2026-04-03T02:51:34.687764Z","end":"2026-04-03T02:51:35.444861Z","steps":["trace[700262754] 'process raft request' (duration: 105.708084ms)","trace[700262754] 'compare' (duration: 650.622423ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T02:51:35.445002Z","caller":"traceutil/trace.go:171","msg":"trace[539702674] transaction","detail":"{read_only:false; response_revision:345; number_of_response:1; }","duration":"753.724155ms","start":"2026-04-03T02:51:34.691264Z","end":"2026-04-03T02:51:35.444988Z","steps":["trace[539702674] 'process raft request' (duration: 753.595759ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:51:35.445009Z","caller":"traceutil/trace.go:171","msg":"trace[1971402024] linearizableReadLoop","detail":"{readStateIndex:357; appliedIndex:356; }","duration":"756.901743ms","start":"2026-04-03T02:51:34.688082Z","end":"2026-04-03T02:51:35.444984Z","steps":["trace[1971402024] 'read index received' (duration: 105.380858ms)","trace[1971402024] 'applied index is now lower than readState.Index' (duration: 651.518435ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T02:51:35.445024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:51:34.68775Z","time spent":"757.218557ms","remote":"127.0.0.1:54976","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-04-03T02:51:35.445209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"757.110882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4369"} {"level":"warn","ts":"2026-04-03T02:51:35.445238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:51:34.691247Z","time spent":"753.799328ms","remote":"127.0.0.1:55374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3691,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-03T02:51:35.44528Z","caller":"traceutil/trace.go:171","msg":"trace[1506702480] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:345; }","duration":"757.199617ms","start":"2026-04-03T02:51:34.688068Z","end":"2026-04-03T02:51:35.445268Z","steps":["trace[1506702480] 'agreement among raft nodes before linearized reading' (duration: 757.054451ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:51:35.445348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:51:34.688061Z","time spent":"757.275321ms","remote":"127.0.0.1:55084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4392,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-04-03T02:51:35.445366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.99299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"warn","ts":"2026-04-03T02:51:35.445407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"541.193437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"warn","ts":"2026-04-03T02:51:35.445439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"691.873071ms","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-04-03T02:51:35.445456Z","caller":"traceutil/trace.go:171","msg":"trace[1882742920] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:345; }","duration":"541.25817ms","start":"2026-04-03T02:51:34.904177Z","end":"2026-04-03T02:51:35.445435Z","steps":["trace[1882742920] 'agreement among raft nodes before linearized reading' (duration: 541.192297ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:51:35.445496Z","caller":"traceutil/trace.go:171","msg":"trace[1669514449] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:345; }","duration":"691.948674ms","start":"2026-04-03T02:51:34.753536Z","end":"2026-04-03T02:51:35.445484Z","steps":["trace[1669514449] 'agreement among raft nodes before linearized reading' (duration: 691.86303ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:51:35.445505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.448596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"warn","ts":"2026-04-03T02:51:35.445537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:51:34.753525Z","time spent":"692.004716ms","remote":"127.0.0.1:55106","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-04-03T02:51:35.445556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"705.64942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-03T02:51:35.445587Z","caller":"traceutil/trace.go:171","msg":"trace[1215893297] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:345; }","duration":"705.721234ms","start":"2026-04-03T02:51:34.739858Z","end":"2026-04-03T02:51:35.445579Z","steps":["trace[1215893297] 'agreement among raft nodes before linearized reading' (duration: 705.66319ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:51:35.445601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.87063ms","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":"warn","ts":"2026-04-03T02:51:35.445612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:51:34.73984Z","time spent":"705.766766ms","remote":"127.0.0.1:55166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-03T02:51:35.445635Z","caller":"traceutil/trace.go:171","msg":"trace[525532607] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:345; }","duration":"391.938112ms","start":"2026-04-03T02:51:35.053689Z","end":"2026-04-03T02:51:35.445627Z","steps":["trace[525532607] 'agreement among raft nodes before linearized reading' (duration: 391.8678ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:51:35.445623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"491.659565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"warn","ts":"2026-04-03T02:51:35.445738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"641.510319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"warn","ts":"2026-04-03T02:51:35.445742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"741.046064ms","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-04-03T02:51:35.445775Z","caller":"traceutil/trace.go:171","msg":"trace[2110741371] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:345; }","duration":"741.101858ms","start":"2026-04-03T02:51:34.704666Z","end":"2026-04-03T02:51:35.445768Z","steps":["trace[2110741371] 'agreement among raft nodes before linearized reading' (duration: 741.052686ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:51:35.445807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:51:34.704651Z","time spent":"741.150341ms","remote":"127.0.0.1:55106","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":"info","ts":"2026-04-03T02:51:35.445756Z","caller":"traceutil/trace.go:171","msg":"trace[314733610] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:345; }","duration":"491.878454ms","start":"2026-04-03T02:51:34.953852Z","end":"2026-04-03T02:51:35.44573Z","steps":["trace[314733610] 'agreement among raft nodes before linearized reading' (duration: 491.475736ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:51:35.445849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"757.615545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4369"} {"level":"info","ts":"2026-04-03T02:51:35.445539Z","caller":"traceutil/trace.go:171","msg":"trace[977104579] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:345; }","duration":"342.500338ms","start":"2026-04-03T02:51:35.103028Z","end":"2026-04-03T02:51:35.445528Z","steps":["trace[977104579] 'agreement among raft nodes before linearized reading' (duration: 342.451256ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:51:35.445599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"551.402652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-03T02:51:35.445882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:51:34.953836Z","time spent":"492.029032ms","remote":"127.0.0.1:55106","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-04-03T02:51:35.445501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:51:34.904165Z","time spent":"541.328983ms","remote":"127.0.0.1:55106","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-04-03T02:51:35.445675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:51:35.053667Z","time spent":"392.001476ms","remote":"127.0.0.1:55106","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-04-03T02:51:35.445965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:51:35.103016Z","time spent":"342.933618ms","remote":"127.0.0.1:55106","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":"info","ts":"2026-04-03T02:51:35.446087Z","caller":"traceutil/trace.go:171","msg":"trace[2059667016] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:345; }","duration":"551.983129ms","start":"2026-04-03T02:51:34.894014Z","end":"2026-04-03T02:51:35.445997Z","steps":["trace[2059667016] 'agreement among raft nodes before linearized reading' (duration: 551.277766ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:51:35.446232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:51:34.894002Z","time spent":"552.181759ms","remote":"127.0.0.1:54920","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-04-03T02:51:35.445774Z","caller":"traceutil/trace.go:171","msg":"trace[2133984942] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:345; }","duration":"641.564191ms","start":"2026-04-03T02:51:34.804201Z","end":"2026-04-03T02:51:35.445765Z","steps":["trace[2133984942] 'agreement among raft nodes before linearized reading' (duration: 641.512958ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:51:35.446292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:51:34.804189Z","time spent":"642.092076ms","remote":"127.0.0.1:55106","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":"info","ts":"2026-04-03T02:51:35.445443Z","caller":"traceutil/trace.go:171","msg":"trace[307897189] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:345; }","duration":"441.103655ms","start":"2026-04-03T02:51:35.004322Z","end":"2026-04-03T02:51:35.445426Z","steps":["trace[307897189] 'agreement among raft nodes before linearized reading' (duration: 440.977308ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:51:35.446392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:51:35.004306Z","time spent":"442.072809ms","remote":"127.0.0.1:55106","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-04-03T02:51:35.445692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"591.991178ms","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-04-03T02:51:35.446498Z","caller":"traceutil/trace.go:171","msg":"trace[1944008675] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:345; }","duration":"592.783894ms","start":"2026-04-03T02:51:34.853693Z","end":"2026-04-03T02:51:35.446477Z","steps":["trace[1944008675] 'agreement among raft nodes before linearized reading' (duration: 591.970558ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:51:35.446529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:51:34.853629Z","time spent":"592.892779ms","remote":"127.0.0.1:55106","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":"info","ts":"2026-04-03T02:51:35.445887Z","caller":"traceutil/trace.go:171","msg":"trace[467327032] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:345; }","duration":"757.659447ms","start":"2026-04-03T02:51:34.688218Z","end":"2026-04-03T02:51:35.445877Z","steps":["trace[467327032] 'agreement among raft nodes before linearized reading' (duration: 757.06918ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:51:35.446825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:51:34.688212Z","time spent":"758.59954ms","remote":"127.0.0.1:55084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4392,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-04-03T02:51:35.699146Z","caller":"traceutil/trace.go:171","msg":"trace[541986838] transaction","detail":"{read_only:false; response_revision:346; number_of_response:1; }","duration":"245.241694ms","start":"2026-04-03T02:51:35.453875Z","end":"2026-04-03T02:51:35.699117Z","steps":["trace[541986838] 'process raft request' (duration: 239.347072ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:51:35.699307Z","caller":"traceutil/trace.go:171","msg":"trace[1500192518] linearizableReadLoop","detail":"{readStateIndex:359; appliedIndex:358; }","duration":"244.962131ms","start":"2026-04-03T02:51:35.454322Z","end":"2026-04-03T02:51:35.699284Z","steps":["trace[1500192518] 'read index received' (duration: 239.047209ms)","trace[1500192518] 'applied index is now lower than readState.Index' (duration: 5.913392ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T02:51:35.699583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.201253ms","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-04-03T02:51:35.699746Z","caller":"traceutil/trace.go:171","msg":"trace[2043747747] transaction","detail":"{read_only:false; response_revision:347; number_of_response:1; }","duration":"245.376611ms","start":"2026-04-03T02:51:35.454344Z","end":"2026-04-03T02:51:35.699721Z","steps":["trace[2043747747] 'process raft request' (duration: 245.209343ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:51:35.700127Z","caller":"traceutil/trace.go:171","msg":"trace[1744877996] transaction","detail":"{read_only:false; response_revision:348; number_of_response:1; }","duration":"244.112971ms","start":"2026-04-03T02:51:35.455999Z","end":"2026-04-03T02:51:35.700112Z","steps":["trace[1744877996] 'process raft request' (duration: 243.667101ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:51:35.69974Z","caller":"traceutil/trace.go:171","msg":"trace[106899412] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:346; }","duration":"245.349679ms","start":"2026-04-03T02:51:35.454309Z","end":"2026-04-03T02:51:35.699658Z","steps":["trace[106899412] 'agreement among raft nodes before linearized reading' (duration: 245.111738ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:51:35.700308Z","caller":"traceutil/trace.go:171","msg":"trace[19305047] transaction","detail":"{read_only:false; response_revision:349; number_of_response:1; }","duration":"244.246028ms","start":"2026-04-03T02:51:35.456051Z","end":"2026-04-03T02:51:35.700297Z","steps":["trace[19305047] 'process raft request' (duration: 243.646501ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:51:35.700473Z","caller":"traceutil/trace.go:171","msg":"trace[1993787240] transaction","detail":"{read_only:false; response_revision:350; number_of_response:1; }","duration":"244.395155ms","start":"2026-04-03T02:51:35.456069Z","end":"2026-04-03T02:51:35.700464Z","steps":["trace[1993787240] 'process raft request' (duration: 243.659941ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:51:35.700951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.095964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:3706"} {"level":"info","ts":"2026-04-03T02:51:35.70094Z","caller":"traceutil/trace.go:171","msg":"trace[1490261497] transaction","detail":"{read_only:false; response_revision:356; number_of_response:1; }","duration":"241.592396ms","start":"2026-04-03T02:51:35.458981Z","end":"2026-04-03T02:51:35.700573Z","steps":["trace[1490261497] 'process raft request' (duration: 240.991448ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:51:35.701002Z","caller":"traceutil/trace.go:171","msg":"trace[570945914] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:357; }","duration":"243.182939ms","start":"2026-04-03T02:51:35.457808Z","end":"2026-04-03T02:51:35.700991Z","steps":["trace[570945914] 'agreement among raft nodes before linearized reading' (duration: 243.086704ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:51:35.700969Z","caller":"traceutil/trace.go:171","msg":"trace[1389722194] transaction","detail":"{read_only:false; response_revision:353; number_of_response:1; }","duration":"243.717014ms","start":"2026-04-03T02:51:35.457232Z","end":"2026-04-03T02:51:35.700949Z","steps":["trace[1389722194] 'process raft request' (duration: 242.586752ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:51:35.701162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.301862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-03T02:51:35.701214Z","caller":"traceutil/trace.go:171","msg":"trace[1651008639] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:357; }","duration":"206.360794ms","start":"2026-04-03T02:51:35.494839Z","end":"2026-04-03T02:51:35.7012Z","steps":["trace[1651008639] 'agreement among raft nodes before linearized reading' (duration: 206.281931ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:51:35.700963Z","caller":"traceutil/trace.go:171","msg":"trace[438854702] transaction","detail":"{read_only:false; response_revision:352; number_of_response:1; }","duration":"244.780673ms","start":"2026-04-03T02:51:35.45617Z","end":"2026-04-03T02:51:35.700951Z","steps":["trace[438854702] 'process raft request' (duration: 243.62374ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:51:35.701364Z","caller":"traceutil/trace.go:171","msg":"trace[1694906971] transaction","detail":"{read_only:false; response_revision:354; number_of_response:1; }","duration":"242.238325ms","start":"2026-04-03T02:51:35.45841Z","end":"2026-04-03T02:51:35.700648Z","steps":["trace[1694906971] 'process raft request' (duration: 241.4906ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:51:35.701407Z","caller":"traceutil/trace.go:171","msg":"trace[1545716892] transaction","detail":"{read_only:false; response_revision:357; number_of_response:1; }","duration":"240.738446ms","start":"2026-04-03T02:51:35.460657Z","end":"2026-04-03T02:51:35.701395Z","steps":["trace[1545716892] 'process raft request' (duration: 239.762922ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:51:35.701617Z","caller":"traceutil/trace.go:171","msg":"trace[1357773014] transaction","detail":"{read_only:false; response_revision:355; number_of_response:1; }","duration":"242.200043ms","start":"2026-04-03T02:51:35.458455Z","end":"2026-04-03T02:51:35.700655Z","steps":["trace[1357773014] 'process raft request' (duration: 241.479971ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:51:35.701708Z","caller":"traceutil/trace.go:171","msg":"trace[1872015822] transaction","detail":"{read_only:false; response_revision:351; number_of_response:1; }","duration":"244.769772ms","start":"2026-04-03T02:51:35.456089Z","end":"2026-04-03T02:51:35.700859Z","steps":["trace[1872015822] 'process raft request' (duration: 243.673901ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:51:38.11595Z","caller":"traceutil/trace.go:171","msg":"trace[1721236619] linearizableReadLoop","detail":"{readStateIndex:413; appliedIndex:412; }","duration":"126.314966ms","start":"2026-04-03T02:51:37.989609Z","end":"2026-04-03T02:51:38.115924Z","steps":["trace[1721236619] 'read index received' (duration: 126.189511ms)","trace[1721236619] 'applied index is now lower than readState.Index' (duration: 124.115µs)"],"step_count":2} {"level":"warn","ts":"2026-04-03T02:51:38.116093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.464802ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-03T02:51:38.116174Z","caller":"traceutil/trace.go:171","msg":"trace[1464362811] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:398; }","duration":"126.554856ms","start":"2026-04-03T02:51:37.989563Z","end":"2026-04-03T02:51:38.116118Z","steps":["trace[1464362811] 'agreement among raft nodes before linearized reading' (duration: 126.460122ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:51:38.369781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.853164ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-03T02:51:38.369907Z","caller":"traceutil/trace.go:171","msg":"trace[1067469382] transaction","detail":"{read_only:false; response_revision:399; number_of_response:1; }","duration":"252.920202ms","start":"2026-04-03T02:51:38.116958Z","end":"2026-04-03T02:51:38.369879Z","steps":["trace[1067469382] 'process raft request' (duration: 71.886469ms)","trace[1067469382] 'compare' (duration: 180.660717ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T02:51:38.456894Z","caller":"traceutil/trace.go:171","msg":"trace[2090518665] linearizableReadLoop","detail":"{readStateIndex:415; appliedIndex:414; }","duration":"169.069589ms","start":"2026-04-03T02:51:38.287797Z","end":"2026-04-03T02:51:38.456866Z","steps":["trace[2090518665] 'read index received' (duration: 168.780975ms)","trace[2090518665] 'applied index is now lower than readState.Index' (duration: 287.284µs)"],"step_count":2} {"level":"warn","ts":"2026-04-03T02:51:38.457257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.464966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-03T02:51:38.457272Z","caller":"traceutil/trace.go:171","msg":"trace[856134350] transaction","detail":"{read_only:false; response_revision:400; number_of_response:1; }","duration":"337.398833ms","start":"2026-04-03T02:51:38.119829Z","end":"2026-04-03T02:51:38.457228Z","steps":["trace[856134350] 'process raft request' (duration: 336.86671ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:51:38.457319Z","caller":"traceutil/trace.go:171","msg":"trace[1606001812] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:400; }","duration":"169.582642ms","start":"2026-04-03T02:51:38.287721Z","end":"2026-04-03T02:51:38.457303Z","steps":["trace[1606001812] 'agreement among raft nodes before linearized reading' (duration: 169.480218ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:51:38.458015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:51:38.119811Z","time spent":"338.097905ms","remote":"127.0.0.1:55166","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-04-03T02:51:39.903366Z","caller":"traceutil/trace.go:171","msg":"trace[1432412869] transaction","detail":"{read_only:false; response_revision:403; number_of_response:1; }","duration":"150.344966ms","start":"2026-04-03T02:51:39.752997Z","end":"2026-04-03T02:51:39.903342Z","steps":["trace[1432412869] 'process raft request' (duration: 150.20757ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:51:40.719592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.876709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6965"} {"level":"info","ts":"2026-04-03T02:51:40.719677Z","caller":"traceutil/trace.go:171","msg":"trace[1145795546] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:405; }","duration":"147.012507ms","start":"2026-04-03T02:51:40.572646Z","end":"2026-04-03T02:51:40.719659Z","steps":["trace[1145795546] 'range keys from in-memory index tree' (duration: 146.722883ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:51:40.719682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.762269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-03T02:51:40.719748Z","caller":"traceutil/trace.go:171","msg":"trace[2103197569] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:405; }","duration":"131.876462ms","start":"2026-04-03T02:51:40.587854Z","end":"2026-04-03T02:51:40.71973Z","steps":["trace[2103197569] 'range keys from in-memory index tree' (duration: 131.656373ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:51:40.720322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.089765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-03T02:51:40.720378Z","caller":"traceutil/trace.go:171","msg":"trace[884717573] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:405; }","duration":"159.167318ms","start":"2026-04-03T02:51:40.561197Z","end":"2026-04-03T02:51:40.720365Z","steps":["trace[884717573] 'range keys from in-memory index tree' (duration: 158.997809ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:51:41.286305Z","caller":"traceutil/trace.go:171","msg":"trace[1880372099] transaction","detail":"{read_only:false; response_revision:406; number_of_response:1; }","duration":"561.644416ms","start":"2026-04-03T02:51:40.724635Z","end":"2026-04-03T02:51:41.286279Z","steps":["trace[1880372099] 'process raft request' (duration: 561.498429ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:51:41.286461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:51:40.724613Z","time spent":"561.768491ms","remote":"127.0.0.1:55166","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-04-03T02:51:41.955039Z","caller":"traceutil/trace.go:171","msg":"trace[144972998] linearizableReadLoop","detail":"{readStateIndex:423; appliedIndex:421; }","duration":"460.22268ms","start":"2026-04-03T02:51:41.494795Z","end":"2026-04-03T02:51:41.955018Z","steps":["trace[144972998] 'read index received' (duration: 459.678196ms)","trace[144972998] 'applied index is now lower than readState.Index' (duration: 543.954µs)"],"step_count":2} {"level":"info","ts":"2026-04-03T02:51:41.955142Z","caller":"traceutil/trace.go:171","msg":"trace[616990824] transaction","detail":"{read_only:false; response_revision:408; number_of_response:1; }","duration":"1.226968885s","start":"2026-04-03T02:51:40.728163Z","end":"2026-04-03T02:51:41.955132Z","steps":["trace[616990824] 'process raft request' (duration: 1.226789518s)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:51:41.955232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:51:40.728156Z","time spent":"1.227009408s","remote":"127.0.0.1:55096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6740,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-03T02:51:41.955487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.694922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-03T02:51:41.955518Z","caller":"traceutil/trace.go:171","msg":"trace[1772395822] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:408; }","duration":"460.764555ms","start":"2026-04-03T02:51:41.494744Z","end":"2026-04-03T02:51:41.955509Z","steps":["trace[1772395822] 'agreement among raft nodes before linearized reading' (duration: 460.6588ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:51:41.955543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:51:41.494723Z","time spent":"460.813717ms","remote":"127.0.0.1:54920","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-04-03T02:51:41.9557Z","caller":"traceutil/trace.go:171","msg":"trace[377017030] transaction","detail":"{read_only:false; response_revision:407; number_of_response:1; }","duration":"1.230749982s","start":"2026-04-03T02:51:40.72493Z","end":"2026-04-03T02:51:41.95568Z","steps":["trace[377017030] 'process raft request' (duration: 1.229513968s)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:51:41.955813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:51:40.724906Z","time spent":"1.230847125s","remote":"127.0.0.1:55166","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-04-03T02:51:42.954202Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356764461211587476,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-03T02:51:43.245094Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.158480252s","expected-duration":"1s"} {"level":"info","ts":"2026-04-03T02:51:43.245633Z","caller":"traceutil/trace.go:171","msg":"trace[1103100800] linearizableReadLoop","detail":"{readStateIndex:424; appliedIndex:423; }","duration":"792.374256ms","start":"2026-04-03T02:51:42.453238Z","end":"2026-04-03T02:51:43.245612Z","steps":["trace[1103100800] 'read index received' (duration: 792.156987ms)","trace[1103100800] 'applied index is now lower than readState.Index' (duration: 216.349µs)"],"step_count":2} {"level":"info","ts":"2026-04-03T02:51:43.245686Z","caller":"traceutil/trace.go:171","msg":"trace[309243701] transaction","detail":"{read_only:false; response_revision:409; number_of_response:1; }","duration":"1.285835018s","start":"2026-04-03T02:51:41.959831Z","end":"2026-04-03T02:51:43.245666Z","steps":["trace[309243701] 'process raft request' (duration: 1.28564341s)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:51:43.245805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:51:41.959812Z","time spent":"1.285919402s","remote":"127.0.0.1:55166","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-04-03T02:51:43.245889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"792.624377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4326"} {"level":"info","ts":"2026-04-03T02:51:43.246145Z","caller":"traceutil/trace.go:171","msg":"trace[1668415887] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:409; }","duration":"792.781333ms","start":"2026-04-03T02:51:42.453189Z","end":"2026-04-03T02:51:43.245971Z","steps":["trace[1668415887] 'agreement among raft nodes before linearized reading' (duration: 792.512732ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:51:43.246322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:51:42.453168Z","time spent":"793.016713ms","remote":"127.0.0.1:55096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":4349,"request content":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" "} {"level":"warn","ts":"2026-04-03T02:51:43.284919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"789.730866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-03T02:51:43.284989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"786.990557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-03T02:51:43.285067Z","caller":"traceutil/trace.go:171","msg":"trace[1687756562] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:410; }","duration":"787.100452ms","start":"2026-04-03T02:51:42.497946Z","end":"2026-04-03T02:51:43.285047Z","steps":["trace[1687756562] 'agreement among raft nodes before linearized reading' (duration: 786.937136ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:51:43.285118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:51:42.497934Z","time spent":"787.176288ms","remote":"127.0.0.1:55166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-03T02:51:43.28499Z","caller":"traceutil/trace.go:171","msg":"trace[1560838349] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:410; }","duration":"789.848551ms","start":"2026-04-03T02:51:42.495126Z","end":"2026-04-03T02:51:43.284975Z","steps":["trace[1560838349] 'agreement among raft nodes before linearized reading' (duration: 789.691125ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:51:43.285356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:51:42.495105Z","time spent":"790.190586ms","remote":"127.0.0.1:54920","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-04-03T02:51:45.953938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.586427ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-03T02:51:45.954016Z","caller":"traceutil/trace.go:171","msg":"trace[1968752139] transaction","detail":"{read_only:false; response_revision:421; number_of_response:1; }","duration":"260.983555ms","start":"2026-04-03T02:51:45.693019Z","end":"2026-04-03T02:51:45.954002Z","steps":["trace[1968752139] 'process raft request' (duration: 48.275753ms)","trace[1968752139] 'compare' (duration: 212.249563ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T02:51:45.955972Z","caller":"traceutil/trace.go:171","msg":"trace[788871278] transaction","detail":"{read_only:false; response_revision:423; number_of_response:1; }","duration":"259.923761ms","start":"2026-04-03T02:51:45.696014Z","end":"2026-04-03T02:51:45.955937Z","steps":["trace[788871278] 'process raft request' (duration: 259.850649ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:51:45.956383Z","caller":"traceutil/trace.go:171","msg":"trace[1243634612] transaction","detail":"{read_only:false; response_revision:422; number_of_response:1; }","duration":"262.868124ms","start":"2026-04-03T02:51:45.693503Z","end":"2026-04-03T02:51:45.956371Z","steps":["trace[1243634612] 'process raft request' (duration: 262.242098ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:51:46.16638Z","caller":"traceutil/trace.go:171","msg":"trace[911931500] linearizableReadLoop","detail":"{readStateIndex:444; appliedIndex:439; }","duration":"194.391624ms","start":"2026-04-03T02:51:45.971965Z","end":"2026-04-03T02:51:46.166356Z","steps":["trace[911931500] 'read index received' (duration: 34.040339ms)","trace[911931500] 'applied index is now lower than readState.Index' (duration: 160.350025ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T02:51:46.166482Z","caller":"traceutil/trace.go:171","msg":"trace[1146166006] transaction","detail":"{read_only:false; response_revision:427; number_of_response:1; }","duration":"196.675079ms","start":"2026-04-03T02:51:45.969784Z","end":"2026-04-03T02:51:46.16646Z","steps":["trace[1146166006] 'process raft request' (duration: 196.494361ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:51:46.166512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.54005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-03T02:51:46.166544Z","caller":"traceutil/trace.go:171","msg":"trace[1763074885] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:428; }","duration":"194.625024ms","start":"2026-04-03T02:51:45.97191Z","end":"2026-04-03T02:51:46.166535Z","steps":["trace[1763074885] 'agreement among raft nodes before linearized reading' (duration: 194.53743ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:51:46.166746Z","caller":"traceutil/trace.go:171","msg":"trace[1921544197] transaction","detail":"{read_only:false; response_revision:428; number_of_response:1; }","duration":"196.642238ms","start":"2026-04-03T02:51:45.970094Z","end":"2026-04-03T02:51:46.166736Z","steps":["trace[1921544197] 'process raft request' (duration: 196.219541ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:51:46.166902Z","caller":"traceutil/trace.go:171","msg":"trace[328382682] transaction","detail":"{read_only:false; response_revision:425; number_of_response:1; }","duration":"196.860827ms","start":"2026-04-03T02:51:45.969449Z","end":"2026-04-03T02:51:46.16631Z","steps":["trace[328382682] 'process raft request' (duration: 186.334532ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:51:46.166964Z","caller":"traceutil/trace.go:171","msg":"trace[30010748] transaction","detail":"{read_only:false; response_revision:426; number_of_response:1; }","duration":"197.305755ms","start":"2026-04-03T02:51:45.969647Z","end":"2026-04-03T02:51:46.166953Z","steps":["trace[30010748] 'process raft request' (duration: 196.552764ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:51:46.356757Z","caller":"traceutil/trace.go:171","msg":"trace[1880908223] linearizableReadLoop","detail":"{readStateIndex:448; appliedIndex:447; }","duration":"119.697976ms","start":"2026-04-03T02:51:46.23703Z","end":"2026-04-03T02:51:46.356728Z","steps":["trace[1880908223] 'read index received' (duration: 43.299989ms)","trace[1880908223] 'applied index is now lower than readState.Index' (duration: 76.397007ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T02:51:46.35692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.864622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/kube-system/coredns-67659f764b\" ","response":"range_response_count:1 size:3855"} {"level":"info","ts":"2026-04-03T02:51:46.356974Z","caller":"traceutil/trace.go:171","msg":"trace[1686993618] range","detail":"{range_begin:/registry/replicasets/kube-system/coredns-67659f764b; range_end:; response_count:1; response_revision:432; }","duration":"119.976567ms","start":"2026-04-03T02:51:46.236987Z","end":"2026-04-03T02:51:46.356963Z","steps":["trace[1686993618] 'agreement among raft nodes before linearized reading' (duration: 119.848002ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:51:46.35707Z","caller":"traceutil/trace.go:171","msg":"trace[1978167516] transaction","detail":"{read_only:false; response_revision:432; number_of_response:1; }","duration":"154.731023ms","start":"2026-04-03T02:51:46.202295Z","end":"2026-04-03T02:51:46.357026Z","steps":["trace[1978167516] 'process raft request' (duration: 78.085766ms)","trace[1978167516] 'compare' (duration: 76.225929ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T02:51:46.531802Z","caller":"traceutil/trace.go:171","msg":"trace[1419762757] transaction","detail":"{read_only:false; response_revision:437; number_of_response:1; }","duration":"142.371732ms","start":"2026-04-03T02:51:46.389411Z","end":"2026-04-03T02:51:46.531783Z","steps":["trace[1419762757] 'process raft request' (duration: 142.31297ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:51:46.531885Z","caller":"traceutil/trace.go:171","msg":"trace[438393292] transaction","detail":"{read_only:false; response_revision:436; number_of_response:1; }","duration":"145.725081ms","start":"2026-04-03T02:51:46.386107Z","end":"2026-04-03T02:51:46.531832Z","steps":["trace[438393292] 'process raft request' (duration: 136.833493ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:51:46.786645Z","caller":"traceutil/trace.go:171","msg":"trace[405414969] transaction","detail":"{read_only:false; response_revision:448; number_of_response:1; }","duration":"153.536052ms","start":"2026-04-03T02:51:46.633084Z","end":"2026-04-03T02:51:46.78662Z","steps":["trace[405414969] 'process raft request' (duration: 153.410217ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:51:46.949675Z","caller":"traceutil/trace.go:171","msg":"trace[1943716201] transaction","detail":"{read_only:false; response_revision:449; number_of_response:1; }","duration":"154.629537ms","start":"2026-04-03T02:51:46.795025Z","end":"2026-04-03T02:51:46.949655Z","steps":["trace[1943716201] 'process raft request' (duration: 88.501885ms)","trace[1943716201] 'compare' (duration: 66.025328ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T02:51:50.332743Z","caller":"traceutil/trace.go:171","msg":"trace[1760360464] transaction","detail":"{read_only:false; response_revision:459; number_of_response:1; }","duration":"117.606261ms","start":"2026-04-03T02:51:50.215095Z","end":"2026-04-03T02:51:50.332701Z","steps":["trace[1760360464] 'process raft request' (duration: 117.468376ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:51:50.454472Z","caller":"traceutil/trace.go:171","msg":"trace[70661825] transaction","detail":"{read_only:false; response_revision:460; number_of_response:1; }","duration":"107.656275ms","start":"2026-04-03T02:51:50.346793Z","end":"2026-04-03T02:51:50.454449Z","steps":["trace[70661825] 'process raft request' (duration: 106.471348ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:51:57.540616Z","caller":"traceutil/trace.go:171","msg":"trace[1066480709] linearizableReadLoop","detail":"{readStateIndex:492; appliedIndex:491; }","duration":"149.570029ms","start":"2026-04-03T02:51:57.391025Z","end":"2026-04-03T02:51:57.540595Z","steps":["trace[1066480709] 'read index received' (duration: 149.366692ms)","trace[1066480709] 'applied index is now lower than readState.Index' (duration: 202.177µs)"],"step_count":2} {"level":"info","ts":"2026-04-03T02:51:57.540693Z","caller":"traceutil/trace.go:171","msg":"trace[1741688429] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"157.533376ms","start":"2026-04-03T02:51:57.383137Z","end":"2026-04-03T02:51:57.54067Z","steps":["trace[1741688429] 'process raft request' (duration: 157.296358ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:51:57.540802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.753966ms","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":"warn","ts":"2026-04-03T02:51:57.540808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.519899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-03T02:51:57.540847Z","caller":"traceutil/trace.go:171","msg":"trace[117671440] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:3; response_revision:474; }","duration":"149.832628ms","start":"2026-04-03T02:51:57.391001Z","end":"2026-04-03T02:51:57.540834Z","steps":["trace[117671440] 'agreement among raft nodes before linearized reading' (duration: 149.707733ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:51:57.54087Z","caller":"traceutil/trace.go:171","msg":"trace[108711211] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:474; }","duration":"133.629514ms","start":"2026-04-03T02:51:57.407223Z","end":"2026-04-03T02:51:57.540853Z","steps":["trace[108711211] 'agreement among raft nodes before linearized reading' (duration: 133.516199ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:51:57.698355Z","caller":"traceutil/trace.go:171","msg":"trace[1102057168] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"152.27816ms","start":"2026-04-03T02:51:57.546026Z","end":"2026-04-03T02:51:57.698304Z","steps":["trace[1102057168] 'process raft request' (duration: 101.17782ms)","trace[1102057168] 'compare' (duration: 50.798269ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T02:52:00.993022Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356764461211587685,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-03T02:52:01.23132Z","caller":"traceutil/trace.go:171","msg":"trace[1257420913] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"769.291864ms","start":"2026-04-03T02:52:00.461998Z","end":"2026-04-03T02:52:01.231289Z","steps":["trace[1257420913] 'process raft request' (duration: 769.122318ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:52:01.231486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:52:00.461967Z","time spent":"769.445141ms","remote":"127.0.0.1:55166","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-04-03T02:52:01.762454Z","caller":"traceutil/trace.go:171","msg":"trace[312692086] linearizableReadLoop","detail":"{readStateIndex:536; appliedIndex:534; }","duration":"1.269644934s","start":"2026-04-03T02:52:00.492775Z","end":"2026-04-03T02:52:01.76242Z","steps":["trace[312692086] 'read index received' (duration: 738.303507ms)","trace[312692086] 'applied index is now lower than readState.Index' (duration: 531.339767ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T02:52:01.762831Z","caller":"traceutil/trace.go:171","msg":"trace[1933083937] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"1.183356502s","start":"2026-04-03T02:52:00.579432Z","end":"2026-04-03T02:52:01.762788Z","steps":["trace[1933083937] 'process raft request' (duration: 1.181791406s)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:52:01.762968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:52:00.579408Z","time spent":"1.183493257s","remote":"127.0.0.1:55166","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-04-03T02:52:01.762858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.270071819s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-03T02:52:01.763084Z","caller":"traceutil/trace.go:171","msg":"trace[569338210] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:517; }","duration":"1.270337908s","start":"2026-04-03T02:52:00.492733Z","end":"2026-04-03T02:52:01.763071Z","steps":["trace[569338210] 'agreement among raft nodes before linearized reading' (duration: 1.270075288s)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:52:01.763116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:52:00.492714Z","time spent":"1.27039304s","remote":"127.0.0.1:54920","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-04-03T02:52:01.763316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"584.923721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-03T02:52:01.763376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.585039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-03T02:52:01.763481Z","caller":"traceutil/trace.go:171","msg":"trace[1187141688] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:517; }","duration":"210.692383ms","start":"2026-04-03T02:52:01.552743Z","end":"2026-04-03T02:52:01.763435Z","steps":["trace[1187141688] 'agreement among raft nodes before linearized reading' (duration: 210.573989ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:52:01.763331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.593684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-03T02:52:01.763626Z","caller":"traceutil/trace.go:171","msg":"trace[1151223020] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:517; }","duration":"163.910846ms","start":"2026-04-03T02:52:01.599703Z","end":"2026-04-03T02:52:01.763614Z","steps":["trace[1151223020] 'agreement among raft nodes before linearized reading' (duration: 163.603435ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:52:01.763497Z","caller":"traceutil/trace.go:171","msg":"trace[855209452] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:517; }","duration":"585.049326ms","start":"2026-04-03T02:52:01.178344Z","end":"2026-04-03T02:52:01.763393Z","steps":["trace[855209452] 'agreement among raft nodes before linearized reading' (duration: 584.935962ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:52:01.763777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:52:01.178327Z","time spent":"585.43574ms","remote":"127.0.0.1:54984","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" "} {"level":"info","ts":"2026-04-03T02:52:02.064727Z","caller":"traceutil/trace.go:171","msg":"trace[677150647] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"107.832254ms","start":"2026-04-03T02:52:01.95686Z","end":"2026-04-03T02:52:02.064692Z","steps":["trace[677150647] 'process raft request' (duration: 107.73217ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:52:02.169479Z","caller":"traceutil/trace.go:171","msg":"trace[195620344] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"208.671431ms","start":"2026-04-03T02:52:01.960782Z","end":"2026-04-03T02:52:02.169453Z","steps":["trace[195620344] 'process raft request' (duration: 208.441233ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:52:02.169501Z","caller":"traceutil/trace.go:171","msg":"trace[2130093584] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"200.645272ms","start":"2026-04-03T02:52:01.968829Z","end":"2026-04-03T02:52:02.169474Z","steps":["trace[2130093584] 'process raft request' (duration: 200.497747ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:52:02.16977Z","caller":"traceutil/trace.go:171","msg":"trace[1719912015] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"198.766123ms","start":"2026-04-03T02:52:01.970966Z","end":"2026-04-03T02:52:02.169732Z","steps":["trace[1719912015] 'process raft request' (duration: 198.3981ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:52:02.169816Z","caller":"traceutil/trace.go:171","msg":"trace[1105664270] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"198.831385ms","start":"2026-04-03T02:52:01.970967Z","end":"2026-04-03T02:52:02.169798Z","steps":["trace[1105664270] 'process raft request' (duration: 198.436292ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:52:02.170478Z","caller":"traceutil/trace.go:171","msg":"trace[1434373835] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"193.422821ms","start":"2026-04-03T02:52:01.97704Z","end":"2026-04-03T02:52:02.170463Z","steps":["trace[1434373835] 'process raft request' (duration: 192.690414ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:52:02.170635Z","caller":"traceutil/trace.go:171","msg":"trace[446891700] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"193.466963ms","start":"2026-04-03T02:52:01.97713Z","end":"2026-04-03T02:52:02.170597Z","steps":["trace[446891700] 'process raft request' (duration: 193.292767ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:52:02.170755Z","caller":"traceutil/trace.go:171","msg":"trace[2127018407] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"193.431781ms","start":"2026-04-03T02:52:01.977308Z","end":"2026-04-03T02:52:02.17074Z","steps":["trace[2127018407] 'process raft request' (duration: 193.226834ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:52:02.17081Z","caller":"traceutil/trace.go:171","msg":"trace[116347837] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"165.680519ms","start":"2026-04-03T02:52:02.005111Z","end":"2026-04-03T02:52:02.170791Z","steps":["trace[116347837] 'process raft request' (duration: 165.613947ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:52:02.170852Z","caller":"traceutil/trace.go:171","msg":"trace[1149354797] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"181.76286ms","start":"2026-04-03T02:52:01.989079Z","end":"2026-04-03T02:52:02.170842Z","steps":["trace[1149354797] 'process raft request' (duration: 181.517021ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:52:02.170857Z","caller":"traceutil/trace.go:171","msg":"trace[1853851339] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"181.693337ms","start":"2026-04-03T02:52:01.989156Z","end":"2026-04-03T02:52:02.170849Z","steps":["trace[1853851339] 'process raft request' (duration: 181.50666ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:52:02.627456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.326213ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-03T02:52:02.62793Z","caller":"traceutil/trace.go:171","msg":"trace[592810176] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"555.5325ms","start":"2026-04-03T02:52:02.072377Z","end":"2026-04-03T02:52:02.62791Z","steps":["trace[592810176] 'process raft request' (duration: 555.186848ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:52:02.628581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:52:02.072366Z","time spent":"556.032448ms","remote":"127.0.0.1:54948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6888,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-03T02:52:02.628884Z","caller":"traceutil/trace.go:171","msg":"trace[530296803] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"559.325917ms","start":"2026-04-03T02:52:02.069322Z","end":"2026-04-03T02:52:02.628647Z","steps":["trace[530296803] 'process raft request' (duration: 327.694167ms)","trace[530296803] 'compare' (duration: 230.163397ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T02:52:02.629025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:52:02.069307Z","time spent":"559.652398ms","remote":"127.0.0.1:55438","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":995,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-03T02:52:02.62901Z","caller":"traceutil/trace.go:171","msg":"trace[989344717] transaction","detail":"{read_only:false; number_of_response:1; response_revision:533; }","duration":"556.447303ms","start":"2026-04-03T02:52:02.072522Z","end":"2026-04-03T02:52:02.628969Z","steps":["trace[989344717] 'process raft request' (duration: 555.132376ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:52:02.629345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:52:02.072491Z","time spent":"556.739403ms","remote":"127.0.0.1:54948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":102,"response count":0,"response size":6937,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-03T02:52:02.629723Z","caller":"traceutil/trace.go:171","msg":"trace[192579355] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"448.848722ms","start":"2026-04-03T02:52:02.180845Z","end":"2026-04-03T02:52:02.629694Z","steps":["trace[192579355] 'process raft request' (duration: 448.117736ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:52:02.629818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:52:02.180824Z","time spent":"448.951236ms","remote":"127.0.0.1:55438","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1004,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-03T02:52:02.630446Z","caller":"traceutil/trace.go:171","msg":"trace[959935495] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"449.458864ms","start":"2026-04-03T02:52:02.180939Z","end":"2026-04-03T02:52:02.630398Z","steps":["trace[959935495] 'process raft request' (duration: 448.185858ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:52:02.63052Z","caller":"traceutil/trace.go:171","msg":"trace[1949431290] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"445.930829ms","start":"2026-04-03T02:52:02.184566Z","end":"2026-04-03T02:52:02.630497Z","steps":["trace[1949431290] 'process raft request' (duration: 444.731736ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:52:02.630547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:52:02.180929Z","time spent":"449.574618ms","remote":"127.0.0.1:55438","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":980,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-03T02:52:02.630634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:52:02.184558Z","time spent":"446.021932ms","remote":"127.0.0.1:55438","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-03T02:52:02.630398Z","caller":"traceutil/trace.go:171","msg":"trace[938664059] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"497.11152ms","start":"2026-04-03T02:52:02.13317Z","end":"2026-04-03T02:52:02.630282Z","steps":["trace[938664059] 'process raft request' (duration: 494.558038ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:52:02.630736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:52:02.133145Z","time spent":"497.553305ms","remote":"127.0.0.1:54948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-03T02:52:02.631788Z","caller":"traceutil/trace.go:171","msg":"trace[662991904] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"443.084616ms","start":"2026-04-03T02:52:02.188662Z","end":"2026-04-03T02:52:02.631747Z","steps":["trace[662991904] 'process raft request' (duration: 440.710071ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:52:02.631862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:52:02.188651Z","time spent":"443.178219ms","remote":"127.0.0.1:55438","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-03T02:52:02.633184Z","caller":"traceutil/trace.go:171","msg":"trace[679130996] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"444.061591ms","start":"2026-04-03T02:52:02.189096Z","end":"2026-04-03T02:52:02.633158Z","steps":["trace[679130996] 'process raft request' (duration: 440.778093ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:52:02.633257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:52:02.189063Z","time spent":"444.151155ms","remote":"127.0.0.1:54948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28739,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-03T02:52:02.634531Z","caller":"traceutil/trace.go:171","msg":"trace[1447678461] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"445.321336ms","start":"2026-04-03T02:52:02.189193Z","end":"2026-04-03T02:52:02.634515Z","steps":["trace[1447678461] 'process raft request' (duration: 443.278413ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:52:02.634854Z","caller":"traceutil/trace.go:171","msg":"trace[100466520] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"445.70967ms","start":"2026-04-03T02:52:02.189129Z","end":"2026-04-03T02:52:02.634839Z","steps":["trace[100466520] 'process raft request' (duration: 442.762965ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:52:02.634911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:52:02.189114Z","time spent":"445.768102ms","remote":"127.0.0.1:54948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30393,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-03T02:52:02.634882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:52:02.189187Z","time spent":"445.385199ms","remote":"127.0.0.1:54948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10063,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-03T02:52:02.635202Z","caller":"traceutil/trace.go:171","msg":"trace[56810878] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"443.261833ms","start":"2026-04-03T02:52:02.191919Z","end":"2026-04-03T02:52:02.635181Z","steps":["trace[56810878] 'process raft request' (duration: 441.254231ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:52:02.635297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:52:02.19191Z","time spent":"443.337295ms","remote":"127.0.0.1:54948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30374,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-03T02:52:05.740103Z","caller":"traceutil/trace.go:171","msg":"trace[1271416504] linearizableReadLoop","detail":"{readStateIndex:628; appliedIndex:627; }","duration":"115.692257ms","start":"2026-04-03T02:52:05.624341Z","end":"2026-04-03T02:52:05.740033Z","steps":["trace[1271416504] 'read index received' (duration: 108.212967ms)","trace[1271416504] 'applied index is now lower than readState.Index' (duration: 7.47841ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T02:52:05.740146Z","caller":"traceutil/trace.go:171","msg":"trace[1384812891] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"127.414626ms","start":"2026-04-03T02:52:05.612681Z","end":"2026-04-03T02:52:05.740095Z","steps":["trace[1384812891] 'process raft request' (duration: 120.502255ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:52:05.74036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.986617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-03T02:52:05.740436Z","caller":"traceutil/trace.go:171","msg":"trace[1600442082] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:604; }","duration":"116.146932ms","start":"2026-04-03T02:52:05.624268Z","end":"2026-04-03T02:52:05.740415Z","steps":["trace[1600442082] 'agreement among raft nodes before linearized reading' (duration: 115.983787ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:52:05.740622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.220006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-03T02:52:05.740765Z","caller":"traceutil/trace.go:171","msg":"trace[180933994] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:604; }","duration":"116.436973ms","start":"2026-04-03T02:52:05.624298Z","end":"2026-04-03T02:52:05.740735Z","steps":["trace[180933994] 'agreement among raft nodes before linearized reading' (duration: 115.822911ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:52:05.946278Z","caller":"traceutil/trace.go:171","msg":"trace[881294055] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"146.963967ms","start":"2026-04-03T02:52:05.799278Z","end":"2026-04-03T02:52:05.946242Z","steps":["trace[881294055] 'process raft request' (duration: 69.179313ms)","trace[881294055] 'compare' (duration: 77.548626ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T02:52:05.946311Z","caller":"traceutil/trace.go:171","msg":"trace[290286382] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"146.752012ms","start":"2026-04-03T02:52:05.799541Z","end":"2026-04-03T02:52:05.946293Z","steps":["trace[290286382] 'process raft request' (duration: 146.661878ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:52:05.946353Z","caller":"traceutil/trace.go:171","msg":"trace[1376000294] linearizableReadLoop","detail":"{readStateIndex:629; appliedIndex:628; }","duration":"146.812663ms","start":"2026-04-03T02:52:05.799499Z","end":"2026-04-03T02:52:05.946311Z","steps":["trace[1376000294] 'read index received' (duration: 68.971117ms)","trace[1376000294] 'applied index is now lower than readState.Index' (duration: 77.837566ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T02:52:05.946528Z","caller":"traceutil/trace.go:171","msg":"trace[326060708] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"145.538109ms","start":"2026-04-03T02:52:05.800979Z","end":"2026-04-03T02:52:05.946518Z","steps":["trace[326060708] 'process raft request' (duration: 145.27395ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:52:05.946599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.072472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-03T02:52:05.946648Z","caller":"traceutil/trace.go:171","msg":"trace[2003692467] range","detail":"{range_begin:/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:607; }","duration":"147.163636ms","start":"2026-04-03T02:52:05.799466Z","end":"2026-04-03T02:52:05.94663Z","steps":["trace[2003692467] 'agreement among raft nodes before linearized reading' (duration: 147.029102ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:52:09.5049Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356764461211587937,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-03T02:52:09.53282Z","caller":"traceutil/trace.go:171","msg":"trace[301943473] linearizableReadLoop","detail":"{readStateIndex:652; appliedIndex:651; }","duration":"528.341923ms","start":"2026-04-03T02:52:09.004392Z","end":"2026-04-03T02:52:09.532734Z","steps":["trace[301943473] 'read index received' (duration: 524.69513ms)","trace[301943473] 'applied index is now lower than readState.Index' (duration: 3.645383ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T02:52:09.535504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"531.099947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-p66wd\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-04-03T02:52:09.535614Z","caller":"traceutil/trace.go:171","msg":"trace[704913956] range","detail":"{range_begin:/registry/pods/kube-system/cilium-p66wd; range_end:; response_count:1; response_revision:627; }","duration":"531.258752ms","start":"2026-04-03T02:52:09.004319Z","end":"2026-04-03T02:52:09.535578Z","steps":["trace[704913956] 'agreement among raft nodes before linearized reading' (duration: 531.019284ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:52:09.535664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:52:09.004298Z","time spent":"531.354394ms","remote":"127.0.0.1:55096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-p66wd\" "} {"level":"info","ts":"2026-04-03T02:52:09.535992Z","caller":"traceutil/trace.go:171","msg":"trace[274621106] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"765.920401ms","start":"2026-04-03T02:52:08.770015Z","end":"2026-04-03T02:52:09.535935Z","steps":["trace[274621106] 'process raft request' (duration: 759.94581ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:52:09.536356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:52:08.76999Z","time spent":"766.187929ms","remote":"127.0.0.1:54984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036289,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-03T02:52:09.536145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.410717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-03T02:52:09.539971Z","caller":"traceutil/trace.go:171","msg":"trace[1582576409] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:627; }","duration":"262.263487ms","start":"2026-04-03T02:52:09.277686Z","end":"2026-04-03T02:52:09.53995Z","steps":["trace[1582576409] 'agreement among raft nodes before linearized reading' (duration: 258.256642ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:52:11.934276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.559106376s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-03T02:52:11.934513Z","caller":"traceutil/trace.go:171","msg":"trace[657668608] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"2.338312962s","start":"2026-04-03T02:52:09.596157Z","end":"2026-04-03T02:52:11.93447Z","steps":["trace[657668608] 'process raft request' (duration: 778.971187ms)","trace[657668608] 'compare' (duration: 1.5586013s)"],"step_count":2} {"level":"info","ts":"2026-04-03T02:52:11.934562Z","caller":"traceutil/trace.go:171","msg":"trace[709794027] linearizableReadLoop","detail":"{readStateIndex:655; appliedIndex:654; }","duration":"1.972618541s","start":"2026-04-03T02:52:09.961909Z","end":"2026-04-03T02:52:11.934527Z","steps":["trace[709794027] 'read index received' (duration: 413.103782ms)","trace[709794027] 'applied index is now lower than readState.Index' (duration: 1.559512499s)"],"step_count":2} {"level":"warn","ts":"2026-04-03T02:52:11.934665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:52:09.596132Z","time spent":"2.338462897s","remote":"127.0.0.1:55422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-03T02:52:11.934837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.972910981s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-03T02:52:11.934882Z","caller":"traceutil/trace.go:171","msg":"trace[1871198494] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:631; }","duration":"1.973003383s","start":"2026-04-03T02:52:09.961866Z","end":"2026-04-03T02:52:11.934869Z","steps":["trace[1871198494] 'agreement among raft nodes before linearized reading' (duration: 1.972810267s)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:52:11.934917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:52:09.961844Z","time spent":"1.973064565s","remote":"127.0.0.1:55166","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-04-03T02:52:11.934977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.972653502s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-03T02:52:11.935033Z","caller":"traceutil/trace.go:171","msg":"trace[49965666] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:631; }","duration":"1.972745275s","start":"2026-04-03T02:52:09.962272Z","end":"2026-04-03T02:52:11.935017Z","steps":["trace[49965666] 'agreement among raft nodes before linearized reading' (duration: 1.97260445s)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:52:11.935076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:52:09.962211Z","time spent":"1.972857648s","remote":"127.0.0.1:55166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-03T02:52:11.935199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.972722243s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-03T02:52:11.935221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.362285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-03T02:52:11.935288Z","caller":"traceutil/trace.go:171","msg":"trace[1426972021] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:631; }","duration":"350.450278ms","start":"2026-04-03T02:52:11.584824Z","end":"2026-04-03T02:52:11.935274Z","steps":["trace[1426972021] 'agreement among raft nodes before linearized reading' (duration: 350.274652ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:52:11.935351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:52:11.584804Z","time spent":"350.52819ms","remote":"127.0.0.1:55166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-03T02:52:11.935232Z","caller":"traceutil/trace.go:171","msg":"trace[1667277702] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:631; }","duration":"1.972772307s","start":"2026-04-03T02:52:09.96245Z","end":"2026-04-03T02:52:11.935222Z","steps":["trace[1667277702] 'agreement among raft nodes before linearized reading' (duration: 1.972694954s)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:52:11.935497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:52:09.962438Z","time spent":"1.973046515s","remote":"127.0.0.1:55166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-03T02:52:11.935069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.439991665s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-03T02:52:11.935617Z","caller":"traceutil/trace.go:171","msg":"trace[1000271674] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:631; }","duration":"1.440558544s","start":"2026-04-03T02:52:10.495028Z","end":"2026-04-03T02:52:11.935587Z","steps":["trace[1000271674] 'agreement among raft nodes before linearized reading' (duration: 1.439989705s)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:52:11.935721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:52:10.495006Z","time spent":"1.440685628s","remote":"127.0.0.1:54920","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-04-03T02:52:12.578271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.639166ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-03T02:52:12.578387Z","caller":"traceutil/trace.go:171","msg":"trace[979461530] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"561.103331ms","start":"2026-04-03T02:52:12.017264Z","end":"2026-04-03T02:52:12.578368Z","steps":["trace[979461530] 'process raft request' (duration: 259.30424ms)","trace[979461530] 'compare' (duration: 301.489492ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T02:52:12.578448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:52:12.017232Z","time spent":"561.194075ms","remote":"127.0.0.1:54976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":765,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-03T02:52:12.803831Z","caller":"traceutil/trace.go:171","msg":"trace[1909634705] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"772.083559ms","start":"2026-04-03T02:52:12.031722Z","end":"2026-04-03T02:52:12.803806Z","steps":["trace[1909634705] 'process raft request' (duration: 748.992047ms)","trace[1909634705] 'compare' (duration: 23.004669ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T02:52:12.803966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:52:12.031704Z","time spent":"772.203103ms","remote":"127.0.0.1:55096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17232,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-03T02:52:13.136467Z","caller":"traceutil/trace.go:171","msg":"trace[1691384063] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"725.732486ms","start":"2026-04-03T02:52:12.410709Z","end":"2026-04-03T02:52:13.136442Z","steps":["trace[1691384063] 'process raft request' (duration: 725.016693ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:52:13.136753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:52:12.410693Z","time spent":"725.925094ms","remote":"127.0.0.1:54984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036317,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-03T02:52:13.354922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.190884ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-03T02:52:13.35517Z","caller":"traceutil/trace.go:171","msg":"trace[1153998683] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"161.483834ms","start":"2026-04-03T02:52:13.193598Z","end":"2026-04-03T02:52:13.355081Z","steps":["trace[1153998683] 'process raft request' (duration: 59.085213ms)","trace[1153998683] 'compare' (duration: 101.999217ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T02:52:13.48576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.750577ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-03T02:52:13.485841Z","caller":"traceutil/trace.go:171","msg":"trace[295291592] linearizableReadLoop","detail":"{readStateIndex:665; appliedIndex:664; }","duration":"126.923403ms","start":"2026-04-03T02:52:13.358904Z","end":"2026-04-03T02:52:13.485828Z","steps":["trace[295291592] 'read index received' (duration: 42.881µs)","trace[295291592] 'applied index is now lower than readState.Index' (duration: 126.879542ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T02:52:13.485904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.004865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-04-03T02:52:13.485927Z","caller":"traceutil/trace.go:171","msg":"trace[1224494308] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:639; }","duration":"127.068206ms","start":"2026-04-03T02:52:13.358852Z","end":"2026-04-03T02:52:13.48592Z","steps":["trace[1224494308] 'agreement among raft nodes before linearized reading' (duration: 127.007975ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:52:13.864301Z","caller":"traceutil/trace.go:171","msg":"trace[758742925] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"360.567507ms","start":"2026-04-03T02:52:13.503711Z","end":"2026-04-03T02:52:13.864279Z","steps":["trace[758742925] 'process raft request' (duration: 360.389151ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:52:13.864433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:52:13.5037Z","time spent":"360.67467ms","remote":"127.0.0.1:55096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2735,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-03T02:52:13.977738Z","caller":"traceutil/trace.go:171","msg":"trace[1173855487] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"109.4029ms","start":"2026-04-03T02:52:13.868308Z","end":"2026-04-03T02:52:13.977711Z","steps":["trace[1173855487] 'process raft request' (duration: 35.634236ms)","trace[1173855487] 'compare' (duration: 73.65662ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T02:52:14.020153Z","caller":"traceutil/trace.go:171","msg":"trace[1732134562] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"151.302377ms","start":"2026-04-03T02:52:13.868826Z","end":"2026-04-03T02:52:14.020129Z","steps":["trace[1732134562] 'process raft request' (duration: 151.075289ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:52:14.020352Z","caller":"traceutil/trace.go:171","msg":"trace[406609983] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"151.510244ms","start":"2026-04-03T02:52:13.868833Z","end":"2026-04-03T02:52:14.020344Z","steps":["trace[406609983] 'process raft request' (duration: 151.192123ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:52:14.020601Z","caller":"traceutil/trace.go:171","msg":"trace[376371783] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"149.303321ms","start":"2026-04-03T02:52:13.871268Z","end":"2026-04-03T02:52:14.020571Z","steps":["trace[376371783] 'process raft request' (duration: 148.807524ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:52:14.221389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.955204ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-03T02:52:14.221865Z","caller":"traceutil/trace.go:171","msg":"trace[280395526] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"195.302781ms","start":"2026-04-03T02:52:14.026544Z","end":"2026-04-03T02:52:14.221847Z","steps":["trace[280395526] 'process raft request' (duration: 195.200428ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:52:14.221877Z","caller":"traceutil/trace.go:171","msg":"trace[1834585978] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"195.350021ms","start":"2026-04-03T02:52:14.02647Z","end":"2026-04-03T02:52:14.22182Z","steps":["trace[1834585978] 'process raft request' (duration: 66.86811ms)","trace[1834585978] 'compare' (duration: 127.450097ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T02:52:14.222048Z","caller":"traceutil/trace.go:171","msg":"trace[1418756465] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"195.380903ms","start":"2026-04-03T02:52:14.026619Z","end":"2026-04-03T02:52:14.222Z","steps":["trace[1418756465] 'process raft request' (duration: 195.190527ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:52:16.539748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.257744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-03T02:52:16.539837Z","caller":"traceutil/trace.go:171","msg":"trace[428402707] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:0; response_revision:674; }","duration":"298.40309ms","start":"2026-04-03T02:52:16.241414Z","end":"2026-04-03T02:52:16.539817Z","steps":["trace[428402707] 'range keys from in-memory index tree' (duration: 298.178053ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:52:16.539928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.345886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-03T02:52:16.53993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.378348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-03T02:52:16.539985Z","caller":"traceutil/trace.go:171","msg":"trace[1797636269] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:674; }","duration":"313.44949ms","start":"2026-04-03T02:52:16.226521Z","end":"2026-04-03T02:52:16.539971Z","steps":["trace[1797636269] 'range keys from in-memory index tree' (duration: 313.187202ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:52:16.54001Z","caller":"traceutil/trace.go:171","msg":"trace[698838447] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:674; }","duration":"313.505612ms","start":"2026-04-03T02:52:16.226486Z","end":"2026-04-03T02:52:16.539992Z","steps":["trace[698838447] 'range keys from in-memory index tree' (duration: 313.139531ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:52:16.540015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:52:16.2265Z","time spent":"313.508942ms","remote":"127.0.0.1:55166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-03T02:52:16.54005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:52:16.226467Z","time spent":"313.574094ms","remote":"127.0.0.1:55166","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-04-03T02:52:16.540198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.553104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-03T02:52:16.540359Z","caller":"traceutil/trace.go:171","msg":"trace[1360851889] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:674; }","duration":"313.690758ms","start":"2026-04-03T02:52:16.226618Z","end":"2026-04-03T02:52:16.540309Z","steps":["trace[1360851889] 'range keys from in-memory index tree' (duration: 313.411919ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:52:16.540411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:52:16.226548Z","time spent":"313.851454ms","remote":"127.0.0.1:55166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-03T02:52:18.272762Z","caller":"traceutil/trace.go:171","msg":"trace[1883696640] transaction","detail":"{read_only:false; response_revision:705; number_of_response:1; }","duration":"224.747097ms","start":"2026-04-03T02:52:18.047992Z","end":"2026-04-03T02:52:18.272739Z","steps":["trace[1883696640] 'process raft request' (duration: 224.622333ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:52:18.281595Z","caller":"traceutil/trace.go:171","msg":"trace[1761621376] transaction","detail":"{read_only:false; response_revision:706; number_of_response:1; }","duration":"232.211513ms","start":"2026-04-03T02:52:18.049367Z","end":"2026-04-03T02:52:18.281579Z","steps":["trace[1761621376] 'process raft request' (duration: 232.079609ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:52:18.281923Z","caller":"traceutil/trace.go:171","msg":"trace[1632793289] transaction","detail":"{read_only:false; response_revision:707; number_of_response:1; }","duration":"228.221607ms","start":"2026-04-03T02:52:18.053676Z","end":"2026-04-03T02:52:18.281898Z","steps":["trace[1632793289] 'process raft request' (duration: 227.866196ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:52:18.281917Z","caller":"traceutil/trace.go:171","msg":"trace[1152084025] linearizableReadLoop","detail":"{readStateIndex:733; appliedIndex:732; }","duration":"228.723883ms","start":"2026-04-03T02:52:18.053171Z","end":"2026-04-03T02:52:18.281894Z","steps":["trace[1152084025] 'read index received' (duration: 219.702878ms)","trace[1152084025] 'applied index is now lower than readState.Index' (duration: 9.018804ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T02:52:18.282222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.028152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-p66wd\" ","response":"range_response_count:1 size:18067"} {"level":"info","ts":"2026-04-03T02:52:18.282285Z","caller":"traceutil/trace.go:171","msg":"trace[1550217119] range","detail":"{range_begin:/registry/pods/kube-system/cilium-p66wd; range_end:; response_count:1; response_revision:707; }","duration":"229.134656ms","start":"2026-04-03T02:52:18.053136Z","end":"2026-04-03T02:52:18.28227Z","steps":["trace[1550217119] 'agreement among raft nodes before linearized reading' (duration: 228.828226ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:52:18.282412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.193888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-04-03T02:52:18.282523Z","caller":"traceutil/trace.go:171","msg":"trace[1242366516] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:707; }","duration":"224.373596ms","start":"2026-04-03T02:52:18.058124Z","end":"2026-04-03T02:52:18.282497Z","steps":["trace[1242366516] 'agreement among raft nodes before linearized reading' (duration: 224.059846ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:52:18.443109Z","caller":"traceutil/trace.go:171","msg":"trace[1259885559] transaction","detail":"{read_only:false; response_revision:710; number_of_response:1; }","duration":"147.603268ms","start":"2026-04-03T02:52:18.29548Z","end":"2026-04-03T02:52:18.443083Z","steps":["trace[1259885559] 'process raft request' (duration: 147.479305ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:52:18.458977Z","caller":"traceutil/trace.go:171","msg":"trace[31531356] transaction","detail":"{read_only:false; response_revision:711; number_of_response:1; }","duration":"114.904328ms","start":"2026-04-03T02:52:18.344054Z","end":"2026-04-03T02:52:18.458958Z","steps":["trace[31531356] 'process raft request' (duration: 114.524826ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:52:18.778369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.816647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:1 size:9320"} {"level":"info","ts":"2026-04-03T02:52:18.778451Z","caller":"traceutil/trace.go:171","msg":"trace[641284666] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:1; response_revision:716; }","duration":"135.946531ms","start":"2026-04-03T02:52:18.642485Z","end":"2026-04-03T02:52:18.778431Z","steps":["trace[641284666] 'range keys from in-memory index tree' (duration: 135.624631ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:52:19.627136Z","caller":"traceutil/trace.go:171","msg":"trace[1869822707] linearizableReadLoop","detail":"{readStateIndex:746; appliedIndex:745; }","duration":"132.034927ms","start":"2026-04-03T02:52:19.495075Z","end":"2026-04-03T02:52:19.62711Z","steps":["trace[1869822707] 'read index received' (duration: 131.720337ms)","trace[1869822707] 'applied index is now lower than readState.Index' (duration: 313.39µs)"],"step_count":2} {"level":"warn","ts":"2026-04-03T02:52:19.627368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.272644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-03T02:52:19.627365Z","caller":"traceutil/trace.go:171","msg":"trace[559985143] transaction","detail":"{read_only:false; response_revision:719; number_of_response:1; }","duration":"159.9799ms","start":"2026-04-03T02:52:19.467361Z","end":"2026-04-03T02:52:19.627341Z","steps":["trace[559985143] 'process raft request' (duration: 159.510116ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:52:19.627443Z","caller":"traceutil/trace.go:171","msg":"trace[919455642] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:719; }","duration":"132.364367ms","start":"2026-04-03T02:52:19.495067Z","end":"2026-04-03T02:52:19.627431Z","steps":["trace[919455642] 'agreement among raft nodes before linearized reading' (duration: 132.14993ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:52:19.682519Z","caller":"traceutil/trace.go:171","msg":"trace[1886169272] transaction","detail":"{read_only:false; response_revision:720; number_of_response:1; }","duration":"118.061849ms","start":"2026-04-03T02:52:19.564426Z","end":"2026-04-03T02:52:19.682488Z","steps":["trace[1886169272] 'process raft request' (duration: 117.911045ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:52:19.89643Z","caller":"traceutil/trace.go:171","msg":"trace[553731616] transaction","detail":"{read_only:false; response_revision:721; number_of_response:1; }","duration":"203.14948ms","start":"2026-04-03T02:52:19.693263Z","end":"2026-04-03T02:52:19.896413Z","steps":["trace[553731616] 'process raft request' (duration: 148.11129ms)","trace[553731616] 'compare' (duration: 54.899845ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T02:52:19.924396Z","caller":"traceutil/trace.go:171","msg":"trace[970811683] transaction","detail":"{read_only:false; response_revision:722; number_of_response:1; }","duration":"218.068164ms","start":"2026-04-03T02:52:19.706309Z","end":"2026-04-03T02:52:19.924377Z","steps":["trace[970811683] 'process raft request' (duration: 217.977412ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:52:20.142098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.694469ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-03T02:52:20.142225Z","caller":"traceutil/trace.go:171","msg":"trace[2131723277] transaction","detail":"{read_only:false; response_revision:723; number_of_response:1; }","duration":"244.402047ms","start":"2026-04-03T02:52:19.897792Z","end":"2026-04-03T02:52:20.142194Z","steps":["trace[2131723277] 'process raft request' (duration: 128.539822ms)","trace[2131723277] 'compare' (duration: 115.405461ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T02:52:20.170035Z","caller":"traceutil/trace.go:171","msg":"trace[685014292] linearizableReadLoop","detail":"{readStateIndex:751; appliedIndex:750; }","duration":"126.599667ms","start":"2026-04-03T02:52:20.043406Z","end":"2026-04-03T02:52:20.170005Z","steps":["trace[685014292] 'read index received' (duration: 126.256937ms)","trace[685014292] 'applied index is now lower than readState.Index' (duration: 341.59µs)"],"step_count":2} {"level":"info","ts":"2026-04-03T02:52:20.170182Z","caller":"traceutil/trace.go:171","msg":"trace[1738997805] transaction","detail":"{read_only:false; response_revision:724; number_of_response:1; }","duration":"194.14584ms","start":"2026-04-03T02:52:19.976029Z","end":"2026-04-03T02:52:20.170175Z","steps":["trace[1738997805] 'process raft request' (duration: 193.738597ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:52:20.170476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.051561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:1 size:5177"} {"level":"info","ts":"2026-04-03T02:52:20.170514Z","caller":"traceutil/trace.go:171","msg":"trace[1605849616] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:1; response_revision:724; }","duration":"127.147475ms","start":"2026-04-03T02:52:20.043354Z","end":"2026-04-03T02:52:20.170501Z","steps":["trace[1605849616] 'agreement among raft nodes before linearized reading' (duration: 126.982159ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:52:20.392607Z","caller":"traceutil/trace.go:171","msg":"trace[1007651052] linearizableReadLoop","detail":"{readStateIndex:753; appliedIndex:751; }","duration":"150.127718ms","start":"2026-04-03T02:52:20.242418Z","end":"2026-04-03T02:52:20.392546Z","steps":["trace[1007651052] 'read index received' (duration: 26.11404ms)","trace[1007651052] 'applied index is now lower than readState.Index' (duration: 124.012308ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T02:52:20.393444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.994054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:1 size:24244"} {"level":"info","ts":"2026-04-03T02:52:20.393536Z","caller":"traceutil/trace.go:171","msg":"trace[502457782] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:1; response_revision:726; }","duration":"151.120068ms","start":"2026-04-03T02:52:20.242375Z","end":"2026-04-03T02:52:20.393496Z","steps":["trace[502457782] 'agreement among raft nodes before linearized reading' (duration: 150.388796ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:52:20.39371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.772522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-03T02:52:20.392927Z","caller":"traceutil/trace.go:171","msg":"trace[1698027367] transaction","detail":"{read_only:false; response_revision:726; number_of_response:1; }","duration":"173.602336ms","start":"2026-04-03T02:52:20.219236Z","end":"2026-04-03T02:52:20.392839Z","steps":["trace[1698027367] 'process raft request' (duration: 157.813357ms)","trace[1698027367] 'compare' (duration: 15.287524ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T02:52:20.394191Z","caller":"traceutil/trace.go:171","msg":"trace[1098186118] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:726; }","duration":"106.958328ms","start":"2026-04-03T02:52:20.286853Z","end":"2026-04-03T02:52:20.393812Z","steps":["trace[1098186118] 'agreement among raft nodes before linearized reading' (duration: 106.108132ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:52:20.394249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.308444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-04-03T02:52:20.394349Z","caller":"traceutil/trace.go:171","msg":"trace[504575281] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:726; }","duration":"102.404287ms","start":"2026-04-03T02:52:20.291899Z","end":"2026-04-03T02:52:20.394303Z","steps":["trace[504575281] 'agreement among raft nodes before linearized reading' (duration: 102.17848ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:52:22.090261Z","caller":"traceutil/trace.go:171","msg":"trace[154997288] transaction","detail":"{read_only:false; number_of_response:0; response_revision:744; }","duration":"159.286846ms","start":"2026-04-03T02:52:21.930935Z","end":"2026-04-03T02:52:22.090222Z","steps":["trace[154997288] 'process raft request' (duration: 125.603767ms)","trace[154997288] 'compare' (duration: 33.568565ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T02:52:22.482467Z","caller":"traceutil/trace.go:171","msg":"trace[1334029381] transaction","detail":"{read_only:false; response_revision:746; number_of_response:1; }","duration":"112.193461ms","start":"2026-04-03T02:52:22.370243Z","end":"2026-04-03T02:52:22.482436Z","steps":["trace[1334029381] 'process raft request' (duration: 111.891681ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:52:25.342541Z","caller":"traceutil/trace.go:171","msg":"trace[347513541] transaction","detail":"{read_only:false; response_revision:765; number_of_response:1; }","duration":"208.694114ms","start":"2026-04-03T02:52:25.133828Z","end":"2026-04-03T02:52:25.342523Z","steps":["trace[347513541] 'process raft request' (duration: 208.578841ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:52:25.467066Z","caller":"traceutil/trace.go:171","msg":"trace[1784948856] transaction","detail":"{read_only:false; response_revision:767; number_of_response:1; }","duration":"112.676083ms","start":"2026-04-03T02:52:25.354359Z","end":"2026-04-03T02:52:25.467035Z","steps":["trace[1784948856] 'process raft request' (duration: 112.436555ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:52:25.800772Z","caller":"traceutil/trace.go:171","msg":"trace[248184113] transaction","detail":"{read_only:false; response_revision:770; number_of_response:1; }","duration":"109.535058ms","start":"2026-04-03T02:52:25.691213Z","end":"2026-04-03T02:52:25.800748Z","steps":["trace[248184113] 'process raft request' (duration: 106.500767ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:52:28.081867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.103074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.113\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-03T02:52:28.081962Z","caller":"traceutil/trace.go:171","msg":"trace[1180386933] range","detail":"{range_begin:/registry/masterleases/199.204.45.113; range_end:; response_count:1; response_revision:786; }","duration":"107.245509ms","start":"2026-04-03T02:52:27.974692Z","end":"2026-04-03T02:52:28.081937Z","steps":["trace[1180386933] 'range keys from in-memory index tree' (duration: 106.872608ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:52:31.050938Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356764461211588267,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-03T02:52:31.551317Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356764461211588267,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-03T02:52:31.568345Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.442039474s","expected-duration":"1s"} {"level":"info","ts":"2026-04-03T02:52:31.568868Z","caller":"traceutil/trace.go:171","msg":"trace[1609572011] transaction","detail":"{read_only:false; response_revision:801; number_of_response:1; }","duration":"1.442638351s","start":"2026-04-03T02:52:30.126201Z","end":"2026-04-03T02:52:31.568839Z","steps":["trace[1609572011] 'process raft request' (duration: 1.442414995s)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:52:31.569054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:52:30.126186Z","time spent":"1.442764225s","remote":"127.0.0.1:55096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4028,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-03T02:52:31.580856Z","caller":"traceutil/trace.go:171","msg":"trace[1060517121] linearizableReadLoop","detail":"{readStateIndex:833; appliedIndex:831; }","duration":"1.030789683s","start":"2026-04-03T02:52:30.550042Z","end":"2026-04-03T02:52:31.580831Z","steps":["trace[1060517121] 'read index received' (duration: 1.018646474s)","trace[1060517121] 'applied index is now lower than readState.Index' (duration: 12.142159ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T02:52:31.581149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"957.151616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-04-03T02:52:31.581198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"891.516688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-03T02:52:31.581256Z","caller":"traceutil/trace.go:171","msg":"trace[598289558] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:802; }","duration":"891.608151ms","start":"2026-04-03T02:52:30.689637Z","end":"2026-04-03T02:52:31.581245Z","steps":["trace[598289558] 'agreement among raft nodes before linearized reading' (duration: 891.473287ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:52:31.581174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"878.083999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-03T02:52:31.581173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.031109253s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-03T02:52:31.581257Z","caller":"traceutil/trace.go:171","msg":"trace[17987359] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:802; }","duration":"957.28866ms","start":"2026-04-03T02:52:30.623934Z","end":"2026-04-03T02:52:31.581223Z","steps":["trace[17987359] 'agreement among raft nodes before linearized reading' (duration: 957.059733ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:52:31.581352Z","caller":"traceutil/trace.go:171","msg":"trace[121299414] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:802; }","duration":"878.289137ms","start":"2026-04-03T02:52:30.703028Z","end":"2026-04-03T02:52:31.581317Z","steps":["trace[121299414] 'agreement among raft nodes before linearized reading' (duration: 877.992558ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:52:31.58139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:52:30.70301Z","time spent":"878.371298ms","remote":"127.0.0.1:55166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-03T02:52:31.581298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:52:30.68962Z","time spent":"891.667682ms","remote":"127.0.0.1:55166","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-04-03T02:52:31.581367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:52:30.623915Z","time spent":"957.443653ms","remote":"127.0.0.1:55166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-03T02:52:31.581327Z","caller":"traceutil/trace.go:171","msg":"trace[1515797183] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:802; }","duration":"1.03132095s","start":"2026-04-03T02:52:30.549999Z","end":"2026-04-03T02:52:31.58132Z","steps":["trace[1515797183] 'agreement among raft nodes before linearized reading' (duration: 1.030952598s)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:52:31.581831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:52:30.549977Z","time spent":"1.031843604s","remote":"127.0.0.1:55166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-03T02:52:31.581188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"896.375778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-03T02:52:31.581954Z","caller":"traceutil/trace.go:171","msg":"trace[1779788884] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:802; }","duration":"897.182291ms","start":"2026-04-03T02:52:30.684761Z","end":"2026-04-03T02:52:31.581943Z","steps":["trace[1779788884] 'agreement among raft nodes before linearized reading' (duration: 896.358778ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:52:31.582004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:52:30.684738Z","time spent":"897.255685ms","remote":"127.0.0.1:55166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-03T02:52:31.581175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.738942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-03T02:52:31.582578Z","caller":"traceutil/trace.go:171","msg":"trace[1069365001] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:802; }","duration":"109.177454ms","start":"2026-04-03T02:52:31.473391Z","end":"2026-04-03T02:52:31.582568Z","steps":["trace[1069365001] 'agreement among raft nodes before linearized reading' (duration: 107.750403ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:52:32.482155Z","caller":"traceutil/trace.go:171","msg":"trace[2473464] transaction","detail":"{read_only:false; response_revision:809; number_of_response:1; }","duration":"164.834201ms","start":"2026-04-03T02:52:32.317307Z","end":"2026-04-03T02:52:32.482141Z","steps":["trace[2473464] 'process raft request' (duration: 164.661267ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:52:32.48214Z","caller":"traceutil/trace.go:171","msg":"trace[814297432] linearizableReadLoop","detail":"{readStateIndex:840; appliedIndex:839; }","duration":"135.00175ms","start":"2026-04-03T02:52:32.347106Z","end":"2026-04-03T02:52:32.482108Z","steps":["trace[814297432] 'read index received' (duration: 134.797195ms)","trace[814297432] 'applied index is now lower than readState.Index' (duration: 203.495µs)"],"step_count":2} {"level":"warn","ts":"2026-04-03T02:52:32.482241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.121493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-03T02:52:32.482284Z","caller":"traceutil/trace.go:171","msg":"trace[899604338] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:809; }","duration":"135.181406ms","start":"2026-04-03T02:52:32.347091Z","end":"2026-04-03T02:52:32.482272Z","steps":["trace[899604338] 'agreement among raft nodes before linearized reading' (duration: 135.094984ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:52:36.044118Z","caller":"traceutil/trace.go:171","msg":"trace[2098272496] transaction","detail":"{read_only:false; response_revision:851; number_of_response:1; }","duration":"183.559778ms","start":"2026-04-03T02:52:35.860525Z","end":"2026-04-03T02:52:36.044085Z","steps":["trace[2098272496] 'process raft request' (duration: 181.591712ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:52:43.32832Z","caller":"traceutil/trace.go:171","msg":"trace[2009295145] transaction","detail":"{read_only:false; response_revision:936; number_of_response:1; }","duration":"123.935107ms","start":"2026-04-03T02:52:43.204358Z","end":"2026-04-03T02:52:43.328293Z","steps":["trace[2009295145] 'process raft request' (duration: 123.563618ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:52:43.338571Z","caller":"traceutil/trace.go:171","msg":"trace[1188791966] linearizableReadLoop","detail":"{readStateIndex:970; appliedIndex:968; }","duration":"103.428781ms","start":"2026-04-03T02:52:43.235122Z","end":"2026-04-03T02:52:43.338551Z","steps":["trace[1188791966] 'read index received' (duration: 92.818779ms)","trace[1188791966] 'applied index is now lower than readState.Index' (duration: 10.609012ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T02:52:43.33871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.557405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/local-path-storage/\" range_end:\"/registry/limitranges/local-path-storage0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-03T02:52:43.338693Z","caller":"traceutil/trace.go:171","msg":"trace[1969408108] transaction","detail":"{read_only:false; response_revision:937; number_of_response:1; }","duration":"104.096919ms","start":"2026-04-03T02:52:43.234564Z","end":"2026-04-03T02:52:43.338661Z","steps":["trace[1969408108] 'process raft request' (duration: 103.836493ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:52:43.338714Z","caller":"traceutil/trace.go:171","msg":"trace[1953045961] transaction","detail":"{read_only:false; response_revision:938; number_of_response:1; }","duration":"102.285642ms","start":"2026-04-03T02:52:43.236405Z","end":"2026-04-03T02:52:43.338691Z","steps":["trace[1953045961] 'process raft request' (duration: 102.090656ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:52:43.338752Z","caller":"traceutil/trace.go:171","msg":"trace[1119127799] range","detail":"{range_begin:/registry/limitranges/local-path-storage/; range_end:/registry/limitranges/local-path-storage0; response_count:0; response_revision:938; }","duration":"103.651527ms","start":"2026-04-03T02:52:43.235087Z","end":"2026-04-03T02:52:43.338739Z","steps":["trace[1119127799] 'agreement among raft nodes before linearized reading' (duration: 103.539775ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:52:43.844442Z","caller":"traceutil/trace.go:171","msg":"trace[1211938648] linearizableReadLoop","detail":"{readStateIndex:982; appliedIndex:981; }","duration":"293.220552ms","start":"2026-04-03T02:52:43.551194Z","end":"2026-04-03T02:52:43.844415Z","steps":["trace[1211938648] 'read index received' (duration: 293.073847ms)","trace[1211938648] 'applied index is now lower than readState.Index' (duration: 145.335µs)"],"step_count":2} {"level":"info","ts":"2026-04-03T02:52:43.844533Z","caller":"traceutil/trace.go:171","msg":"trace[231214448] transaction","detail":"{read_only:false; response_revision:948; number_of_response:1; }","duration":"343.710185ms","start":"2026-04-03T02:52:43.500759Z","end":"2026-04-03T02:52:43.844469Z","steps":["trace[231214448] 'process raft request' (duration: 343.500599ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:52:43.844706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:52:43.500742Z","time spent":"343.868289ms","remote":"127.0.0.1:55374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3720,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-03T02:52:43.844825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.564221ms","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:12755"} {"level":"warn","ts":"2026-04-03T02:52:43.844837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.482531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-03T02:52:43.844871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.941665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-03T02:52:43.844902Z","caller":"traceutil/trace.go:171","msg":"trace[615603190] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:1; response_revision:948; }","duration":"293.704715ms","start":"2026-04-03T02:52:43.551155Z","end":"2026-04-03T02:52:43.84486Z","steps":["trace[615603190] 'agreement among raft nodes before linearized reading' (duration: 293.432607ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:52:43.844905Z","caller":"traceutil/trace.go:171","msg":"trace[1692710249] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:948; }","duration":"139.608114ms","start":"2026-04-03T02:52:43.705277Z","end":"2026-04-03T02:52:43.844885Z","steps":["trace[1692710249] 'agreement among raft nodes before linearized reading' (duration: 139.409349ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:52:43.844941Z","caller":"traceutil/trace.go:171","msg":"trace[1795937047] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:948; }","duration":"119.073248ms","start":"2026-04-03T02:52:43.725854Z","end":"2026-04-03T02:52:43.844927Z","steps":["trace[1795937047] 'agreement among raft nodes before linearized reading' (duration: 118.836452ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:52:43.845006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.824269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-03T02:52:43.844986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.873782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-03T02:52:43.845114Z","caller":"traceutil/trace.go:171","msg":"trace[99351052] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:948; }","duration":"119.032646ms","start":"2026-04-03T02:52:43.726054Z","end":"2026-04-03T02:52:43.845086Z","steps":["trace[99351052] 'agreement among raft nodes before linearized reading' (duration: 118.661386ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:52:43.845155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.620678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-03T02:52:43.845187Z","caller":"traceutil/trace.go:171","msg":"trace[449169300] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:948; }","duration":"129.707452ms","start":"2026-04-03T02:52:43.715472Z","end":"2026-04-03T02:52:43.845179Z","steps":["trace[449169300] 'agreement among raft nodes before linearized reading' (duration: 129.599759ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:52:43.845052Z","caller":"traceutil/trace.go:171","msg":"trace[1950936299] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:948; }","duration":"119.919781ms","start":"2026-04-03T02:52:43.725121Z","end":"2026-04-03T02:52:43.845041Z","steps":["trace[1950936299] 'agreement among raft nodes before linearized reading' (duration: 119.812178ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:52:44.094163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.368677ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-03T02:52:44.094501Z","caller":"traceutil/trace.go:171","msg":"trace[755098284] transaction","detail":"{read_only:false; response_revision:952; number_of_response:1; }","duration":"243.594847ms","start":"2026-04-03T02:52:43.850886Z","end":"2026-04-03T02:52:44.094481Z","steps":["trace[755098284] 'process raft request' (duration: 243.485684ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:52:44.094471Z","caller":"traceutil/trace.go:171","msg":"trace[1414859165] transaction","detail":"{read_only:false; response_revision:950; number_of_response:1; }","duration":"244.43861ms","start":"2026-04-03T02:52:43.849993Z","end":"2026-04-03T02:52:44.094431Z","steps":["trace[1414859165] 'process raft request' (duration: 93.694984ms)","trace[1414859165] 'compare' (duration: 150.241712ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T02:52:44.094507Z","caller":"traceutil/trace.go:171","msg":"trace[144206285] transaction","detail":"{read_only:false; response_revision:951; number_of_response:1; }","duration":"243.769491ms","start":"2026-04-03T02:52:43.850701Z","end":"2026-04-03T02:52:44.09447Z","steps":["trace[144206285] 'process raft request' (duration: 243.586986ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:52:44.094733Z","caller":"traceutil/trace.go:171","msg":"trace[1970184620] transaction","detail":"{read_only:false; response_revision:953; number_of_response:1; }","duration":"243.187346ms","start":"2026-04-03T02:52:43.85153Z","end":"2026-04-03T02:52:44.094718Z","steps":["trace[1970184620] 'process raft request' (duration: 242.894098ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:52:44.418132Z","caller":"traceutil/trace.go:171","msg":"trace[1949009744] transaction","detail":"{read_only:false; response_revision:954; number_of_response:1; }","duration":"191.297431ms","start":"2026-04-03T02:52:44.226814Z","end":"2026-04-03T02:52:44.418111Z","steps":["trace[1949009744] 'process raft request' (duration: 191.151776ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:53:06.113454Z","caller":"traceutil/trace.go:171","msg":"trace[1032217223] transaction","detail":"{read_only:false; response_revision:1045; number_of_response:1; }","duration":"127.197255ms","start":"2026-04-03T02:53:05.986234Z","end":"2026-04-03T02:53:06.113431Z","steps":["trace[1032217223] 'process raft request' (duration: 127.092733ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:53:10.45667Z","caller":"traceutil/trace.go:171","msg":"trace[778450813] transaction","detail":"{read_only:false; response_revision:1060; number_of_response:1; }","duration":"234.859996ms","start":"2026-04-03T02:53:10.221772Z","end":"2026-04-03T02:53:10.456632Z","steps":["trace[778450813] 'process raft request' (duration: 234.631261ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:53:36.755815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.439471ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-03T02:53:36.755952Z","caller":"traceutil/trace.go:171","msg":"trace[912724157] linearizableReadLoop","detail":"{readStateIndex:1191; appliedIndex:1190; }","duration":"158.604911ms","start":"2026-04-03T02:53:36.597315Z","end":"2026-04-03T02:53:36.755919Z","steps":["trace[912724157] 'read index received' (duration: 157.289724ms)","trace[912724157] 'applied index is now lower than readState.Index' (duration: 1.313607ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T02:53:36.755981Z","caller":"traceutil/trace.go:171","msg":"trace[1900581663] transaction","detail":"{read_only:false; response_revision:1146; number_of_response:1; }","duration":"166.943437ms","start":"2026-04-03T02:53:36.589005Z","end":"2026-04-03T02:53:36.755948Z","steps":["trace[1900581663] 'process raft request' (duration: 165.576338ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:53:36.756184Z","caller":"traceutil/trace.go:171","msg":"trace[1918967232] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1145; }","duration":"104.579823ms","start":"2026-04-03T02:53:36.651335Z","end":"2026-04-03T02:53:36.755915Z","steps":["trace[1918967232] 'range keys from in-memory index tree' (duration: 104.420471ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:53:36.756242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.592753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-03T02:53:36.756286Z","caller":"traceutil/trace.go:171","msg":"trace[1435800996] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1146; }","duration":"148.675254ms","start":"2026-04-03T02:53:36.6076Z","end":"2026-04-03T02:53:36.756275Z","steps":["trace[1435800996] 'agreement among raft nodes before linearized reading' (duration: 148.48649ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:53:36.75678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.936639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-03T02:53:36.756879Z","caller":"traceutil/trace.go:171","msg":"trace[1914838355] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1146; }","duration":"159.584562ms","start":"2026-04-03T02:53:36.597274Z","end":"2026-04-03T02:53:36.756858Z","steps":["trace[1914838355] 'agreement among raft nodes before linearized reading' (duration: 158.757555ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:53:38.1352Z","caller":"traceutil/trace.go:171","msg":"trace[206132153] transaction","detail":"{read_only:false; response_revision:1150; number_of_response:1; }","duration":"121.685964ms","start":"2026-04-03T02:53:38.013482Z","end":"2026-04-03T02:53:38.135168Z","steps":["trace[206132153] 'process raft request' (duration: 71.235768ms)","trace[206132153] 'compare' (duration: 50.324374ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T02:53:53.101897Z","caller":"traceutil/trace.go:171","msg":"trace[1629430111] linearizableReadLoop","detail":"{readStateIndex:1246; appliedIndex:1245; }","duration":"274.519428ms","start":"2026-04-03T02:53:52.827354Z","end":"2026-04-03T02:53:53.101874Z","steps":["trace[1629430111] 'read index received' (duration: 274.329215ms)","trace[1629430111] 'applied index is now lower than readState.Index' (duration: 189.313µs)"],"step_count":2} {"level":"info","ts":"2026-04-03T02:53:53.101957Z","caller":"traceutil/trace.go:171","msg":"trace[601260060] transaction","detail":"{read_only:false; response_revision:1198; number_of_response:1; }","duration":"409.713909ms","start":"2026-04-03T02:53:52.692212Z","end":"2026-04-03T02:53:53.101925Z","steps":["trace[601260060] 'process raft request' (duration: 409.523775ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:53:53.102116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:53:52.692187Z","time spent":"409.839232ms","remote":"127.0.0.1:55166","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-04-03T02:53:53.102157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.781694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-03T02:53:53.102804Z","caller":"traceutil/trace.go:171","msg":"trace[711666924] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1198; }","duration":"275.471547ms","start":"2026-04-03T02:53:52.827315Z","end":"2026-04-03T02:53:53.102787Z","steps":["trace[711666924] 'agreement among raft nodes before linearized reading' (duration: 274.717682ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:53:53.102399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.99584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-03T02:53:53.103014Z","caller":"traceutil/trace.go:171","msg":"trace[1755568120] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1198; }","duration":"262.640792ms","start":"2026-04-03T02:53:52.840363Z","end":"2026-04-03T02:53:53.103004Z","steps":["trace[1755568120] 'agreement among raft nodes before linearized reading' (duration: 261.859497ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:53:53.102515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.261675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-03T02:53:53.103165Z","caller":"traceutil/trace.go:171","msg":"trace[2028976396] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1198; }","duration":"259.919299ms","start":"2026-04-03T02:53:52.843236Z","end":"2026-04-03T02:53:53.103156Z","steps":["trace[2028976396] 'agreement among raft nodes before linearized reading' (duration: 259.219315ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:53:53.675387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.182476ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-03T02:53:53.675487Z","caller":"traceutil/trace.go:171","msg":"trace[2106876945] linearizableReadLoop","detail":"{readStateIndex:1250; appliedIndex:1249; }","duration":"179.646455ms","start":"2026-04-03T02:53:53.495823Z","end":"2026-04-03T02:53:53.67547Z","steps":["trace[2106876945] 'read index received' (duration: 64.315186ms)","trace[2106876945] 'applied index is now lower than readState.Index' (duration: 115.329639ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T02:53:53.675558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.726426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-03T02:53:53.675589Z","caller":"traceutil/trace.go:171","msg":"trace[797644504] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1201; }","duration":"179.80932ms","start":"2026-04-03T02:53:53.495771Z","end":"2026-04-03T02:53:53.675581Z","steps":["trace[797644504] 'agreement among raft nodes before linearized reading' (duration: 179.744248ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:53:53.995556Z","caller":"traceutil/trace.go:171","msg":"trace[21100976] transaction","detail":"{read_only:false; response_revision:1202; number_of_response:1; }","duration":"105.216639ms","start":"2026-04-03T02:53:53.890299Z","end":"2026-04-03T02:53:53.995515Z","steps":["trace[21100976] 'process raft request' (duration: 105.045205ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:54:11.419128Z","caller":"traceutil/trace.go:171","msg":"trace[1600350014] transaction","detail":"{read_only:false; response_revision:1273; number_of_response:1; }","duration":"133.387018ms","start":"2026-04-03T02:54:11.285718Z","end":"2026-04-03T02:54:11.419105Z","steps":["trace[1600350014] 'process raft request' (duration: 81.662683ms)","trace[1600350014] 'compare' (duration: 51.560191ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T02:54:11.41945Z","caller":"traceutil/trace.go:171","msg":"trace[1176186101] transaction","detail":"{read_only:false; response_revision:1274; number_of_response:1; }","duration":"132.898008ms","start":"2026-04-03T02:54:11.286521Z","end":"2026-04-03T02:54:11.419419Z","steps":["trace[1176186101] 'process raft request' (duration: 132.547381ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:54:12.171479Z","caller":"traceutil/trace.go:171","msg":"trace[1414396860] transaction","detail":"{read_only:false; response_revision:1275; number_of_response:1; }","duration":"110.240712ms","start":"2026-04-03T02:54:12.06121Z","end":"2026-04-03T02:54:12.171451Z","steps":["trace[1414396860] 'process raft request' (duration: 110.061679ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:54:13.556226Z","caller":"traceutil/trace.go:171","msg":"trace[1463243890] transaction","detail":"{read_only:false; response_revision:1280; number_of_response:1; }","duration":"129.652446ms","start":"2026-04-03T02:54:13.426549Z","end":"2026-04-03T02:54:13.556201Z","steps":["trace[1463243890] 'process raft request' (duration: 129.511303ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:54:13.580898Z","caller":"traceutil/trace.go:171","msg":"trace[719447539] transaction","detail":"{read_only:false; response_revision:1281; number_of_response:1; }","duration":"152.475909ms","start":"2026-04-03T02:54:13.428386Z","end":"2026-04-03T02:54:13.580861Z","steps":["trace[719447539] 'process raft request' (duration: 152.220404ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:54:13.618375Z","caller":"traceutil/trace.go:171","msg":"trace[1552052334] linearizableReadLoop","detail":"{readStateIndex:1334; appliedIndex:1332; }","duration":"189.758295ms","start":"2026-04-03T02:54:13.428582Z","end":"2026-04-03T02:54:13.61834Z","steps":["trace[1552052334] 'read index received' (duration: 127.431394ms)","trace[1552052334] 'applied index is now lower than readState.Index' (duration: 62.324851ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T02:54:13.618826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.849646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-03T02:54:13.618875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.259075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-03T02:54:13.618984Z","caller":"traceutil/trace.go:171","msg":"trace[39182825] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:1281; }","duration":"113.420979ms","start":"2026-04-03T02:54:13.505537Z","end":"2026-04-03T02:54:13.618958Z","steps":["trace[39182825] 'agreement among raft nodes before linearized reading' (duration: 113.253806ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:54:13.618903Z","caller":"traceutil/trace.go:171","msg":"trace[317014003] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1281; }","duration":"123.950938ms","start":"2026-04-03T02:54:13.494911Z","end":"2026-04-03T02:54:13.618862Z","steps":["trace[317014003] 'agreement among raft nodes before linearized reading' (duration: 123.864296ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:54:13.619902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.285864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b0d6ee08-c64e-45b8-b6c9-687abc013fd8\" ","response":"range_response_count:1 size:3579"} {"level":"info","ts":"2026-04-03T02:54:13.62003Z","caller":"traceutil/trace.go:171","msg":"trace[75347837] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-b0d6ee08-c64e-45b8-b6c9-687abc013fd8; range_end:; response_count:1; response_revision:1281; }","duration":"191.437807ms","start":"2026-04-03T02:54:13.428547Z","end":"2026-04-03T02:54:13.619985Z","steps":["trace[75347837] 'agreement among raft nodes before linearized reading' (duration: 190.143292ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:54:17.418102Z","caller":"traceutil/trace.go:171","msg":"trace[444582412] transaction","detail":"{read_only:false; response_revision:1304; number_of_response:1; }","duration":"119.262431ms","start":"2026-04-03T02:54:17.298809Z","end":"2026-04-03T02:54:17.418072Z","steps":["trace[444582412] 'process raft request' (duration: 119.091268ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:54:17.589684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.157796ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-03T02:54:17.58987Z","caller":"traceutil/trace.go:171","msg":"trace[527748005] transaction","detail":"{read_only:false; response_revision:1306; number_of_response:1; }","duration":"166.664023ms","start":"2026-04-03T02:54:17.423189Z","end":"2026-04-03T02:54:17.589853Z","steps":["trace[527748005] 'process raft request' (duration: 166.605172ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:54:17.589973Z","caller":"traceutil/trace.go:171","msg":"trace[2111431059] transaction","detail":"{read_only:false; response_revision:1305; number_of_response:1; }","duration":"167.965196ms","start":"2026-04-03T02:54:17.421969Z","end":"2026-04-03T02:54:17.589934Z","steps":["trace[2111431059] 'process raft request' (duration: 45.486144ms)","trace[2111431059] 'compare' (duration: 121.980953ms)"],"step_count":2} {"level":"info","ts":"2026-04-03T02:54:17.590053Z","caller":"traceutil/trace.go:171","msg":"trace[969740470] linearizableReadLoop","detail":"{readStateIndex:1360; appliedIndex:1359; }","duration":"167.530889ms","start":"2026-04-03T02:54:17.422453Z","end":"2026-04-03T02:54:17.589983Z","steps":["trace[969740470] 'read index received' (duration: 45.013876ms)","trace[969740470] 'applied index is now lower than readState.Index' (duration: 122.513263ms)"],"step_count":2} {"level":"warn","ts":"2026-04-03T02:54:17.590221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.761293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/default/test-pvc\" ","response":"range_response_count:1 size:1465"} {"level":"info","ts":"2026-04-03T02:54:17.590269Z","caller":"traceutil/trace.go:171","msg":"trace[662522117] range","detail":"{range_begin:/registry/persistentvolumeclaims/default/test-pvc; range_end:; response_count:1; response_revision:1306; }","duration":"167.831144ms","start":"2026-04-03T02:54:17.422424Z","end":"2026-04-03T02:54:17.590255Z","steps":["trace[662522117] 'agreement among raft nodes before linearized reading' (duration: 167.675981ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:54:17.590461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.956897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:1536"} {"level":"info","ts":"2026-04-03T02:54:17.590546Z","caller":"traceutil/trace.go:171","msg":"trace[7221874] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:1306; }","duration":"168.08569ms","start":"2026-04-03T02:54:17.422444Z","end":"2026-04-03T02:54:17.59053Z","steps":["trace[7221874] 'agreement among raft nodes before linearized reading' (duration: 167.825384ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:54:21.414473Z","caller":"traceutil/trace.go:171","msg":"trace[1317297629] transaction","detail":"{read_only:false; response_revision:1323; number_of_response:1; }","duration":"103.025531ms","start":"2026-04-03T02:54:21.311419Z","end":"2026-04-03T02:54:21.414445Z","steps":["trace[1317297629] 'process raft request' (duration: 102.882149ms)"],"step_count":1} {"level":"info","ts":"2026-04-03T02:54:21.744213Z","caller":"traceutil/trace.go:171","msg":"trace[1959938858] transaction","detail":"{read_only:false; response_revision:1324; number_of_response:1; }","duration":"122.281991ms","start":"2026-04-03T02:54:21.621897Z","end":"2026-04-03T02:54:21.744179Z","steps":["trace[1959938858] 'process raft request' (duration: 122.003056ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:54:28.30351Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17356764461211589571,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-03T02:54:28.409996Z","caller":"traceutil/trace.go:171","msg":"trace[1170274615] transaction","detail":"{read_only:false; response_revision:1349; number_of_response:1; }","duration":"639.529653ms","start":"2026-04-03T02:54:27.770448Z","end":"2026-04-03T02:54:28.409977Z","steps":["trace[1170274615] 'process raft request' (duration: 639.265327ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:54:28.4102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"607.249437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-03T02:54:28.410236Z","caller":"traceutil/trace.go:171","msg":"trace[1946955927] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1349; }","duration":"607.339829ms","start":"2026-04-03T02:54:27.802887Z","end":"2026-04-03T02:54:28.410227Z","steps":["trace[1946955927] 'agreement among raft nodes before linearized reading' (duration: 607.189547ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:54:28.41026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:54:27.802866Z","time spent":"607.387629ms","remote":"127.0.0.1:55166","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-04-03T02:54:28.410413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:54:27.770421Z","time spent":"639.753016ms","remote":"127.0.0.1:55166","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-04-03T02:54:28.409907Z","caller":"traceutil/trace.go:171","msg":"trace[1536398034] linearizableReadLoop","detail":"{readStateIndex:1406; appliedIndex:1405; }","duration":"606.941581ms","start":"2026-04-03T02:54:27.802937Z","end":"2026-04-03T02:54:28.409879Z","steps":["trace[1536398034] 'read index received' (duration: 606.724897ms)","trace[1536398034] 'applied index is now lower than readState.Index' (duration: 215.504µs)"],"step_count":2} {"level":"info","ts":"2026-04-03T02:54:28.425876Z","caller":"traceutil/trace.go:171","msg":"trace[1649432772] transaction","detail":"{read_only:false; response_revision:1350; number_of_response:1; }","duration":"115.55265ms","start":"2026-04-03T02:54:28.310288Z","end":"2026-04-03T02:54:28.42584Z","steps":["trace[1649432772] 'process raft request' (duration: 115.343166ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:54:28.426059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.199815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.113\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-03T02:54:28.426119Z","caller":"traceutil/trace.go:171","msg":"trace[1085312236] range","detail":"{range_begin:/registry/masterleases/199.204.45.113; range_end:; response_count:1; response_revision:1350; }","duration":"445.301795ms","start":"2026-04-03T02:54:27.980803Z","end":"2026-04-03T02:54:28.426105Z","steps":["trace[1085312236] 'agreement among raft nodes before linearized reading' (duration: 445.156283ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:54:28.426154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:54:27.980776Z","time spent":"445.369869ms","remote":"127.0.0.1:54942","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.113\" "} {"level":"warn","ts":"2026-04-03T02:54:28.426197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"618.985283ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-03T02:54:28.426282Z","caller":"traceutil/trace.go:171","msg":"trace[962318588] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1350; }","duration":"619.118166ms","start":"2026-04-03T02:54:27.807141Z","end":"2026-04-03T02:54:28.426259Z","steps":["trace[962318588] 'agreement among raft nodes before linearized reading' (duration: 618.644956ms)"],"step_count":1} {"level":"warn","ts":"2026-04-03T02:54:28.426375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-03T02:54:27.807131Z","time spent":"619.232498ms","remote":"127.0.0.1:55166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "}