{"level":"warn","ts":"2026-06-02T06:54:01.206662Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-02T06:54:01.206746Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.20:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.20:2380","--initial-cluster=instance=https://199.19.213.20:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.20:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.20: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-06-02T06:54:01.206814Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-02T06:54:01.206827Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.20:2380"]} {"level":"info","ts":"2026-06-02T06:54:01.206842Z","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-06-02T06:54:01.2075Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.20:2379"]} {"level":"info","ts":"2026-06-02T06:54:01.20761Z","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.19.213.20:2380"],"listen-peer-urls":["https://199.19.213.20:2380"],"advertise-client-urls":["https://199.19.213.20:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.20:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.20: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-06-02T06:54:01.214615Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.801041ms"} {"level":"info","ts":"2026-06-02T06:54:01.225665Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"185293d8d40ffce0","cluster-id":"cc857afa6e1fa648"} {"level":"info","ts":"2026-06-02T06:54:01.225731Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"185293d8d40ffce0 switched to configuration voters=()"} {"level":"info","ts":"2026-06-02T06:54:01.226052Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"185293d8d40ffce0 became follower at term 0"} {"level":"info","ts":"2026-06-02T06:54:01.226066Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 185293d8d40ffce0 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-02T06:54:01.226082Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"185293d8d40ffce0 became follower at term 1"} {"level":"info","ts":"2026-06-02T06:54:01.226144Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"185293d8d40ffce0 switched to configuration voters=(1752625764480580832)"} {"level":"warn","ts":"2026-06-02T06:54:01.2334Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-02T06:54:01.234942Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-02T06:54:01.236141Z","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-06-02T06:54:01.238373Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"185293d8d40ffce0","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-02T06:54:01.238553Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"185293d8d40ffce0","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-02T06:54:01.242454Z","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-06-02T06:54:01.242519Z","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-06-02T06:54:01.243013Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"185293d8d40ffce0 switched to configuration voters=(1752625764480580832)"} {"level":"info","ts":"2026-06-02T06:54:01.243255Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"cc857afa6e1fa648","local-member-id":"185293d8d40ffce0","added-peer-id":"185293d8d40ffce0","added-peer-peer-urls":["https://199.19.213.20:2380"]} {"level":"info","ts":"2026-06-02T06:54:01.242532Z","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-06-02T06:54:01.247685Z","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-06-02T06:54:01.24802Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"185293d8d40ffce0","initial-advertise-peer-urls":["https://199.19.213.20:2380"],"listen-peer-urls":["https://199.19.213.20:2380"],"advertise-client-urls":["https://199.19.213.20:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.20:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-02T06:54:01.248102Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-02T06:54:01.248283Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.20:2380"} {"level":"info","ts":"2026-06-02T06:54:01.248343Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.20:2380"} {"level":"info","ts":"2026-06-02T06:54:01.326348Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"185293d8d40ffce0 is starting a new election at term 1"} {"level":"info","ts":"2026-06-02T06:54:01.326412Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"185293d8d40ffce0 became pre-candidate at term 1"} {"level":"info","ts":"2026-06-02T06:54:01.326448Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"185293d8d40ffce0 received MsgPreVoteResp from 185293d8d40ffce0 at term 1"} {"level":"info","ts":"2026-06-02T06:54:01.326468Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"185293d8d40ffce0 became candidate at term 2"} {"level":"info","ts":"2026-06-02T06:54:01.326486Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"185293d8d40ffce0 received MsgVoteResp from 185293d8d40ffce0 at term 2"} {"level":"info","ts":"2026-06-02T06:54:01.326508Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"185293d8d40ffce0 became leader at term 2"} {"level":"info","ts":"2026-06-02T06:54:01.326527Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 185293d8d40ffce0 elected leader 185293d8d40ffce0 at term 2"} {"level":"info","ts":"2026-06-02T06:54:01.330233Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"185293d8d40ffce0","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.20:2379]}","request-path":"/0/members/185293d8d40ffce0/attributes","cluster-id":"cc857afa6e1fa648","publish-timeout":"7s"} {"level":"info","ts":"2026-06-02T06:54:01.330363Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-02T06:54:01.330435Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-02T06:54:01.33045Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-02T06:54:01.330406Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-02T06:54:01.330767Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-02T06:54:01.332615Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.20:2379"} {"level":"info","ts":"2026-06-02T06:54:01.334315Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"cc857afa6e1fa648","local-member-id":"185293d8d40ffce0","cluster-version":"3.5"} {"level":"info","ts":"2026-06-02T06:54:01.334598Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-02T06:54:01.334658Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-02T06:54:01.336836Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-02T06:54:19.41386Z","caller":"traceutil/trace.go:171","msg":"trace[241354918] transaction","detail":"{read_only:false; response_revision:377; number_of_response:1; }","duration":"112.928429ms","start":"2026-06-02T06:54:19.300826Z","end":"2026-06-02T06:54:19.413754Z","steps":["trace[241354918] 'process raft request' (duration: 112.761445ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:54:19.414466Z","caller":"traceutil/trace.go:171","msg":"trace[639621863] transaction","detail":"{read_only:false; response_revision:378; number_of_response:1; }","duration":"113.242566ms","start":"2026-06-02T06:54:19.301198Z","end":"2026-06-02T06:54:19.41444Z","steps":["trace[639621863] 'process raft request' (duration: 113.080472ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:54:25.174215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.067084ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-02T06:54:25.174329Z","caller":"traceutil/trace.go:171","msg":"trace[726145572] transaction","detail":"{read_only:false; response_revision:422; number_of_response:1; }","duration":"266.727336ms","start":"2026-06-02T06:54:24.907589Z","end":"2026-06-02T06:54:25.174316Z","steps":["trace[726145572] 'process raft request' (duration: 143.146289ms)","trace[726145572] 'compare' (duration: 122.913921ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T06:54:25.942717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"502.453219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-06-02T06:54:25.94274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.61445ms","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-06-02T06:54:25.942789Z","caller":"traceutil/trace.go:171","msg":"trace[873803198] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:422; }","duration":"502.585942ms","start":"2026-06-02T06:54:25.440185Z","end":"2026-06-02T06:54:25.942771Z","steps":["trace[873803198] 'range keys from in-memory index tree' (duration: 502.365237ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:54:25.942893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:54:25.44017Z","time spent":"502.715536ms","remote":"127.0.0.1:43846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-02T06:54:25.942871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"502.813417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-06-02T06:54:25.942913Z","caller":"traceutil/trace.go:171","msg":"trace[136028526] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:422; }","duration":"181.848454ms","start":"2026-06-02T06:54:25.761026Z","end":"2026-06-02T06:54:25.942874Z","steps":["trace[136028526] 'range keys from in-memory index tree' (duration: 181.459486ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:54:25.942783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.631722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T06:54:25.942975Z","caller":"traceutil/trace.go:171","msg":"trace[1941642675] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:422; }","duration":"502.994941ms","start":"2026-06-02T06:54:25.439955Z","end":"2026-06-02T06:54:25.94295Z","steps":["trace[1941642675] 'range keys from in-memory index tree' (duration: 502.616333ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:54:25.94299Z","caller":"traceutil/trace.go:171","msg":"trace[459114477] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:422; }","duration":"258.898069ms","start":"2026-06-02T06:54:25.684074Z","end":"2026-06-02T06:54:25.942972Z","steps":["trace[459114477] 'range keys from in-memory index tree' (duration: 258.506879ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:54:25.943035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:54:25.439938Z","time spent":"503.084234ms","remote":"127.0.0.1:43846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-02T06:54:25.942936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.818777ms","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-06-02T06:54:25.943419Z","caller":"traceutil/trace.go:171","msg":"trace[822351774] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:422; }","duration":"400.352238ms","start":"2026-06-02T06:54:25.543058Z","end":"2026-06-02T06:54:25.94341Z","steps":["trace[822351774] 'range keys from in-memory index tree' (duration: 399.704884ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:54:25.943444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:54:25.543031Z","time spent":"400.40421ms","remote":"127.0.0.1:43846","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-06-02T06:54:37.117968Z","caller":"traceutil/trace.go:171","msg":"trace[822790067] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"321.690129ms","start":"2026-06-02T06:54:36.79625Z","end":"2026-06-02T06:54:37.11794Z","steps":["trace[822790067] 'process raft request' (duration: 321.493885ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:54:37.118124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:54:36.796227Z","time spent":"321.831302ms","remote":"127.0.0.1:43634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-02T06:54:37.1241Z","caller":"traceutil/trace.go:171","msg":"trace[594762052] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"306.439494ms","start":"2026-06-02T06:54:36.81763Z","end":"2026-06-02T06:54:37.12407Z","steps":["trace[594762052] 'process raft request' (duration: 306.242769ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:54:37.124248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:54:36.817615Z","time spent":"306.562166ms","remote":"127.0.0.1:43634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-02T06:54:37.124386Z","caller":"traceutil/trace.go:171","msg":"trace[1712935306] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"289.815036ms","start":"2026-06-02T06:54:36.834558Z","end":"2026-06-02T06:54:37.124373Z","steps":["trace[1712935306] 'process raft request' (duration: 289.494969ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:54:37.124489Z","caller":"traceutil/trace.go:171","msg":"trace[2041699237] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"306.834802ms","start":"2026-06-02T06:54:36.817646Z","end":"2026-06-02T06:54:37.124481Z","steps":["trace[2041699237] 'process raft request' (duration: 306.334771ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:54:37.124542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:54:36.817617Z","time spent":"306.899104ms","remote":"127.0.0.1:43634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-02T06:54:37.12468Z","caller":"traceutil/trace.go:171","msg":"trace[1827655475] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"297.847349ms","start":"2026-06-02T06:54:36.826815Z","end":"2026-06-02T06:54:37.124662Z","steps":["trace[1827655475] 'process raft request' (duration: 297.204345ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:54:37.124814Z","caller":"traceutil/trace.go:171","msg":"trace[1353042396] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"285.923569ms","start":"2026-06-02T06:54:36.838881Z","end":"2026-06-02T06:54:37.124805Z","steps":["trace[1353042396] 'process raft request' (duration: 285.355596ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:54:37.125018Z","caller":"traceutil/trace.go:171","msg":"trace[1563413871] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"271.920594ms","start":"2026-06-02T06:54:36.853082Z","end":"2026-06-02T06:54:37.125003Z","steps":["trace[1563413871] 'process raft request' (duration: 271.284948ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:54:37.125007Z","caller":"traceutil/trace.go:171","msg":"trace[397799098] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"157.986055ms","start":"2026-06-02T06:54:36.967001Z","end":"2026-06-02T06:54:37.124987Z","steps":["trace[397799098] 'process raft request' (duration: 157.859471ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:54:37.125128Z","caller":"traceutil/trace.go:171","msg":"trace[321481538] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"286.268468ms","start":"2026-06-02T06:54:36.838824Z","end":"2026-06-02T06:54:37.125092Z","steps":["trace[321481538] 'process raft request' (duration: 285.306196ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:54:37.125127Z","caller":"traceutil/trace.go:171","msg":"trace[1700449084] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"277.198262ms","start":"2026-06-02T06:54:36.847884Z","end":"2026-06-02T06:54:37.125082Z","steps":["trace[1700449084] 'process raft request' (duration: 276.396473ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:54:37.125118Z","caller":"traceutil/trace.go:171","msg":"trace[961515605] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"255.291347ms","start":"2026-06-02T06:54:36.869772Z","end":"2026-06-02T06:54:37.125063Z","steps":["trace[961515605] 'process raft request' (duration: 254.829196ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:54:37.124908Z","caller":"traceutil/trace.go:171","msg":"trace[1529226168] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"289.407448ms","start":"2026-06-02T06:54:36.83549Z","end":"2026-06-02T06:54:37.124897Z","steps":["trace[1529226168] 'process raft request' (duration: 288.59872ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:54:43.544836Z","caller":"traceutil/trace.go:171","msg":"trace[1737288952] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"381.535773ms","start":"2026-06-02T06:54:43.163221Z","end":"2026-06-02T06:54:43.544757Z","steps":["trace[1737288952] 'process raft request' (duration: 380.803767ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:54:43.545589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:54:43.163188Z","time spent":"382.019293ms","remote":"127.0.0.1:43686","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036281,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T06:54:44.184991Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18221738395474071334,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-02T06:54:44.253118Z","caller":"traceutil/trace.go:171","msg":"trace[1166131851] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"681.039399ms","start":"2026-06-02T06:54:43.572047Z","end":"2026-06-02T06:54:44.253086Z","steps":["trace[1166131851] 'process raft request' (duration: 680.861135ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:54:44.253302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:54:43.572019Z","time spent":"681.192133ms","remote":"127.0.0.1:43758","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T06:54:44.32889Z","caller":"traceutil/trace.go:171","msg":"trace[1657399565] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"713.017773ms","start":"2026-06-02T06:54:43.615816Z","end":"2026-06-02T06:54:44.328834Z","steps":["trace[1657399565] 'process raft request' (duration: 712.463791ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:54:44.328955Z","caller":"traceutil/trace.go:171","msg":"trace[2045599930] linearizableReadLoop","detail":"{readStateIndex:618; appliedIndex:615; }","duration":"644.990303ms","start":"2026-06-02T06:54:43.683866Z","end":"2026-06-02T06:54:44.328857Z","steps":["trace[2045599930] 'read index received' (duration: 569.127847ms)","trace[2045599930] 'applied index is now lower than readState.Index' (duration: 75.860036ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T06:54:44.329076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:54:43.615787Z","time spent":"713.204206ms","remote":"127.0.0.1:44068","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-02T06:54:44.32916Z","caller":"traceutil/trace.go:171","msg":"trace[243449046] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"533.436849ms","start":"2026-06-02T06:54:43.795706Z","end":"2026-06-02T06:54:44.329142Z","steps":["trace[243449046] 'process raft request' (duration: 532.986739ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:54:44.32933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:54:43.795675Z","time spent":"533.531451ms","remote":"127.0.0.1:43846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T06:54:44.329669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.48879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-06-02T06:54:44.329785Z","caller":"traceutil/trace.go:171","msg":"trace[1475195417] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:593; }","duration":"194.641093ms","start":"2026-06-02T06:54:44.135107Z","end":"2026-06-02T06:54:44.329748Z","steps":["trace[1475195417] 'agreement among raft nodes before linearized reading' (duration: 194.107921ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:54:44.329936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.206145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-06-02T06:54:44.329955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"646.076007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T06:54:44.330009Z","caller":"traceutil/trace.go:171","msg":"trace[1886478499] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:593; }","duration":"187.294257ms","start":"2026-06-02T06:54:44.142697Z","end":"2026-06-02T06:54:44.329991Z","steps":["trace[1886478499] 'agreement among raft nodes before linearized reading' (duration: 187.103782ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:54:44.330236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.642056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"warn","ts":"2026-06-02T06:54:44.330277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.387413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-06-02T06:54:44.330297Z","caller":"traceutil/trace.go:171","msg":"trace[1348718123] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:593; }","duration":"187.721967ms","start":"2026-06-02T06:54:44.142555Z","end":"2026-06-02T06:54:44.330277Z","steps":["trace[1348718123] 'agreement among raft nodes before linearized reading' (duration: 187.503502ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:54:44.330322Z","caller":"traceutil/trace.go:171","msg":"trace[979895830] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:593; }","duration":"188.503556ms","start":"2026-06-02T06:54:44.141804Z","end":"2026-06-02T06:54:44.330308Z","steps":["trace[979895830] 'agreement among raft nodes before linearized reading' (duration: 188.317922ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:54:44.330539Z","caller":"traceutil/trace.go:171","msg":"trace[360676270] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:593; }","duration":"646.18873ms","start":"2026-06-02T06:54:43.683826Z","end":"2026-06-02T06:54:44.330015Z","steps":["trace[360676270] 'agreement among raft nodes before linearized reading' (duration: 646.064087ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:54:44.33059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:54:43.683805Z","time spent":"646.771705ms","remote":"127.0.0.1:43608","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-06-02T06:54:45.492583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"566.294284ms","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-06-02T06:54:45.492687Z","caller":"traceutil/trace.go:171","msg":"trace[746800282] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:593; }","duration":"566.409457ms","start":"2026-06-02T06:54:44.926258Z","end":"2026-06-02T06:54:45.492668Z","steps":["trace[746800282] 'range keys from in-memory index tree' (duration: 566.277523ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:54:45.493069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"936.113511ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-02T06:54:45.493424Z","caller":"traceutil/trace.go:171","msg":"trace[155777223] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"1.154174495s","start":"2026-06-02T06:54:44.339211Z","end":"2026-06-02T06:54:45.493385Z","steps":["trace[155777223] 'process raft request' (duration: 1.154083023s)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:54:45.493439Z","caller":"traceutil/trace.go:171","msg":"trace[133480067] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"1.157777377s","start":"2026-06-02T06:54:44.335644Z","end":"2026-06-02T06:54:45.493421Z","steps":["trace[133480067] 'process raft request' (duration: 221.195905ms)","trace[133480067] 'compare' (duration: 935.782644ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T06:54:45.493497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:54:44.339193Z","time spent":"1.154270387s","remote":"127.0.0.1:43846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T06:54:45.493556Z","caller":"traceutil/trace.go:171","msg":"trace[1013373986] linearizableReadLoop","detail":"{readStateIndex:622; appliedIndex:618; }","duration":"1.152320694s","start":"2026-06-02T06:54:44.341215Z","end":"2026-06-02T06:54:45.493536Z","steps":["trace[1013373986] 'read index received' (duration: 215.593868ms)","trace[1013373986] 'applied index is now lower than readState.Index' (duration: 936.725606ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T06:54:45.493563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:54:44.335617Z","time spent":"1.157860989s","remote":"127.0.0.1:43846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T06:54:45.493759Z","caller":"traceutil/trace.go:171","msg":"trace[1557293279] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"1.158046412s","start":"2026-06-02T06:54:44.335701Z","end":"2026-06-02T06:54:45.493747Z","steps":["trace[1557293279] 'process raft request' (duration: 1.15749161s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:54:45.49382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:54:44.335661Z","time spent":"1.158129874s","remote":"127.0.0.1:43846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T06:54:45.493892Z","caller":"traceutil/trace.go:171","msg":"trace[1754331831] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"1.154604794s","start":"2026-06-02T06:54:44.339276Z","end":"2026-06-02T06:54:45.493881Z","steps":["trace[1754331831] 'process raft request' (duration: 1.154061052s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:54:45.494197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"811.485612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-02T06:54:45.494255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"646.399006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.20\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-06-02T06:54:45.494281Z","caller":"traceutil/trace.go:171","msg":"trace[18408725] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:597; }","duration":"811.683726ms","start":"2026-06-02T06:54:44.682577Z","end":"2026-06-02T06:54:45.494261Z","steps":["trace[18408725] 'agreement among raft nodes before linearized reading' (duration: 811.513052ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:54:45.494293Z","caller":"traceutil/trace.go:171","msg":"trace[823014101] range","detail":"{range_begin:/registry/masterleases/199.19.213.20; range_end:; response_count:1; response_revision:597; }","duration":"646.491508ms","start":"2026-06-02T06:54:44.84779Z","end":"2026-06-02T06:54:45.494282Z","steps":["trace[823014101] 'agreement among raft nodes before linearized reading' (duration: 646.413186ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:54:45.494327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:54:44.847757Z","time spent":"646.56352ms","remote":"127.0.0.1:43628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.19.213.20\" "} {"level":"warn","ts":"2026-06-02T06:54:45.494269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.153025679s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T06:54:45.494422Z","caller":"traceutil/trace.go:171","msg":"trace[45143585] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:597; }","duration":"1.153195553s","start":"2026-06-02T06:54:44.341187Z","end":"2026-06-02T06:54:45.494383Z","steps":["trace[45143585] 'agreement among raft nodes before linearized reading' (duration: 1.153001679s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:54:45.494511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:54:44.341177Z","time spent":"1.153316976s","remote":"127.0.0.1:43824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"warn","ts":"2026-06-02T06:54:45.494328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:54:44.682544Z","time spent":"811.774748ms","remote":"127.0.0.1:43604","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-06-02T06:54:45.496016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:54:44.33927Z","time spent":"1.154659655s","remote":"127.0.0.1:43846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T06:54:59.951281Z","caller":"traceutil/trace.go:171","msg":"trace[1571682680] transaction","detail":"{read_only:false; response_revision:750; number_of_response:1; }","duration":"389.063783ms","start":"2026-06-02T06:54:59.562182Z","end":"2026-06-02T06:54:59.951246Z","steps":["trace[1571682680] 'process raft request' (duration: 388.835068ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:54:59.95152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:54:59.562128Z","time spent":"389.260007ms","remote":"127.0.0.1:43846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T06:54:59.951517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.313861ms","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-06-02T06:54:59.951651Z","caller":"traceutil/trace.go:171","msg":"trace[880585238] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:750; }","duration":"386.453274ms","start":"2026-06-02T06:54:59.565133Z","end":"2026-06-02T06:54:59.951587Z","steps":["trace[880585238] 'agreement among raft nodes before linearized reading' (duration: 386.208408ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:54:59.951781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:54:59.56512Z","time spent":"386.644418ms","remote":"127.0.0.1:43846","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-06-02T06:54:59.951219Z","caller":"traceutil/trace.go:171","msg":"trace[1636547258] linearizableReadLoop","detail":"{readStateIndex:779; appliedIndex:778; }","duration":"386.009174ms","start":"2026-06-02T06:54:59.56518Z","end":"2026-06-02T06:54:59.951189Z","steps":["trace[1636547258] 'read index received' (duration: 385.84508ms)","trace[1636547258] 'applied index is now lower than readState.Index' (duration: 162.784µs)"],"step_count":2} {"level":"info","ts":"2026-06-02T06:55:00.004131Z","caller":"traceutil/trace.go:171","msg":"trace[269957440] transaction","detail":"{read_only:false; response_revision:751; number_of_response:1; }","duration":"354.277246ms","start":"2026-06-02T06:54:59.649834Z","end":"2026-06-02T06:55:00.004111Z","steps":["trace[269957440] 'process raft request' (duration: 353.810625ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:55:00.004268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:54:59.649809Z","time spent":"354.383979ms","remote":"127.0.0.1:43824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T06:55:00.004459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.175357ms","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-06-02T06:55:00.004476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.769482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-06-02T06:55:00.004527Z","caller":"traceutil/trace.go:171","msg":"trace[1777470638] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:751; }","duration":"436.837584ms","start":"2026-06-02T06:54:59.567677Z","end":"2026-06-02T06:55:00.004515Z","steps":["trace[1777470638] 'agreement among raft nodes before linearized reading' (duration: 436.597469ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:55:00.004528Z","caller":"traceutil/trace.go:171","msg":"trace[1945764979] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:751; }","duration":"435.266129ms","start":"2026-06-02T06:54:59.569244Z","end":"2026-06-02T06:55:00.00451Z","steps":["trace[1945764979] 'agreement among raft nodes before linearized reading' (duration: 435.055744ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:55:00.004562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:54:59.56767Z","time spent":"436.885315ms","remote":"127.0.0.1:43846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-02T06:55:00.004573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:54:59.569238Z","time spent":"435.32392ms","remote":"127.0.0.1:43846","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-06-02T06:55:34.4655Z","caller":"traceutil/trace.go:171","msg":"trace[983523290] transaction","detail":"{read_only:false; response_revision:967; number_of_response:1; }","duration":"272.145596ms","start":"2026-06-02T06:55:34.19332Z","end":"2026-06-02T06:55:34.465465Z","steps":["trace[983523290] 'process raft request' (duration: 271.965873ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:55:34.47947Z","caller":"traceutil/trace.go:171","msg":"trace[492429812] transaction","detail":"{read_only:false; response_revision:968; number_of_response:1; }","duration":"285.720214ms","start":"2026-06-02T06:55:34.193732Z","end":"2026-06-02T06:55:34.479452Z","steps":["trace[492429812] 'process raft request' (duration: 285.588411ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:55:34.479682Z","caller":"traceutil/trace.go:171","msg":"trace[1865793412] transaction","detail":"{read_only:false; response_revision:969; number_of_response:1; }","duration":"285.562991ms","start":"2026-06-02T06:55:34.194069Z","end":"2026-06-02T06:55:34.479632Z","steps":["trace[1865793412] 'process raft request' (duration: 285.356577ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:55:35.578255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.566579ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-02T06:55:35.578456Z","caller":"traceutil/trace.go:171","msg":"trace[1758130053] transaction","detail":"{read_only:false; response_revision:970; number_of_response:1; }","duration":"626.192649ms","start":"2026-06-02T06:55:34.95224Z","end":"2026-06-02T06:55:35.578432Z","steps":["trace[1758130053] 'process raft request' (duration: 273.289282ms)","trace[1758130053] 'compare' (duration: 351.807173ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T06:55:35.578536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:55:34.952226Z","time spent":"626.274871ms","remote":"127.0.0.1:43628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T06:55:35.792637Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18221738395474072107,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-02T06:55:36.063913Z","caller":"traceutil/trace.go:171","msg":"trace[1402740354] transaction","detail":"{read_only:false; response_revision:971; number_of_response:1; }","duration":"1.106452758s","start":"2026-06-02T06:55:34.957426Z","end":"2026-06-02T06:55:36.063879Z","steps":["trace[1402740354] 'process raft request' (duration: 1.091426808s)","trace[1402740354] 'compare' (duration: 14.508688ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T06:55:36.064116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:55:34.957417Z","time spent":"1.10659157s","remote":"127.0.0.1:43846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T06:55:36.064094Z","caller":"traceutil/trace.go:171","msg":"trace[1259333031] linearizableReadLoop","detail":"{readStateIndex:1010; appliedIndex:1008; }","duration":"772.415645ms","start":"2026-06-02T06:55:35.291645Z","end":"2026-06-02T06:55:36.064061Z","steps":["trace[1259333031] 'read index received' (duration: 757.222801ms)","trace[1259333031] 'applied index is now lower than readState.Index' (duration: 15.191714ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T06:55:36.064353Z","caller":"traceutil/trace.go:171","msg":"trace[1926843484] transaction","detail":"{read_only:false; response_revision:972; number_of_response:1; }","duration":"661.390331ms","start":"2026-06-02T06:55:35.402947Z","end":"2026-06-02T06:55:36.064338Z","steps":["trace[1926843484] 'process raft request' (duration: 660.859609ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:55:36.064486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:55:35.402907Z","time spent":"661.482063ms","remote":"127.0.0.1:43846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T06:55:36.064507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"483.715736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-06-02T06:55:36.064581Z","caller":"traceutil/trace.go:171","msg":"trace[1268381643] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:972; }","duration":"483.840478ms","start":"2026-06-02T06:55:35.580722Z","end":"2026-06-02T06:55:36.064562Z","steps":["trace[1268381643] 'agreement among raft nodes before linearized reading' (duration: 483.677005ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:55:36.064599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"772.951247ms","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-06-02T06:55:36.064624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:55:35.580701Z","time spent":"483.91422ms","remote":"127.0.0.1:43746","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":444,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"info","ts":"2026-06-02T06:55:36.064648Z","caller":"traceutil/trace.go:171","msg":"trace[703127102] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:972; }","duration":"773.030298ms","start":"2026-06-02T06:55:35.291603Z","end":"2026-06-02T06:55:36.064633Z","steps":["trace[703127102] 'agreement among raft nodes before linearized reading' (duration: 772.863974ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:55:36.064689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:55:35.291585Z","time spent":"773.09503ms","remote":"127.0.0.1:43846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-02T06:55:36.064586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.075723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T06:55:36.064758Z","caller":"traceutil/trace.go:171","msg":"trace[325792771] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:972; }","duration":"380.298018ms","start":"2026-06-02T06:55:35.68444Z","end":"2026-06-02T06:55:36.064738Z","steps":["trace[325792771] 'agreement among raft nodes before linearized reading' (duration: 380.070273ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:55:36.064819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:55:35.684419Z","time spent":"380.375089ms","remote":"127.0.0.1:43604","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-06-02T06:55:56.697184Z","caller":"traceutil/trace.go:171","msg":"trace[1224491026] linearizableReadLoop","detail":"{readStateIndex:1079; appliedIndex:1078; }","duration":"474.670276ms","start":"2026-06-02T06:55:56.222494Z","end":"2026-06-02T06:55:56.697165Z","steps":["trace[1224491026] 'read index received' (duration: 474.457862ms)","trace[1224491026] 'applied index is now lower than readState.Index' (duration: 211.324µs)"],"step_count":2} {"level":"info","ts":"2026-06-02T06:55:56.697299Z","caller":"traceutil/trace.go:171","msg":"trace[428353719] transaction","detail":"{read_only:false; response_revision:1037; number_of_response:1; }","duration":"572.667465ms","start":"2026-06-02T06:55:56.124565Z","end":"2026-06-02T06:55:56.697232Z","steps":["trace[428353719] 'process raft request' (duration: 572.45625ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:55:56.697454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:55:56.124545Z","time spent":"572.822108ms","remote":"127.0.0.1:43846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T06:55:56.697494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.970043ms","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-06-02T06:55:56.697603Z","caller":"traceutil/trace.go:171","msg":"trace[995864373] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1037; }","duration":"475.131546ms","start":"2026-06-02T06:55:56.222448Z","end":"2026-06-02T06:55:56.69758Z","steps":["trace[995864373] 'agreement among raft nodes before linearized reading' (duration: 474.83725ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:55:56.697669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:55:56.222409Z","time spent":"475.242218ms","remote":"127.0.0.1:43846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-06-02T06:55:56.988121Z","caller":"traceutil/trace.go:171","msg":"trace[849163825] linearizableReadLoop","detail":"{readStateIndex:1080; appliedIndex:1079; }","duration":"290.853047ms","start":"2026-06-02T06:55:56.697244Z","end":"2026-06-02T06:55:56.988097Z","steps":["trace[849163825] 'read index received' (duration: 287.630427ms)","trace[849163825] 'applied index is now lower than readState.Index' (duration: 3.22156ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T06:55:56.988182Z","caller":"traceutil/trace.go:171","msg":"trace[2041422806] transaction","detail":"{read_only:false; response_revision:1038; number_of_response:1; }","duration":"596.514738ms","start":"2026-06-02T06:55:56.391618Z","end":"2026-06-02T06:55:56.988133Z","steps":["trace[2041422806] 'process raft request' (duration: 593.270107ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:55:56.988302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"765.293638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"warn","ts":"2026-06-02T06:55:56.988284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.276938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-06-02T06:55:56.988341Z","caller":"traceutil/trace.go:171","msg":"trace[1334961841] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1038; }","duration":"765.368299ms","start":"2026-06-02T06:55:56.222962Z","end":"2026-06-02T06:55:56.98833Z","steps":["trace[1334961841] 'agreement among raft nodes before linearized reading' (duration: 765.225326ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:55:56.988362Z","caller":"traceutil/trace.go:171","msg":"trace[1851099117] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1038; }","duration":"407.400841ms","start":"2026-06-02T06:55:56.58094Z","end":"2026-06-02T06:55:56.988341Z","steps":["trace[1851099117] 'agreement among raft nodes before linearized reading' (duration: 407.167926ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:55:56.98838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:55:56.222949Z","time spent":"765.422281ms","remote":"127.0.0.1:43846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-02T06:55:56.988405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:55:56.580916Z","time spent":"407.480573ms","remote":"127.0.0.1:43846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-02T06:55:56.988405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:55:56.391595Z","time spent":"596.680022ms","remote":"127.0.0.1:43846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T06:55:56.988514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.422244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T06:55:56.988543Z","caller":"traceutil/trace.go:171","msg":"trace[2113646697] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1038; }","duration":"304.490565ms","start":"2026-06-02T06:55:56.684044Z","end":"2026-06-02T06:55:56.988535Z","steps":["trace[2113646697] 'agreement among raft nodes before linearized reading' (duration: 304.449774ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:55:56.988567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:55:56.684021Z","time spent":"304.540546ms","remote":"127.0.0.1:43604","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-06-02T06:55:56.988611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.741519ms","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-06-02T06:55:56.988645Z","caller":"traceutil/trace.go:171","msg":"trace[1109464639] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1038; }","duration":"407.79513ms","start":"2026-06-02T06:55:56.580839Z","end":"2026-06-02T06:55:56.988634Z","steps":["trace[1109464639] 'agreement among raft nodes before linearized reading' (duration: 407.712759ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:55:56.988671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:55:56.580828Z","time spent":"407.836731ms","remote":"127.0.0.1:43846","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-06-02T06:55:56.988887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.188177ms","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-06-02T06:55:56.988926Z","caller":"traceutil/trace.go:171","msg":"trace[242135260] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1038; }","duration":"428.262739ms","start":"2026-06-02T06:55:56.560653Z","end":"2026-06-02T06:55:56.988916Z","steps":["trace[242135260] 'agreement among raft nodes before linearized reading' (duration: 428.102855ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:55:56.988953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.177856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-02T06:55:56.988962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:55:56.560637Z","time spent":"428.314699ms","remote":"127.0.0.1:43846","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-06-02T06:55:56.98899Z","caller":"traceutil/trace.go:171","msg":"trace[2043352734] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:1038; }","duration":"428.258438ms","start":"2026-06-02T06:55:56.560722Z","end":"2026-06-02T06:55:56.98898Z","steps":["trace[2043352734] 'agreement among raft nodes before linearized reading' (duration: 428.195417ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:55:56.989023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:55:56.560704Z","time spent":"428.312019ms","remote":"127.0.0.1:43740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true "} {"level":"info","ts":"2026-06-02T06:55:57.034402Z","caller":"traceutil/trace.go:171","msg":"trace[397230449] transaction","detail":"{read_only:false; response_revision:1039; number_of_response:1; }","duration":"332.310396ms","start":"2026-06-02T06:55:56.702066Z","end":"2026-06-02T06:55:57.034376Z","steps":["trace[397230449] 'process raft request' (duration: 331.726733ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:55:57.03456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:55:56.702042Z","time spent":"332.422458ms","remote":"127.0.0.1:43846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T06:56:00.606529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"687.101214ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-06-02T06:56:14.944347Z","caller":"traceutil/trace.go:171","msg":"trace[899461894] transaction","detail":"{read_only:false; response_revision:1148; number_of_response:1; }","duration":"100.618996ms","start":"2026-06-02T06:56:14.843703Z","end":"2026-06-02T06:56:14.944322Z","steps":["trace[899461894] 'process raft request' (duration: 100.206187ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:56:17.364787Z","caller":"traceutil/trace.go:171","msg":"trace[846813887] linearizableReadLoop","detail":"{readStateIndex:1203; appliedIndex:1202; }","duration":"353.717844ms","start":"2026-06-02T06:56:17.01104Z","end":"2026-06-02T06:56:17.364758Z","steps":["trace[846813887] 'read index received' (duration: 353.632242ms)","trace[846813887] 'applied index is now lower than readState.Index' (duration: 84.372µs)"],"step_count":2} {"level":"info","ts":"2026-06-02T06:56:17.36494Z","caller":"traceutil/trace.go:171","msg":"trace[1231594149] transaction","detail":"{read_only:false; response_revision:1155; number_of_response:1; }","duration":"415.877308ms","start":"2026-06-02T06:56:16.949043Z","end":"2026-06-02T06:56:17.364921Z","steps":["trace[1231594149] 'process raft request' (duration: 415.542551ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:56:17.365138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:56:16.949016Z","time spent":"415.962429ms","remote":"127.0.0.1:43846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T06:56:17.365283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.435305ms","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-06-02T06:56:17.365341Z","caller":"traceutil/trace.go:171","msg":"trace[1318461629] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1155; }","duration":"159.530808ms","start":"2026-06-02T06:56:17.205794Z","end":"2026-06-02T06:56:17.365325Z","steps":["trace[1318461629] 'agreement among raft nodes before linearized reading' (duration: 159.349654ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:56:17.365479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.443691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T06:56:17.365521Z","caller":"traceutil/trace.go:171","msg":"trace[646886931] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1155; }","duration":"354.519711ms","start":"2026-06-02T06:56:17.010988Z","end":"2026-06-02T06:56:17.365508Z","steps":["trace[646886931] 'agreement among raft nodes before linearized reading' (duration: 354.45745ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:56:17.365559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:56:17.010969Z","time spent":"354.579313ms","remote":"127.0.0.1:43604","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-06-02T06:56:17.365894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.702179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-06-02T06:56:17.365943Z","caller":"traceutil/trace.go:171","msg":"trace[1232521126] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1155; }","duration":"106.764771ms","start":"2026-06-02T06:56:17.259164Z","end":"2026-06-02T06:56:17.365929Z","steps":["trace[1232521126] 'agreement among raft nodes before linearized reading' (duration: 106.634578ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:56:17.366055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.77758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-06-02T06:56:17.366104Z","caller":"traceutil/trace.go:171","msg":"trace[1640071426] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1155; }","duration":"116.924773ms","start":"2026-06-02T06:56:17.249166Z","end":"2026-06-02T06:56:17.366091Z","steps":["trace[1640071426] 'agreement among raft nodes before linearized reading' (duration: 116.79788ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:56:29.499798Z","caller":"traceutil/trace.go:171","msg":"trace[1506821942] transaction","detail":"{read_only:false; response_revision:1195; number_of_response:1; }","duration":"100.982914ms","start":"2026-06-02T06:56:29.398791Z","end":"2026-06-02T06:56:29.499773Z","steps":["trace[1506821942] 'process raft request' (duration: 100.851411ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:56:30.027827Z","caller":"traceutil/trace.go:171","msg":"trace[539189476] linearizableReadLoop","detail":"{readStateIndex:1246; appliedIndex:1244; }","duration":"533.799012ms","start":"2026-06-02T06:56:29.494011Z","end":"2026-06-02T06:56:30.02781Z","steps":["trace[539189476] 'read index received' (duration: 5.621973ms)","trace[539189476] 'applied index is now lower than readState.Index' (duration: 528.176349ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T06:56:30.028131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"508.097939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-06-02T06:56:30.028199Z","caller":"traceutil/trace.go:171","msg":"trace[1701499282] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1196; }","duration":"508.197441ms","start":"2026-06-02T06:56:29.519989Z","end":"2026-06-02T06:56:30.028187Z","steps":["trace[1701499282] 'agreement among raft nodes before linearized reading' (duration: 508.027467ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:56:30.028211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"523.057657ms","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-06-02T06:56:30.028227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:56:29.519979Z","time spent":"508.243393ms","remote":"127.0.0.1:43846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-02T06:56:30.028245Z","caller":"traceutil/trace.go:171","msg":"trace[668890328] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1196; }","duration":"523.139199ms","start":"2026-06-02T06:56:29.505096Z","end":"2026-06-02T06:56:30.028235Z","steps":["trace[668890328] 'agreement among raft nodes before linearized reading' (duration: 522.892964ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:56:30.028254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"534.198101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-06-02T06:56:30.028305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"512.877885ms","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-06-02T06:56:30.028362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"507.607179ms","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-06-02T06:56:30.028361Z","caller":"traceutil/trace.go:171","msg":"trace[279669918] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1196; }","duration":"534.363114ms","start":"2026-06-02T06:56:29.493972Z","end":"2026-06-02T06:56:30.028335Z","steps":["trace[279669918] 'agreement among raft nodes before linearized reading' (duration: 533.982706ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:56:30.028389Z","caller":"traceutil/trace.go:171","msg":"trace[607071077] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1196; }","duration":"507.644389ms","start":"2026-06-02T06:56:29.520736Z","end":"2026-06-02T06:56:30.02838Z","steps":["trace[607071077] 'agreement among raft nodes before linearized reading' (duration: 507.564227ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:56:30.028407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:56:29.520729Z","time spent":"507.67394ms","remote":"127.0.0.1:43846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-02T06:56:30.028401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:56:29.505079Z","time spent":"523.18381ms","remote":"127.0.0.1:43846","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-06-02T06:56:30.028393Z","caller":"traceutil/trace.go:171","msg":"trace[385591570] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1196; }","duration":"513.012397ms","start":"2026-06-02T06:56:29.51536Z","end":"2026-06-02T06:56:30.028372Z","steps":["trace[385591570] 'agreement among raft nodes before linearized reading' (duration: 512.68405ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:56:30.028424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:56:29.493957Z","time spent":"534.456837ms","remote":"127.0.0.1:43846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-02T06:56:30.028451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:56:29.515349Z","time spent":"513.092719ms","remote":"127.0.0.1:43846","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-06-02T06:56:30.028589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.121617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T06:56:30.028609Z","caller":"traceutil/trace.go:171","msg":"trace[2039978499] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:1196; }","duration":"101.187289ms","start":"2026-06-02T06:56:29.927416Z","end":"2026-06-02T06:56:30.028603Z","steps":["trace[2039978499] 'agreement among raft nodes before linearized reading' (duration: 101.143387ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:56:30.02867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.030765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T06:56:30.028683Z","caller":"traceutil/trace.go:171","msg":"trace[2063916544] transaction","detail":"{read_only:false; response_revision:1196; number_of_response:1; }","duration":"626.69743ms","start":"2026-06-02T06:56:29.401285Z","end":"2026-06-02T06:56:30.027982Z","steps":["trace[2063916544] 'process raft request' (duration: 626.433714ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:56:30.028837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:56:29.401262Z","time spent":"627.511488ms","remote":"127.0.0.1:43846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T06:56:30.028701Z","caller":"traceutil/trace.go:171","msg":"trace[248298077] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1196; }","duration":"335.079437ms","start":"2026-06-02T06:56:29.693611Z","end":"2026-06-02T06:56:30.02869Z","steps":["trace[248298077] 'agreement among raft nodes before linearized reading' (duration: 335.034346ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:56:30.028935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:56:29.693599Z","time spent":"335.325392ms","remote":"127.0.0.1:43604","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-06-02T06:56:32.540546Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18221738395474072674,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-02T06:56:32.828972Z","caller":"traceutil/trace.go:171","msg":"trace[95479678] linearizableReadLoop","detail":"{readStateIndex:1253; appliedIndex:1252; }","duration":"789.253214ms","start":"2026-06-02T06:56:32.039678Z","end":"2026-06-02T06:56:32.828931Z","steps":["trace[95479678] 'read index received' (duration: 788.934627ms)","trace[95479678] 'applied index is now lower than readState.Index' (duration: 317.107µs)"],"step_count":2} {"level":"info","ts":"2026-06-02T06:56:32.829361Z","caller":"traceutil/trace.go:171","msg":"trace[2031618200] transaction","detail":"{read_only:false; response_revision:1202; number_of_response:1; }","duration":"794.771104ms","start":"2026-06-02T06:56:32.034571Z","end":"2026-06-02T06:56:32.829342Z","steps":["trace[2031618200] 'process raft request' (duration: 794.153311ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:56:32.829701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"782.541416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-06-02T06:56:32.829709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"789.967369ms","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-06-02T06:56:32.829758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.52224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T06:56:32.829763Z","caller":"traceutil/trace.go:171","msg":"trace[1804945500] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1202; }","duration":"782.628708ms","start":"2026-06-02T06:56:32.047118Z","end":"2026-06-02T06:56:32.829746Z","steps":["trace[1804945500] 'agreement among raft nodes before linearized reading' (duration: 782.424163ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:56:32.829792Z","caller":"traceutil/trace.go:171","msg":"trace[976099041] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1202; }","duration":"124.584952ms","start":"2026-06-02T06:56:32.705198Z","end":"2026-06-02T06:56:32.829783Z","steps":["trace[976099041] 'agreement among raft nodes before linearized reading' (duration: 124.53804ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:56:32.829804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:56:32.047108Z","time spent":"782.68799ms","remote":"127.0.0.1:43846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-02T06:56:32.829685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"787.119247ms","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-06-02T06:56:32.829877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"782.728601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-06-02T06:56:32.829976Z","caller":"traceutil/trace.go:171","msg":"trace[1414780041] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1202; }","duration":"782.860263ms","start":"2026-06-02T06:56:32.047095Z","end":"2026-06-02T06:56:32.829955Z","steps":["trace[1414780041] 'agreement among raft nodes before linearized reading' (duration: 782.494095ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:56:32.830032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:56:32.047088Z","time spent":"782.935396ms","remote":"127.0.0.1:43846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-06-02T06:56:32.829827Z","caller":"traceutil/trace.go:171","msg":"trace[1955780267] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1202; }","duration":"790.151294ms","start":"2026-06-02T06:56:32.039643Z","end":"2026-06-02T06:56:32.829795Z","steps":["trace[1955780267] 'agreement among raft nodes before linearized reading' (duration: 789.733265ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:56:32.830231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:56:32.039627Z","time spent":"790.591554ms","remote":"127.0.0.1:43846","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-06-02T06:56:32.829705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"779.128791ms","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-06-02T06:56:32.83036Z","caller":"traceutil/trace.go:171","msg":"trace[1048178258] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1202; }","duration":"779.802487ms","start":"2026-06-02T06:56:32.050534Z","end":"2026-06-02T06:56:32.830336Z","steps":["trace[1048178258] 'agreement among raft nodes before linearized reading' (duration: 778.984899ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:56:32.830393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:56:32.050526Z","time spent":"779.857558ms","remote":"127.0.0.1:43846","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-06-02T06:56:32.830125Z","caller":"traceutil/trace.go:171","msg":"trace[1917353808] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1202; }","duration":"787.437434ms","start":"2026-06-02T06:56:32.042493Z","end":"2026-06-02T06:56:32.829931Z","steps":["trace[1917353808] 'agreement among raft nodes before linearized reading' (duration: 787.010074ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:56:32.830499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:56:32.034544Z","time spent":"794.862616ms","remote":"127.0.0.1:43846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T06:56:32.830685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:56:32.042473Z","time spent":"788.191322ms","remote":"127.0.0.1:43846","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-06-02T06:56:33.412128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.512755ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-02T06:56:33.412202Z","caller":"traceutil/trace.go:171","msg":"trace[1225049847] transaction","detail":"{read_only:false; response_revision:1203; number_of_response:1; }","duration":"577.981052ms","start":"2026-06-02T06:56:32.834209Z","end":"2026-06-02T06:56:33.41219Z","steps":["trace[1225049847] 'process raft request' (duration: 154.343854ms)","trace[1225049847] 'compare' (duration: 423.356821ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T06:56:33.412234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:56:32.834197Z","time spent":"578.022852ms","remote":"127.0.0.1:43846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T06:56:33.417598Z","caller":"traceutil/trace.go:171","msg":"trace[2038202176] transaction","detail":"{read_only:false; response_revision:1204; number_of_response:1; }","duration":"583.122665ms","start":"2026-06-02T06:56:32.834451Z","end":"2026-06-02T06:56:33.417573Z","steps":["trace[2038202176] 'process raft request' (duration: 582.936061ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:56:33.417692Z","caller":"traceutil/trace.go:171","msg":"trace[2090040885] transaction","detail":"{read_only:false; response_revision:1205; number_of_response:1; }","duration":"582.056051ms","start":"2026-06-02T06:56:32.835607Z","end":"2026-06-02T06:56:33.417663Z","steps":["trace[2090040885] 'process raft request' (duration: 581.892257ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:56:33.417757Z","caller":"traceutil/trace.go:171","msg":"trace[1767033108] transaction","detail":"{read_only:false; response_revision:1206; number_of_response:1; }","duration":"580.413055ms","start":"2026-06-02T06:56:32.837337Z","end":"2026-06-02T06:56:33.41775Z","steps":["trace[1767033108] 'process raft request' (duration: 580.205891ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:56:33.417748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:56:32.834411Z","time spent":"583.264938ms","remote":"127.0.0.1:43846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T06:56:33.417813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:56:32.837327Z","time spent":"580.454916ms","remote":"127.0.0.1:43846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T06:56:33.417827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:56:32.835595Z","time spent":"582.167963ms","remote":"127.0.0.1:43846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T06:56:33.41772Z","caller":"traceutil/trace.go:171","msg":"trace[2109108175] transaction","detail":"{read_only:false; response_revision:1207; number_of_response:1; }","duration":"579.895883ms","start":"2026-06-02T06:56:32.837808Z","end":"2026-06-02T06:56:33.417704Z","steps":["trace[2109108175] 'process raft request' (duration: 579.848222ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:56:33.417965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:56:32.837798Z","time spent":"580.093469ms","remote":"127.0.0.1:43846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T06:56:33.804885Z","caller":"traceutil/trace.go:171","msg":"trace[522837218] linearizableReadLoop","detail":"{readStateIndex:1259; appliedIndex:1258; }","duration":"174.010766ms","start":"2026-06-02T06:56:33.630846Z","end":"2026-06-02T06:56:33.804857Z","steps":["trace[522837218] 'read index received' (duration: 173.77143ms)","trace[522837218] 'applied index is now lower than readState.Index' (duration: 238.366µs)"],"step_count":2} {"level":"info","ts":"2026-06-02T06:56:33.804922Z","caller":"traceutil/trace.go:171","msg":"trace[539316259] transaction","detail":"{read_only:false; response_revision:1208; number_of_response:1; }","duration":"174.295152ms","start":"2026-06-02T06:56:33.630591Z","end":"2026-06-02T06:56:33.804887Z","steps":["trace[539316259] 'process raft request' (duration: 174.098176ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:56:33.8051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.21383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-02T06:56:33.805132Z","caller":"traceutil/trace.go:171","msg":"trace[487638789] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1208; }","duration":"174.336483ms","start":"2026-06-02T06:56:33.630784Z","end":"2026-06-02T06:56:33.805121Z","steps":["trace[487638789] 'agreement among raft nodes before linearized reading' (duration: 174.175949ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:56:34.938953Z","caller":"traceutil/trace.go:171","msg":"trace[2145048652] transaction","detail":"{read_only:false; response_revision:1209; number_of_response:1; }","duration":"101.971365ms","start":"2026-06-02T06:56:34.836953Z","end":"2026-06-02T06:56:34.938925Z","steps":["trace[2145048652] 'process raft request' (duration: 101.707299ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:56:35.632786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.269546ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-06-02T06:56:35.632882Z","caller":"traceutil/trace.go:171","msg":"trace[732495269] linearizableReadLoop","detail":"{readStateIndex:1261; appliedIndex:1260; }","duration":"217.085279ms","start":"2026-06-02T06:56:35.415778Z","end":"2026-06-02T06:56:35.632864Z","steps":["trace[732495269] 'read index received' (duration: 44.081µs)","trace[732495269] 'applied index is now lower than readState.Index' (duration: 217.039878ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T06:56:35.632957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:56:34.940996Z","time spent":"691.958241ms","remote":"127.0.0.1:43628","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-06-02T06:56:35.633608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.878603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-06-02T06:56:35.633686Z","caller":"traceutil/trace.go:171","msg":"trace[24616637] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1209; }","duration":"211.004496ms","start":"2026-06-02T06:56:35.422664Z","end":"2026-06-02T06:56:35.633669Z","steps":["trace[24616637] 'agreement among raft nodes before linearized reading' (duration: 210.855222ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:56:35.633716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.907724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"warn","ts":"2026-06-02T06:56:35.63361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.885903ms","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-06-02T06:56:35.633783Z","caller":"traceutil/trace.go:171","msg":"trace[263016367] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1209; }","duration":"211.008186ms","start":"2026-06-02T06:56:35.422757Z","end":"2026-06-02T06:56:35.633766Z","steps":["trace[263016367] 'agreement among raft nodes before linearized reading' (duration: 210.787691ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:56:35.633805Z","caller":"traceutil/trace.go:171","msg":"trace[896596300] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1209; }","duration":"211.143938ms","start":"2026-06-02T06:56:35.422649Z","end":"2026-06-02T06:56:35.633792Z","steps":["trace[896596300] 'agreement among raft nodes before linearized reading' (duration: 210.809101ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:56:35.63381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.079187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-06-02T06:56:35.63388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.100971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-06-02T06:56:35.6339Z","caller":"traceutil/trace.go:171","msg":"trace[8162565] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1209; }","duration":"211.21644ms","start":"2026-06-02T06:56:35.422664Z","end":"2026-06-02T06:56:35.63388Z","steps":["trace[8162565] 'agreement among raft nodes before linearized reading' (duration: 210.980015ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:56:35.633924Z","caller":"traceutil/trace.go:171","msg":"trace[644593289] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1209; }","duration":"218.203143ms","start":"2026-06-02T06:56:35.415709Z","end":"2026-06-02T06:56:35.633912Z","steps":["trace[644593289] 'agreement among raft nodes before linearized reading' (duration: 217.423706ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:56:35.848794Z","caller":"traceutil/trace.go:171","msg":"trace[1939858028] transaction","detail":"{read_only:false; response_revision:1213; number_of_response:1; }","duration":"210.333581ms","start":"2026-06-02T06:56:35.638438Z","end":"2026-06-02T06:56:35.848772Z","steps":["trace[1939858028] 'process raft request' (duration: 210.28526ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:56:35.848821Z","caller":"traceutil/trace.go:171","msg":"trace[951404235] transaction","detail":"{read_only:false; response_revision:1211; number_of_response:1; }","duration":"210.810231ms","start":"2026-06-02T06:56:35.637979Z","end":"2026-06-02T06:56:35.84879Z","steps":["trace[951404235] 'process raft request' (duration: 210.574806ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:56:35.848876Z","caller":"traceutil/trace.go:171","msg":"trace[251928575] transaction","detail":"{read_only:false; response_revision:1212; number_of_response:1; }","duration":"210.75602ms","start":"2026-06-02T06:56:35.638078Z","end":"2026-06-02T06:56:35.848834Z","steps":["trace[251928575] 'process raft request' (duration: 210.617957ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:56:35.848981Z","caller":"traceutil/trace.go:171","msg":"trace[1127466194] linearizableReadLoop","detail":"{readStateIndex:1267; appliedIndex:1261; }","duration":"151.611404ms","start":"2026-06-02T06:56:35.697359Z","end":"2026-06-02T06:56:35.84897Z","steps":["trace[1127466194] 'read index received' (duration: 30.761835ms)","trace[1127466194] 'applied index is now lower than readState.Index' (duration: 120.848979ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T06:56:35.849007Z","caller":"traceutil/trace.go:171","msg":"trace[1609710393] transaction","detail":"{read_only:false; response_revision:1214; number_of_response:1; }","duration":"210.488915ms","start":"2026-06-02T06:56:35.638503Z","end":"2026-06-02T06:56:35.848992Z","steps":["trace[1609710393] 'process raft request' (duration: 210.244849ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:56:35.849039Z","caller":"traceutil/trace.go:171","msg":"trace[948684837] transaction","detail":"{read_only:false; response_revision:1215; number_of_response:1; }","duration":"210.351342ms","start":"2026-06-02T06:56:35.638683Z","end":"2026-06-02T06:56:35.849034Z","steps":["trace[948684837] 'process raft request' (duration: 210.236799ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:56:35.849622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.193601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-06-02T06:56:35.849651Z","caller":"traceutil/trace.go:171","msg":"trace[109913607] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:1215; }","duration":"118.265193ms","start":"2026-06-02T06:56:35.731378Z","end":"2026-06-02T06:56:35.849643Z","steps":["trace[109913607] 'agreement among raft nodes before linearized reading' (duration: 118.216652ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:56:35.849092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.720396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T06:56:35.849756Z","caller":"traceutil/trace.go:171","msg":"trace[238966878] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1215; }","duration":"152.423561ms","start":"2026-06-02T06:56:35.697304Z","end":"2026-06-02T06:56:35.849728Z","steps":["trace[238966878] 'agreement among raft nodes before linearized reading' (duration: 151.697066ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:56:36.169218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.378855ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-02T06:56:36.169327Z","caller":"traceutil/trace.go:171","msg":"trace[865711641] linearizableReadLoop","detail":"{readStateIndex:1268; appliedIndex:1267; }","duration":"114.398678ms","start":"2026-06-02T06:56:36.054912Z","end":"2026-06-02T06:56:36.16931Z","steps":["trace[865711641] 'read index received' (duration: 26.9µs)","trace[865711641] 'applied index is now lower than readState.Index' (duration: 114.370388ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T06:56:36.169491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.572531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:5330"} {"level":"info","ts":"2026-06-02T06:56:36.169529Z","caller":"traceutil/trace.go:171","msg":"trace[393915960] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:1216; }","duration":"114.640713ms","start":"2026-06-02T06:56:36.05488Z","end":"2026-06-02T06:56:36.16952Z","steps":["trace[393915960] 'agreement among raft nodes before linearized reading' (duration: 114.47712ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:56:36.16968Z","caller":"traceutil/trace.go:171","msg":"trace[1773159766] transaction","detail":"{read_only:false; response_revision:1216; number_of_response:1; }","duration":"276.270417ms","start":"2026-06-02T06:56:35.893386Z","end":"2026-06-02T06:56:36.169656Z","steps":["trace[1773159766] 'process raft request' (duration: 79.38785ms)","trace[1773159766] 'compare' (duration: 196.172041ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T06:56:36.46687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.467456ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-02T06:56:36.466988Z","caller":"traceutil/trace.go:171","msg":"trace[915654399] linearizableReadLoop","detail":"{readStateIndex:1269; appliedIndex:1268; }","duration":"210.034565ms","start":"2026-06-02T06:56:36.256936Z","end":"2026-06-02T06:56:36.46697Z","steps":["trace[915654399] 'read index received' (duration: 45.323783ms)","trace[915654399] 'applied index is now lower than readState.Index' (duration: 164.708402ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T06:56:36.467047Z","caller":"traceutil/trace.go:171","msg":"trace[1743057532] transaction","detail":"{read_only:false; response_revision:1217; number_of_response:1; }","duration":"238.218223ms","start":"2026-06-02T06:56:36.228769Z","end":"2026-06-02T06:56:36.466988Z","steps":["trace[1743057532] 'process raft request' (duration: 73.561753ms)","trace[1743057532] 'compare' (duration: 164.128829ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T06:56:36.467322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.385823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T06:56:36.467365Z","caller":"traceutil/trace.go:171","msg":"trace[845180791] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:1217; }","duration":"210.502995ms","start":"2026-06-02T06:56:36.25685Z","end":"2026-06-02T06:56:36.467353Z","steps":["trace[845180791] 'agreement among raft nodes before linearized reading' (duration: 210.168168ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:56:37.972126Z","caller":"traceutil/trace.go:171","msg":"trace[833873510] transaction","detail":"{read_only:false; response_revision:1220; number_of_response:1; }","duration":"103.029099ms","start":"2026-06-02T06:56:37.869049Z","end":"2026-06-02T06:56:37.972078Z","steps":["trace[833873510] 'process raft request' (duration: 102.6155ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:56:38.049364Z","caller":"traceutil/trace.go:171","msg":"trace[593249717] transaction","detail":"{read_only:false; response_revision:1222; number_of_response:1; }","duration":"179.861384ms","start":"2026-06-02T06:56:37.869462Z","end":"2026-06-02T06:56:38.049323Z","steps":["trace[593249717] 'process raft request' (duration: 179.65483ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:56:38.049838Z","caller":"traceutil/trace.go:171","msg":"trace[360521796] transaction","detail":"{read_only:false; response_revision:1221; number_of_response:1; }","duration":"180.496088ms","start":"2026-06-02T06:56:37.869326Z","end":"2026-06-02T06:56:38.049822Z","steps":["trace[360521796] 'process raft request' (duration: 179.609719ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:56:38.049858Z","caller":"traceutil/trace.go:171","msg":"trace[1630642685] transaction","detail":"{read_only:false; response_revision:1223; number_of_response:1; }","duration":"179.689129ms","start":"2026-06-02T06:56:37.870135Z","end":"2026-06-02T06:56:38.049824Z","steps":["trace[1630642685] 'process raft request' (duration: 179.028805ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:56:38.050109Z","caller":"traceutil/trace.go:171","msg":"trace[1595439222] transaction","detail":"{read_only:false; response_revision:1224; number_of_response:1; }","duration":"179.399653ms","start":"2026-06-02T06:56:37.870694Z","end":"2026-06-02T06:56:38.050094Z","steps":["trace[1595439222] 'process raft request' (duration: 178.504453ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:56:45.280793Z","caller":"traceutil/trace.go:171","msg":"trace[314152252] transaction","detail":"{read_only:false; response_revision:1296; number_of_response:1; }","duration":"559.118768ms","start":"2026-06-02T06:56:44.721656Z","end":"2026-06-02T06:56:45.280775Z","steps":["trace[314152252] 'process raft request' (duration: 541.691917ms)","trace[314152252] 'compare' (duration: 17.144835ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T06:56:45.280935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:56:44.721632Z","time spent":"559.230731ms","remote":"127.0.0.1:43846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T06:56:45.281014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.048357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.20\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-06-02T06:56:45.281088Z","caller":"traceutil/trace.go:171","msg":"trace[2016768805] range","detail":"{range_begin:/registry/masterleases/199.19.213.20; range_end:; response_count:1; response_revision:1296; }","duration":"424.20677ms","start":"2026-06-02T06:56:44.856869Z","end":"2026-06-02T06:56:45.281076Z","steps":["trace[2016768805] 'agreement among raft nodes before linearized reading' (duration: 423.968875ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:56:45.281129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:56:44.856846Z","time spent":"424.271632ms","remote":"127.0.0.1:43628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.19.213.20\" "} {"level":"warn","ts":"2026-06-02T06:56:45.280709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.006221ms","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-06-02T06:56:45.281305Z","caller":"traceutil/trace.go:171","msg":"trace[700100532] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1295; }","duration":"354.746097ms","start":"2026-06-02T06:56:44.926545Z","end":"2026-06-02T06:56:45.281291Z","steps":["trace[700100532] 'range keys from in-memory index tree' (duration: 353.99468ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:56:45.280784Z","caller":"traceutil/trace.go:171","msg":"trace[420806140] linearizableReadLoop","detail":"{readStateIndex:1349; appliedIndex:1348; }","duration":"423.805432ms","start":"2026-06-02T06:56:44.856947Z","end":"2026-06-02T06:56:45.280752Z","steps":["trace[420806140] 'read index received' (duration: 406.430381ms)","trace[420806140] 'applied index is now lower than readState.Index' (duration: 17.367291ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T06:56:45.340373Z","caller":"traceutil/trace.go:171","msg":"trace[1621114969] transaction","detail":"{read_only:false; response_revision:1297; number_of_response:1; }","duration":"299.527717ms","start":"2026-06-02T06:56:45.040816Z","end":"2026-06-02T06:56:45.340344Z","steps":["trace[1621114969] 'process raft request' (duration: 299.365443ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:56:45.340816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.66206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-provisioner-69cb97fb6-h58zl\" ","response":"range_response_count:1 size:12641"} {"level":"info","ts":"2026-06-02T06:56:45.34087Z","caller":"traceutil/trace.go:171","msg":"trace[1670788311] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-provisioner-69cb97fb6-h58zl; range_end:; response_count:1; response_revision:1297; }","duration":"294.759522ms","start":"2026-06-02T06:56:45.046095Z","end":"2026-06-02T06:56:45.340855Z","steps":["trace[1670788311] 'agreement among raft nodes before linearized reading' (duration: 294.19853ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:56:45.340793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.664112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-02T06:56:45.341015Z","caller":"traceutil/trace.go:171","msg":"trace[1482672775] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:1297; }","duration":"243.824766ms","start":"2026-06-02T06:56:45.097083Z","end":"2026-06-02T06:56:45.340907Z","steps":["trace[1482672775] 'agreement among raft nodes before linearized reading' (duration: 243.543129ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:56:45.893325Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18221738395474072845,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-02T06:56:46.121913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.992085ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"warn","ts":"2026-06-02T06:56:46.122735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:56:45.282427Z","time spent":"840.301633ms","remote":"127.0.0.1:43628","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-06-02T06:56:46.130999Z","caller":"traceutil/trace.go:171","msg":"trace[343795864] linearizableReadLoop","detail":"{readStateIndex:1352; appliedIndex:1350; }","duration":"738.309177ms","start":"2026-06-02T06:56:45.392677Z","end":"2026-06-02T06:56:46.130986Z","steps":["trace[343795864] 'read index received' (duration: 523.18456ms)","trace[343795864] 'applied index is now lower than readState.Index' (duration: 215.123907ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T06:56:46.131048Z","caller":"traceutil/trace.go:171","msg":"trace[821630080] transaction","detail":"{read_only:false; response_revision:1298; number_of_response:1; }","duration":"780.132324ms","start":"2026-06-02T06:56:45.35091Z","end":"2026-06-02T06:56:46.131042Z","steps":["trace[821630080] 'process raft request' (duration: 779.992441ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:56:46.131152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:56:45.350889Z","time spent":"780.198315ms","remote":"127.0.0.1:43758","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":13708,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T06:56:46.131321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"738.622324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-06-02T06:56:46.131415Z","caller":"traceutil/trace.go:171","msg":"trace[1844394564] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1298; }","duration":"738.758567ms","start":"2026-06-02T06:56:45.392636Z","end":"2026-06-02T06:56:46.131394Z","steps":["trace[1844394564] 'agreement among raft nodes before linearized reading' (duration: 738.511091ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:56:46.131474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:56:45.392621Z","time spent":"738.840989ms","remote":"127.0.0.1:43846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":497,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-06-02T06:56:46.134616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.775332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T06:56:46.134653Z","caller":"traceutil/trace.go:171","msg":"trace[1441475126] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1299; }","duration":"449.858184ms","start":"2026-06-02T06:56:45.684785Z","end":"2026-06-02T06:56:46.134643Z","steps":["trace[1441475126] 'agreement among raft nodes before linearized reading' (duration: 449.784502ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:56:46.134713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:56:45.684759Z","time spent":"449.947835ms","remote":"127.0.0.1:43604","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-06-02T06:56:46.134911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.02825ms","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-06-02T06:56:46.134977Z","caller":"traceutil/trace.go:171","msg":"trace[1577938094] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1299; }","duration":"135.137353ms","start":"2026-06-02T06:56:45.999825Z","end":"2026-06-02T06:56:46.134962Z","steps":["trace[1577938094] 'agreement among raft nodes before linearized reading' (duration: 134.938208ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:56:46.137794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.647525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T06:56:46.137831Z","caller":"traceutil/trace.go:171","msg":"trace[1128048586] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:1299; }","duration":"307.731317ms","start":"2026-06-02T06:56:45.83009Z","end":"2026-06-02T06:56:46.137822Z","steps":["trace[1128048586] 'agreement among raft nodes before linearized reading' (duration: 304.732492ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:56:46.137964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:56:45.830069Z","time spent":"307.88492ms","remote":"127.0.0.1:56732","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true "} {"level":"info","ts":"2026-06-02T07:00:26.80929Z","caller":"traceutil/trace.go:171","msg":"trace[406269512] linearizableReadLoop","detail":"{readStateIndex:2438; appliedIndex:2437; }","duration":"124.19309ms","start":"2026-06-02T07:00:26.685053Z","end":"2026-06-02T07:00:26.809246Z","steps":["trace[406269512] 'read index received' (duration: 123.854461ms)","trace[406269512] 'applied index is now lower than readState.Index' (duration: 336.899µs)"],"step_count":2} {"level":"warn","ts":"2026-06-02T07:00:26.809539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.451846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T07:00:26.809594Z","caller":"traceutil/trace.go:171","msg":"trace[915621750] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2339; }","duration":"124.563009ms","start":"2026-06-02T07:00:26.685011Z","end":"2026-06-02T07:00:26.809574Z","steps":["trace[915621750] 'agreement among raft nodes before linearized reading' (duration: 124.436526ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T07:00:26.809744Z","caller":"traceutil/trace.go:171","msg":"trace[848319329] transaction","detail":"{read_only:false; response_revision:2339; number_of_response:1; }","duration":"228.900996ms","start":"2026-06-02T07:00:26.580787Z","end":"2026-06-02T07:00:26.809688Z","steps":["trace[848319329] 'process raft request' (duration: 228.178968ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:00:33.729856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.293759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T07:00:33.729954Z","caller":"traceutil/trace.go:171","msg":"trace[1439402011] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:2378; }","duration":"202.440172ms","start":"2026-06-02T07:00:33.527491Z","end":"2026-06-02T07:00:33.729931Z","steps":["trace[1439402011] 'count revisions from in-memory index tree' (duration: 202.219357ms)"],"step_count":1}