{"level":"warn","ts":"2026-04-02T06:05:31.758399Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-02T06:05:31.758504Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.96: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.96:2380","--initial-cluster=instance=https://199.19.213.96:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.96:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.96:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-02T06:05:31.758591Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-02T06:05:31.758683Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.96:2380"]} {"level":"info","ts":"2026-04-02T06:05:31.758709Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-02T06:05:31.759659Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.96:2379"]} {"level":"info","ts":"2026-04-02T06:05:31.760084Z","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.96:2380"],"listen-peer-urls":["https://199.19.213.96:2380"],"advertise-client-urls":["https://199.19.213.96:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.96:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.96:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-02T06:05:31.765905Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.485942ms"} {"level":"info","ts":"2026-04-02T06:05:31.782117Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"59b38ffafb07452b","cluster-id":"f6b359b7e8f93d01"} {"level":"info","ts":"2026-04-02T06:05:31.782211Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"59b38ffafb07452b switched to configuration voters=()"} {"level":"info","ts":"2026-04-02T06:05:31.782236Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"59b38ffafb07452b became follower at term 0"} {"level":"info","ts":"2026-04-02T06:05:31.782247Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 59b38ffafb07452b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-02T06:05:31.782256Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"59b38ffafb07452b became follower at term 1"} {"level":"info","ts":"2026-04-02T06:05:31.782296Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"59b38ffafb07452b switched to configuration voters=(6463668198322947371)"} {"level":"warn","ts":"2026-04-02T06:05:31.790289Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-02T06:05:31.795255Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-02T06:05:31.79753Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-02T06:05:31.800274Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"59b38ffafb07452b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-02T06:05:31.80111Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"59b38ffafb07452b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-02T06:05:31.801188Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-02T06:05:31.801228Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-02T06:05:31.80124Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-02T06:05:31.80163Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"59b38ffafb07452b switched to configuration voters=(6463668198322947371)"} {"level":"info","ts":"2026-04-02T06:05:31.801817Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"f6b359b7e8f93d01","local-member-id":"59b38ffafb07452b","added-peer-id":"59b38ffafb07452b","added-peer-peer-urls":["https://199.19.213.96:2380"]} {"level":"info","ts":"2026-04-02T06:05:31.805372Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-02T06:05:31.805523Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.96:2380"} {"level":"info","ts":"2026-04-02T06:05:31.805639Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.96:2380"} {"level":"info","ts":"2026-04-02T06:05:31.806475Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"59b38ffafb07452b","initial-advertise-peer-urls":["https://199.19.213.96:2380"],"listen-peer-urls":["https://199.19.213.96:2380"],"advertise-client-urls":["https://199.19.213.96:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.96:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-02T06:05:31.806513Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-02T06:05:32.683169Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"59b38ffafb07452b is starting a new election at term 1"} {"level":"info","ts":"2026-04-02T06:05:32.683238Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"59b38ffafb07452b became pre-candidate at term 1"} {"level":"info","ts":"2026-04-02T06:05:32.683283Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"59b38ffafb07452b received MsgPreVoteResp from 59b38ffafb07452b at term 1"} {"level":"info","ts":"2026-04-02T06:05:32.683301Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"59b38ffafb07452b became candidate at term 2"} {"level":"info","ts":"2026-04-02T06:05:32.683309Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"59b38ffafb07452b received MsgVoteResp from 59b38ffafb07452b at term 2"} {"level":"info","ts":"2026-04-02T06:05:32.683331Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"59b38ffafb07452b became leader at term 2"} {"level":"info","ts":"2026-04-02T06:05:32.683373Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 59b38ffafb07452b elected leader 59b38ffafb07452b at term 2"} {"level":"info","ts":"2026-04-02T06:05:32.688628Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-02T06:05:32.689703Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"59b38ffafb07452b","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.96:2379]}","request-path":"/0/members/59b38ffafb07452b/attributes","cluster-id":"f6b359b7e8f93d01","publish-timeout":"7s"} {"level":"info","ts":"2026-04-02T06:05:32.68976Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-02T06:05:32.690211Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"f6b359b7e8f93d01","local-member-id":"59b38ffafb07452b","cluster-version":"3.5"} {"level":"info","ts":"2026-04-02T06:05:32.690381Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-02T06:05:32.69044Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-02T06:05:32.690468Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-02T06:05:32.693152Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.96:2379"} {"level":"info","ts":"2026-04-02T06:05:32.694356Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-02T06:05:32.694399Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-02T06:05:32.69465Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-02T06:05:34.577621Z","caller":"traceutil/trace.go:171","msg":"trace[1960115525] transaction","detail":"{read_only:false; response_revision:2; number_of_response:1; }","duration":"485.44036ms","start":"2026-04-02T06:05:34.09215Z","end":"2026-04-02T06:05:34.57759Z","steps":["trace[1960115525] 'process raft request' (duration: 485.093283ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:05:34.578223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:05:34.092138Z","time spent":"485.584113ms","remote":"127.0.0.1:37418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":100,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T06:05:36.533361Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.955904806s","expected-duration":"1s"} {"level":"info","ts":"2026-04-02T06:05:36.534483Z","caller":"traceutil/trace.go:171","msg":"trace[5219554] linearizableReadLoop","detail":"{readStateIndex:6; appliedIndex:4; }","duration":"2.437880236s","start":"2026-04-02T06:05:34.096565Z","end":"2026-04-02T06:05:36.534445Z","steps":["trace[5219554] 'read index received' (duration: 480.90716ms)","trace[5219554] 'applied index is now lower than readState.Index' (duration: 1.956972026s)"],"step_count":2} {"level":"info","ts":"2026-04-02T06:05:36.534514Z","caller":"traceutil/trace.go:171","msg":"trace[689283595] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"2.371422854s","start":"2026-04-02T06:05:34.163066Z","end":"2026-04-02T06:05:36.534488Z","steps":["trace[689283595] 'process raft request' (duration: 2.371058987s)"],"step_count":1} {"level":"info","ts":"2026-04-02T06:05:36.534559Z","caller":"traceutil/trace.go:171","msg":"trace[79010963] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"2.363501987s","start":"2026-04-02T06:05:34.171011Z","end":"2026-04-02T06:05:36.534513Z","steps":["trace[79010963] 'process raft request' (duration: 2.363358924s)"],"step_count":1} {"level":"info","ts":"2026-04-02T06:05:36.534623Z","caller":"traceutil/trace.go:171","msg":"trace[866771761] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"2.37070358s","start":"2026-04-02T06:05:34.163774Z","end":"2026-04-02T06:05:36.534477Z","steps":["trace[866771761] 'process raft request' (duration: 2.370558197s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:05:36.534712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:05:34.171Z","time spent":"2.363640599s","remote":"127.0.0.1:37530","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3158,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T06:05:36.534725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:05:34.163724Z","time spent":"2.370933355s","remote":"127.0.0.1:37904","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":992,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T06:05:36.534691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:05:34.163036Z","time spent":"2.371553187s","remote":"127.0.0.1:37904","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":883,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T06:05:36.534894Z","caller":"traceutil/trace.go:171","msg":"trace[805363170] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"2.37170867s","start":"2026-04-02T06:05:34.163173Z","end":"2026-04-02T06:05:36.534881Z","steps":["trace[805363170] 'process raft request' (duration: 2.370996796s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:05:36.535022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:05:34.163159Z","time spent":"2.371799061s","remote":"127.0.0.1:37904","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T06:05:36.535051Z","caller":"traceutil/trace.go:171","msg":"trace[20167294] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"2.371461855s","start":"2026-04-02T06:05:34.163416Z","end":"2026-04-02T06:05:36.534878Z","steps":["trace[20167294] 'process raft request' (duration: 2.370789912s)"],"step_count":1} {"level":"info","ts":"2026-04-02T06:05:36.534896Z","caller":"traceutil/trace.go:171","msg":"trace[663101604] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"2.442159372s","start":"2026-04-02T06:05:34.092304Z","end":"2026-04-02T06:05:36.534463Z","steps":["trace[663101604] 'process raft request' (duration: 2.441396707s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:05:36.535103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:05:34.163335Z","time spent":"2.371740521s","remote":"127.0.0.1:37904","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":920,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T06:05:36.53519Z","caller":"traceutil/trace.go:171","msg":"trace[250236742] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"2.371618868s","start":"2026-04-02T06:05:34.16356Z","end":"2026-04-02T06:05:36.535179Z","steps":["trace[250236742] 'process raft request' (duration: 2.37070125s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:05:36.535114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.438446378s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-02T06:05:36.535203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:05:34.092277Z","time spent":"2.442826215s","remote":"127.0.0.1:37428","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":105,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T06:05:36.535297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:05:34.16355Z","time spent":"2.371675279s","remote":"127.0.0.1:37904","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":971,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T06:05:36.535372Z","caller":"traceutil/trace.go:171","msg":"trace[153748815] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"2.353494327s","start":"2026-04-02T06:05:34.181868Z","end":"2026-04-02T06:05:36.535362Z","steps":["trace[153748815] 'process raft request' (duration: 2.352541919s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:05:36.535639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:05:34.181858Z","time spent":"2.35364368s","remote":"127.0.0.1:37590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":849,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T06:05:36.534514Z","caller":"traceutil/trace.go:171","msg":"trace[540303342] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"2.372765751s","start":"2026-04-02T06:05:34.161711Z","end":"2026-04-02T06:05:36.534477Z","steps":["trace[540303342] 'process raft request' (duration: 2.372330902s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:05:36.537338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:05:34.161697Z","time spent":"2.375609657s","remote":"127.0.0.1:37820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":698,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T06:05:36.537927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.441281783s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-02T06:05:36.538047Z","caller":"traceutil/trace.go:171","msg":"trace[48540676] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:11; }","duration":"2.441399156s","start":"2026-04-02T06:05:34.096603Z","end":"2026-04-02T06:05:36.538002Z","steps":["trace[48540676] 'agreement among raft nodes before linearized reading' (duration: 2.440948177s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:05:36.538105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:05:34.096599Z","time spent":"2.441491848s","remote":"127.0.0.1:37592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-02T06:05:36.53863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.129968053s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-02T06:05:36.538689Z","caller":"traceutil/trace.go:171","msg":"trace[730663840] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:11; }","duration":"1.130076535s","start":"2026-04-02T06:05:35.408588Z","end":"2026-04-02T06:05:36.538664Z","steps":["trace[730663840] 'agreement among raft nodes before linearized reading' (duration: 1.129731928s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:05:36.538756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:05:35.40856Z","time spent":"1.130184107s","remote":"127.0.0.1:37340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-02T06:05:36.535321Z","caller":"traceutil/trace.go:171","msg":"trace[1548527226] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:11; }","duration":"2.438736663s","start":"2026-04-02T06:05:34.096525Z","end":"2026-04-02T06:05:36.535261Z","steps":["trace[1548527226] 'agreement among raft nodes before linearized reading' (duration: 2.438209503s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:05:36.539326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:05:34.096502Z","time spent":"2.442754853s","remote":"127.0.0.1:37592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-02T06:05:36.539902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.479659596s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/system-node-critical\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-02T06:05:36.539987Z","caller":"traceutil/trace.go:171","msg":"trace[1594251508] range","detail":"{range_begin:/registry/priorityclasses/system-node-critical; range_end:; response_count:0; response_revision:11; }","duration":"1.479789849s","start":"2026-04-02T06:05:35.06017Z","end":"2026-04-02T06:05:36.53996Z","steps":["trace[1594251508] 'agreement among raft nodes before linearized reading' (duration: 1.47932145s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:05:36.540026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:05:35.060149Z","time spent":"1.479869811s","remote":"127.0.0.1:37718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":27,"request content":"key:\"/registry/priorityclasses/system-node-critical\" "} {"level":"warn","ts":"2026-04-02T06:05:36.541258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.48187143s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-02T06:05:36.541305Z","caller":"traceutil/trace.go:171","msg":"trace[318073987] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:11; }","duration":"1.481952092s","start":"2026-04-02T06:05:35.059334Z","end":"2026-04-02T06:05:36.541286Z","steps":["trace[318073987] 'agreement among raft nodes before linearized reading' (duration: 1.48187788s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:05:36.541432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:05:35.059312Z","time spent":"1.482110744s","remote":"127.0.0.1:37696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":27,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" "} {"level":"warn","ts":"2026-04-02T06:05:36.542317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.38390786s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.96\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-02T06:05:36.542411Z","caller":"traceutil/trace.go:171","msg":"trace[1760158786] range","detail":"{range_begin:/registry/masterleases/199.19.213.96; range_end:; response_count:0; response_revision:11; }","duration":"2.384016813s","start":"2026-04-02T06:05:34.158362Z","end":"2026-04-02T06:05:36.542379Z","steps":["trace[1760158786] 'agreement among raft nodes before linearized reading' (duration: 2.383802659s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:05:36.542511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.435286962s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-02T06:05:36.54261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:05:34.158338Z","time spent":"2.384262497s","remote":"127.0.0.1:37384","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":0,"response size":27,"request content":"key:\"/registry/masterleases/199.19.213.96\" "} {"level":"info","ts":"2026-04-02T06:05:36.542656Z","caller":"traceutil/trace.go:171","msg":"trace[1998333106] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:11; }","duration":"2.435404755s","start":"2026-04-02T06:05:34.10715Z","end":"2026-04-02T06:05:36.542555Z","steps":["trace[1998333106] 'agreement among raft nodes before linearized reading' (duration: 2.435220422s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:05:36.542705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:05:34.107039Z","time spent":"2.43564897s","remote":"127.0.0.1:37754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":27,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"warn","ts":"2026-04-02T06:05:36.543185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.381240067s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-02T06:05:36.543263Z","caller":"traceutil/trace.go:171","msg":"trace[302682890] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:11; }","duration":"2.381340869s","start":"2026-04-02T06:05:34.161908Z","end":"2026-04-02T06:05:36.543249Z","steps":["trace[302682890] 'agreement among raft nodes before linearized reading' (duration: 2.381231707s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:05:36.543328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:05:34.161891Z","time spent":"2.38140365s","remote":"127.0.0.1:37446","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":27,"request content":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" "} {"level":"warn","ts":"2026-04-02T06:05:36.543911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.384150975s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-02T06:05:36.543977Z","caller":"traceutil/trace.go:171","msg":"trace[1402812745] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"2.384220246s","start":"2026-04-02T06:05:34.159743Z","end":"2026-04-02T06:05:36.543964Z","steps":["trace[1402812745] 'agreement among raft nodes before linearized reading' (duration: 2.383943661s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:05:36.544025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:05:34.159737Z","time spent":"2.384269527s","remote":"127.0.0.1:37480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-04-02T06:05:36.545068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.31110421s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-02T06:05:36.54314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.329350935s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-02T06:05:36.54566Z","caller":"traceutil/trace.go:171","msg":"trace[1499063591] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"2.331975996s","start":"2026-04-02T06:05:34.213663Z","end":"2026-04-02T06:05:36.545639Z","steps":["trace[1499063591] 'agreement among raft nodes before linearized reading' (duration: 2.329216512s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:05:36.545925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:05:34.213616Z","time spent":"2.332298492s","remote":"127.0.0.1:37480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-04-02T06:05:36.549607Z","caller":"traceutil/trace.go:171","msg":"trace[828534093] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"2.311274844s","start":"2026-04-02T06:05:34.233852Z","end":"2026-04-02T06:05:36.545127Z","steps":["trace[828534093] 'agreement among raft nodes before linearized reading' (duration: 2.309631312s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:05:36.549705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:05:34.23382Z","time spent":"2.315852574s","remote":"127.0.0.1:37480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-04-02T06:05:37.051906Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4984250365781411457,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-02T06:05:37.292061Z","caller":"traceutil/trace.go:171","msg":"trace[1683525697] transaction","detail":"{read_only:false; response_revision:12; number_of_response:1; }","duration":"741.0247ms","start":"2026-04-02T06:05:36.550994Z","end":"2026-04-02T06:05:37.292019Z","steps":["trace[1683525697] 'process raft request' (duration: 729.080998ms)","trace[1683525697] 'compare' (duration: 11.747268ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T06:05:37.29222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:05:36.550967Z","time spent":"741.183813ms","remote":"127.0.0.1:37480","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":338,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T06:05:37.292353Z","caller":"traceutil/trace.go:171","msg":"trace[306400301] linearizableReadLoop","detail":"{readStateIndex:24; appliedIndex:14; }","duration":"741.256674ms","start":"2026-04-02T06:05:36.551084Z","end":"2026-04-02T06:05:37.292341Z","steps":["trace[306400301] 'read index received' (duration: 729.020806ms)","trace[306400301] 'applied index is now lower than readState.Index' (duration: 12.235128ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T06:05:37.292463Z","caller":"traceutil/trace.go:171","msg":"trace[192783658] transaction","detail":"{read_only:false; response_revision:15; number_of_response:1; }","duration":"740.999539ms","start":"2026-04-02T06:05:36.551421Z","end":"2026-04-02T06:05:37.292421Z","steps":["trace[192783658] 'process raft request' (duration: 740.648472ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T06:05:37.292502Z","caller":"traceutil/trace.go:171","msg":"trace[1280911036] transaction","detail":"{read_only:false; response_revision:18; number_of_response:1; }","duration":"740.953288ms","start":"2026-04-02T06:05:36.551515Z","end":"2026-04-02T06:05:37.292468Z","steps":["trace[1280911036] 'process raft request' (duration: 740.684602ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T06:05:37.292526Z","caller":"traceutil/trace.go:171","msg":"trace[2136632168] transaction","detail":"{read_only:false; number_of_response:0; response_revision:14; }","duration":"741.135061ms","start":"2026-04-02T06:05:36.55136Z","end":"2026-04-02T06:05:37.292495Z","steps":["trace[2136632168] 'process raft request' (duration: 740.687053ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T06:05:37.292465Z","caller":"traceutil/trace.go:171","msg":"trace[2066161577] transaction","detail":"{read_only:false; response_revision:14; number_of_response:1; }","duration":"741.261544ms","start":"2026-04-02T06:05:36.551091Z","end":"2026-04-02T06:05:37.292353Z","steps":["trace[2066161577] 'process raft request' (duration: 740.905838ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:05:37.292614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:05:36.551351Z","time spent":"741.225094ms","remote":"127.0.0.1:37480","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":27,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T06:05:37.292666Z","caller":"traceutil/trace.go:171","msg":"trace[773009092] transaction","detail":"{read_only:false; response_revision:16; number_of_response:1; }","duration":"742.359805ms","start":"2026-04-02T06:05:36.550293Z","end":"2026-04-02T06:05:37.292653Z","steps":["trace[773009092] 'process raft request' (duration: 741.831975ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:05:37.292743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:05:36.550223Z","time spent":"742.487478ms","remote":"127.0.0.1:37904","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":941,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T06:05:37.292615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:05:36.551497Z","time spent":"741.071261ms","remote":"127.0.0.1:37718","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":429,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T06:05:37.29292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:05:36.551054Z","time spent":"741.58706ms","remote":"127.0.0.1:37904","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T06:05:37.292969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"741.871276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-02T06:05:37.2969Z","caller":"traceutil/trace.go:171","msg":"trace[1774075154] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:20; }","duration":"745.806582ms","start":"2026-04-02T06:05:36.551076Z","end":"2026-04-02T06:05:37.296883Z","steps":["trace[1774075154] 'agreement among raft nodes before linearized reading' (duration: 741.782684ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T06:05:37.293006Z","caller":"traceutil/trace.go:171","msg":"trace[1436463746] transaction","detail":"{read_only:false; response_revision:19; number_of_response:1; }","duration":"739.663823ms","start":"2026-04-02T06:05:36.553332Z","end":"2026-04-02T06:05:37.292996Z","steps":["trace[1436463746] 'process raft request' (duration: 738.891168ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T06:05:37.293035Z","caller":"traceutil/trace.go:171","msg":"trace[1636287601] transaction","detail":"{read_only:false; response_revision:20; number_of_response:1; }","duration":"739.640812ms","start":"2026-04-02T06:05:36.553382Z","end":"2026-04-02T06:05:37.293023Z","steps":["trace[1636287601] 'process raft request' (duration: 738.866167ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:05:37.293047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:05:36.551411Z","time spent":"741.0933ms","remote":"127.0.0.1:37820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T06:05:37.293197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"692.662732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"warn","ts":"2026-04-02T06:05:37.293184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"741.227593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3490"} {"level":"info","ts":"2026-04-02T06:05:37.293183Z","caller":"traceutil/trace.go:171","msg":"trace[1993112851] transaction","detail":"{read_only:false; response_revision:17; number_of_response:1; }","duration":"741.647391ms","start":"2026-04-02T06:05:36.551517Z","end":"2026-04-02T06:05:37.293165Z","steps":["trace[1993112851] 'process raft request' (duration: 740.641962ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T06:05:37.293269Z","caller":"traceutil/trace.go:171","msg":"trace[682319215] transaction","detail":"{read_only:false; response_revision:13; number_of_response:1; }","duration":"741.997638ms","start":"2026-04-02T06:05:36.551262Z","end":"2026-04-02T06:05:37.293259Z","steps":["trace[682319215] 'process raft request' (duration: 740.691793ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:05:37.293288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"540.556812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-02T06:05:37.29332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"686.139934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"warn","ts":"2026-04-02T06:05:37.294243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"693.919997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"warn","ts":"2026-04-02T06:05:37.295116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"743.60967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-02T06:05:37.296922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"745.472586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/csr-8lmv6\" ","response":"range_response_count:1 size:861"} {"level":"info","ts":"2026-04-02T06:05:37.297487Z","caller":"traceutil/trace.go:171","msg":"trace[450383236] range","detail":"{range_begin:/registry/certificatesigningrequests/csr-8lmv6; range_end:; response_count:1; response_revision:20; }","duration":"746.084388ms","start":"2026-04-02T06:05:36.551393Z","end":"2026-04-02T06:05:37.297477Z","steps":["trace[450383236] 'agreement among raft nodes before linearized reading' (duration: 743.852975ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T06:05:37.297485Z","caller":"traceutil/trace.go:171","msg":"trace[100098059] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:20; }","duration":"696.936105ms","start":"2026-04-02T06:05:36.60049Z","end":"2026-04-02T06:05:37.297426Z","steps":["trace[100098059] 'agreement among raft nodes before linearized reading' (duration: 692.633691ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T06:05:37.297794Z","caller":"traceutil/trace.go:171","msg":"trace[1208060230] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:20; }","duration":"745.851203ms","start":"2026-04-02T06:05:36.551933Z","end":"2026-04-02T06:05:37.297784Z","steps":["trace[1208060230] 'agreement among raft nodes before linearized reading' (duration: 741.211523ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:05:37.297774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:05:36.551385Z","time spent":"746.149209ms","remote":"127.0.0.1:37590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":884,"request content":"key:\"/registry/certificatesigningrequests/csr-8lmv6\" "} {"level":"warn","ts":"2026-04-02T06:05:37.297833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:05:36.551924Z","time spent":"745.894544ms","remote":"127.0.0.1:37530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":3513,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-04-02T06:05:37.297846Z","caller":"traceutil/trace.go:171","msg":"trace[1073706543] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:20; }","duration":"698.588898ms","start":"2026-04-02T06:05:36.599236Z","end":"2026-04-02T06:05:37.297825Z","steps":["trace[1073706543] 'agreement among raft nodes before linearized reading' (duration: 693.920197ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:05:37.297928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:05:36.599212Z","time spent":"698.698799ms","remote":"127.0.0.1:37480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":373,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-04-02T06:05:37.298084Z","caller":"traceutil/trace.go:171","msg":"trace[1969727337] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20; }","duration":"746.579247ms","start":"2026-04-02T06:05:36.551479Z","end":"2026-04-02T06:05:37.298059Z","steps":["trace[1969727337] 'agreement among raft nodes before linearized reading' (duration: 743.61261ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T06:05:37.298109Z","caller":"traceutil/trace.go:171","msg":"trace[1309410657] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:20; }","duration":"690.973468ms","start":"2026-04-02T06:05:36.60712Z","end":"2026-04-02T06:05:37.298093Z","steps":["trace[1309410657] 'agreement among raft nodes before linearized reading' (duration: 686.184425ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:05:37.298136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:05:36.551454Z","time spent":"746.67305ms","remote":"127.0.0.1:37340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-02T06:05:37.298233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:05:36.553378Z","time spent":"744.771922ms","remote":"127.0.0.1:37904","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":965,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T06:05:37.297322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:05:36.551047Z","time spent":"746.15049ms","remote":"127.0.0.1:37708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":0,"response size":27,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" "} {"level":"warn","ts":"2026-04-02T06:05:37.29808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:05:36.551506Z","time spent":"746.532227ms","remote":"127.0.0.1:37530","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3478,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T06:05:37.298041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:05:36.553326Z","time spent":"744.612089ms","remote":"127.0.0.1:37904","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":923,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T06:05:37.297855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:05:36.600474Z","time spent":"697.361933ms","remote":"127.0.0.1:37480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":373,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-04-02T06:05:37.298125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:05:36.551251Z","time spent":"746.852533ms","remote":"127.0.0.1:37904","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":941,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T06:05:37.297751Z","caller":"traceutil/trace.go:171","msg":"trace[141564987] range","detail":"{range_begin:/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te; range_end:; response_count:0; response_revision:20; }","duration":"544.854926ms","start":"2026-04-02T06:05:36.75266Z","end":"2026-04-02T06:05:37.297515Z","steps":["trace[141564987] 'agreement among raft nodes before linearized reading' (duration: 540.605883ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:05:37.298767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:05:36.752641Z","time spent":"546.114481ms","remote":"127.0.0.1:37592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" "} {"level":"warn","ts":"2026-04-02T06:05:37.29817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:05:36.607077Z","time spent":"691.0792ms","remote":"127.0.0.1:37480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":373,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-04-02T06:06:06.45618Z","caller":"traceutil/trace.go:171","msg":"trace[2121891014] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"107.487089ms","start":"2026-04-02T06:06:06.348667Z","end":"2026-04-02T06:06:06.456154Z","steps":["trace[2121891014] 'process raft request' (duration: 107.328327ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:06:07.962252Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4984250365781412445,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-02T06:06:08.203559Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.104878262s","expected-duration":"1s"} {"level":"info","ts":"2026-04-02T06:06:08.204033Z","caller":"traceutil/trace.go:171","msg":"trace[1235450297] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"1.105445422s","start":"2026-04-02T06:06:07.098541Z","end":"2026-04-02T06:06:08.203986Z","steps":["trace[1235450297] 'process raft request' (duration: 1.105277709s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:06:08.20416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:06:07.098514Z","time spent":"1.105581694s","remote":"127.0.0.1:37402","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-04-02T06:06:08.213563Z","caller":"traceutil/trace.go:171","msg":"trace[18913563] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"1.10667373s","start":"2026-04-02T06:06:07.106876Z","end":"2026-04-02T06:06:08.21355Z","steps":["trace[18913563] 'process raft request' (duration: 1.106542188s)"],"step_count":1} {"level":"info","ts":"2026-04-02T06:06:08.213611Z","caller":"traceutil/trace.go:171","msg":"trace[244116867] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"1.098866726s","start":"2026-04-02T06:06:07.114716Z","end":"2026-04-02T06:06:08.213583Z","steps":["trace[244116867] 'process raft request' (duration: 1.098795035s)"],"step_count":1} {"level":"info","ts":"2026-04-02T06:06:08.213735Z","caller":"traceutil/trace.go:171","msg":"trace[1772534430] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"1.096392297s","start":"2026-04-02T06:06:07.117297Z","end":"2026-04-02T06:06:08.21369Z","steps":["trace[1772534430] 'process raft request' (duration: 1.096243755s)"],"step_count":1} {"level":"info","ts":"2026-04-02T06:06:08.213865Z","caller":"traceutil/trace.go:171","msg":"trace[769179657] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"1.093684455s","start":"2026-04-02T06:06:07.120155Z","end":"2026-04-02T06:06:08.213839Z","steps":["trace[769179657] 'process raft request' (duration: 1.093466011s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:06:08.213984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:06:07.117277Z","time spent":"1.096620511s","remote":"127.0.0.1:37402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T06:06:08.214043Z","caller":"traceutil/trace.go:171","msg":"trace[570459349] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"1.083525213s","start":"2026-04-02T06:06:07.130495Z","end":"2026-04-02T06:06:08.21402Z","steps":["trace[570459349] 'process raft request' (duration: 1.083357631s)"],"step_count":1} {"level":"info","ts":"2026-04-02T06:06:08.214102Z","caller":"traceutil/trace.go:171","msg":"trace[1095123131] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"1.082243453s","start":"2026-04-02T06:06:07.131847Z","end":"2026-04-02T06:06:08.214091Z","steps":["trace[1095123131] 'process raft request' (duration: 1.082089081s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:06:08.214144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:06:07.131833Z","time spent":"1.082291904s","remote":"127.0.0.1:37402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T06:06:08.214199Z","caller":"traceutil/trace.go:171","msg":"trace[387922230] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"1.081000974s","start":"2026-04-02T06:06:07.133155Z","end":"2026-04-02T06:06:08.214156Z","steps":["trace[387922230] 'process raft request' (duration: 1.08080816s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:06:08.213649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:06:07.106853Z","time spent":"1.106750651s","remote":"127.0.0.1:37402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T06:06:08.213997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:06:07.120132Z","time spent":"1.093832316s","remote":"127.0.0.1:37402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T06:06:08.214325Z","caller":"traceutil/trace.go:171","msg":"trace[786446225] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"1.063382695s","start":"2026-04-02T06:06:07.150934Z","end":"2026-04-02T06:06:08.214316Z","steps":["trace[786446225] 'process raft request' (duration: 1.063136851s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:06:08.214319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:06:07.133144Z","time spent":"1.081096246s","remote":"127.0.0.1:37402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T06:06:08.214357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:06:07.150914Z","time spent":"1.063428396s","remote":"127.0.0.1:37402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T06:06:08.214451Z","caller":"traceutil/trace.go:171","msg":"trace[1538890601] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"1.053800854s","start":"2026-04-02T06:06:07.160643Z","end":"2026-04-02T06:06:08.214444Z","steps":["trace[1538890601] 'process raft request' (duration: 1.05356936s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:06:08.214481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:06:07.160623Z","time spent":"1.053845634s","remote":"127.0.0.1:37402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T06:06:08.214484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:06:07.130473Z","time spent":"1.083643506s","remote":"127.0.0.1:37402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T06:06:08.213991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:06:07.114693Z","time spent":"1.09909022s","remote":"127.0.0.1:37402","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-04-02T06:06:08.214675Z","caller":"traceutil/trace.go:171","msg":"trace[2085342658] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"1.04211877s","start":"2026-04-02T06:06:07.172548Z","end":"2026-04-02T06:06:08.214667Z","steps":["trace[2085342658] 'process raft request' (duration: 1.041912146s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:06:08.214719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:06:07.172511Z","time spent":"1.042183841s","remote":"127.0.0.1:37402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T06:06:08.214881Z","caller":"traceutil/trace.go:171","msg":"trace[800125375] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"935.159699ms","start":"2026-04-02T06:06:07.279712Z","end":"2026-04-02T06:06:08.214872Z","steps":["trace[800125375] 'process raft request' (duration: 934.969236ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:06:08.214915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:06:07.279692Z","time spent":"935.20735ms","remote":"127.0.0.1:37402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T06:06:08.214952Z","caller":"traceutil/trace.go:171","msg":"trace[1092656522] linearizableReadLoop","detail":"{readStateIndex:543; appliedIndex:531; }","duration":"753.752112ms","start":"2026-04-02T06:06:07.461141Z","end":"2026-04-02T06:06:08.214893Z","steps":["trace[1092656522] 'read index received' (duration: 742.664968ms)","trace[1092656522] 'applied index is now lower than readState.Index' (duration: 11.085254ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T06:06:08.215099Z","caller":"traceutil/trace.go:171","msg":"trace[1844644539] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"206.514854ms","start":"2026-04-02T06:06:08.008576Z","end":"2026-04-02T06:06:08.215091Z","steps":["trace[1844644539] 'process raft request' (duration: 206.24685ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:06:08.215278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"754.135348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-02T06:06:08.215306Z","caller":"traceutil/trace.go:171","msg":"trace[1031301095] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:526; }","duration":"754.206449ms","start":"2026-04-02T06:06:07.461092Z","end":"2026-04-02T06:06:08.215299Z","steps":["trace[1031301095] 'agreement among raft nodes before linearized reading' (duration: 754.143659ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:06:08.215349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:06:07.461072Z","time spent":"754.26852ms","remote":"127.0.0.1:37592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-02T06:06:08.225377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"735.132967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-02T06:06:08.22545Z","caller":"traceutil/trace.go:171","msg":"trace[436627224] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:528; }","duration":"735.244369ms","start":"2026-04-02T06:06:07.490189Z","end":"2026-04-02T06:06:08.225433Z","steps":["trace[436627224] 'agreement among raft nodes before linearized reading' (duration: 735.118017ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:06:08.225487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:06:07.49017Z","time spent":"735.3084ms","remote":"127.0.0.1:37592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-02T06:06:08.225815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"732.375894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-02T06:06:08.22587Z","caller":"traceutil/trace.go:171","msg":"trace[1448055113] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:528; }","duration":"732.464565ms","start":"2026-04-02T06:06:07.493393Z","end":"2026-04-02T06:06:08.225858Z","steps":["trace[1448055113] 'agreement among raft nodes before linearized reading' (duration: 732.318912ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:06:08.225894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:06:07.493379Z","time spent":"732.509286ms","remote":"127.0.0.1:37592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-02T06:06:12.986638Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4984250365781412599,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-02T06:06:13.224971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"970.081721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-04-02T06:06:13.22498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"964.093979ms","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-04-02T06:06:13.225036Z","caller":"traceutil/trace.go:171","msg":"trace[1772932864] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:605; }","duration":"970.175582ms","start":"2026-04-02T06:06:12.254841Z","end":"2026-04-02T06:06:13.225016Z","steps":["trace[1772932864] 'range keys from in-memory index tree' (duration: 969.98031ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:06:13.225063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:06:12.254826Z","time spent":"970.231524ms","remote":"127.0.0.1:37592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-02T06:06:13.22507Z","caller":"traceutil/trace.go:171","msg":"trace[455806530] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:605; }","duration":"964.206291ms","start":"2026-04-02T06:06:12.260845Z","end":"2026-04-02T06:06:13.225051Z","steps":["trace[455806530] 'range keys from in-memory index tree' (duration: 963.881816ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:06:13.225112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:06:12.260833Z","time spent":"964.268792ms","remote":"127.0.0.1:37592","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-04-02T06:06:13.224971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"964.100779ms","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-04-02T06:06:13.225279Z","caller":"traceutil/trace.go:171","msg":"trace[2066647664] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:605; }","duration":"964.382053ms","start":"2026-04-02T06:06:12.260827Z","end":"2026-04-02T06:06:13.225209Z","steps":["trace[2066647664] 'range keys from in-memory index tree' (duration: 963.953807ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:06:13.225313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:06:12.260809Z","time spent":"964.496046ms","remote":"127.0.0.1:37592","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-04-02T06:06:13.234907Z","caller":"traceutil/trace.go:171","msg":"trace[1381042447] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"789.60027ms","start":"2026-04-02T06:06:12.445292Z","end":"2026-04-02T06:06:13.234892Z","steps":["trace[1381042447] 'process raft request' (duration: 789.131083ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T06:06:13.235069Z","caller":"traceutil/trace.go:171","msg":"trace[1182993735] linearizableReadLoop","detail":"{readStateIndex:632; appliedIndex:631; }","duration":"748.779243ms","start":"2026-04-02T06:06:12.486254Z","end":"2026-04-02T06:06:13.235034Z","steps":["trace[1182993735] 'read index received' (duration: 748.048662ms)","trace[1182993735] 'applied index is now lower than readState.Index' (duration: 727.671µs)"],"step_count":2} {"level":"warn","ts":"2026-04-02T06:06:13.235008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:06:12.445274Z","time spent":"789.677121ms","remote":"127.0.0.1:37462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036157,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T06:06:13.235199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"748.948307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-02T06:06:13.235241Z","caller":"traceutil/trace.go:171","msg":"trace[833421909] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:606; }","duration":"749.047108ms","start":"2026-04-02T06:06:12.486179Z","end":"2026-04-02T06:06:13.235226Z","steps":["trace[833421909] 'agreement among raft nodes before linearized reading' (duration: 748.921276ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:06:13.235268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:06:12.48616Z","time spent":"749.102009ms","remote":"127.0.0.1:37592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-02T06:06:13.253063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.003612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T06:06:13.253115Z","caller":"traceutil/trace.go:171","msg":"trace[1773049756] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:609; }","duration":"337.142653ms","start":"2026-04-02T06:06:12.915962Z","end":"2026-04-02T06:06:13.253105Z","steps":["trace[1773049756] 'agreement among raft nodes before linearized reading' (duration: 337.032621ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:06:13.253141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:06:12.915942Z","time spent":"337.194474ms","remote":"127.0.0.1:36520","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true "} {"level":"warn","ts":"2026-04-02T06:06:13.253119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.127793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T06:06:13.253328Z","caller":"traceutil/trace.go:171","msg":"trace[1126054443] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:609; }","duration":"337.343496ms","start":"2026-04-02T06:06:12.915902Z","end":"2026-04-02T06:06:13.253245Z","steps":["trace[1126054443] 'agreement among raft nodes before linearized reading' (duration: 337.108282ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:06:13.253389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.97646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T06:06:13.253411Z","caller":"traceutil/trace.go:171","msg":"trace[981183605] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:609; }","duration":"173.04552ms","start":"2026-04-02T06:06:13.08036Z","end":"2026-04-02T06:06:13.253406Z","steps":["trace[981183605] 'agreement among raft nodes before linearized reading' (duration: 173.01892ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:06:13.253413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:06:12.915864Z","time spent":"337.538169ms","remote":"127.0.0.1:36520","response type":"/etcdserverpb.KV/Range","request count":0,"request size":89,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:10000 "} {"level":"warn","ts":"2026-04-02T06:06:13.253494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.144143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T06:06:13.253513Z","caller":"traceutil/trace.go:171","msg":"trace[1579670947] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:609; }","duration":"173.189903ms","start":"2026-04-02T06:06:13.080319Z","end":"2026-04-02T06:06:13.253509Z","steps":["trace[1579670947] 'agreement among raft nodes before linearized reading' (duration: 173.161853ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:06:13.253577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.835499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T06:06:13.253593Z","caller":"traceutil/trace.go:171","msg":"trace[1277301369] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:609; }","duration":"195.851999ms","start":"2026-04-02T06:06:13.057737Z","end":"2026-04-02T06:06:13.253589Z","steps":["trace[1277301369] 'agreement among raft nodes before linearized reading' (duration: 195.828649ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:06:13.253671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.323557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T06:06:13.253694Z","caller":"traceutil/trace.go:171","msg":"trace[850961188] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:609; }","duration":"240.358009ms","start":"2026-04-02T06:06:13.013328Z","end":"2026-04-02T06:06:13.253686Z","steps":["trace[850961188] 'agreement among raft nodes before linearized reading' (duration: 240.329698ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:06:13.25379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.802115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T06:06:13.253811Z","caller":"traceutil/trace.go:171","msg":"trace[1811639782] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:609; }","duration":"240.880037ms","start":"2026-04-02T06:06:13.012926Z","end":"2026-04-02T06:06:13.253806Z","steps":["trace[1811639782] 'agreement among raft nodes before linearized reading' (duration: 240.848936ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:06:13.253778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.416603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T06:06:13.25395Z","caller":"traceutil/trace.go:171","msg":"trace[1050497672] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:609; }","duration":"366.710708ms","start":"2026-04-02T06:06:12.887228Z","end":"2026-04-02T06:06:13.253939Z","steps":["trace[1050497672] 'agreement among raft nodes before linearized reading' (duration: 366.388223ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:06:13.254107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:06:12.88722Z","time spent":"366.87439ms","remote":"127.0.0.1:36506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:500 "} {"level":"warn","ts":"2026-04-02T06:06:13.254236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"631.174587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T06:06:13.25427Z","caller":"traceutil/trace.go:171","msg":"trace[42836508] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:609; }","duration":"631.226738ms","start":"2026-04-02T06:06:12.623034Z","end":"2026-04-02T06:06:13.254261Z","steps":["trace[42836508] 'agreement among raft nodes before linearized reading' (duration: 631.172617ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:06:13.254296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:06:12.623029Z","time spent":"631.260598ms","remote":"127.0.0.1:36498","response type":"/etcdserverpb.KV/Range","request count":0,"request size":131,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:10000 "} {"level":"warn","ts":"2026-04-02T06:06:13.254378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"631.352339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-02T06:06:13.254456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.781403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T06:06:13.254466Z","caller":"traceutil/trace.go:171","msg":"trace[718377083] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:609; }","duration":"631.45492ms","start":"2026-04-02T06:06:12.623Z","end":"2026-04-02T06:06:13.254455Z","steps":["trace[718377083] 'agreement among raft nodes before linearized reading' (duration: 631.320508ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T06:06:13.254481Z","caller":"traceutil/trace.go:171","msg":"trace[1613048216] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:609; }","duration":"367.838835ms","start":"2026-04-02T06:06:12.886636Z","end":"2026-04-02T06:06:13.254475Z","steps":["trace[1613048216] 'agreement among raft nodes before linearized reading' (duration: 367.805704ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:06:13.254497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:06:12.62299Z","time spent":"631.498072ms","remote":"127.0.0.1:36498","response type":"/etcdserverpb.KV/Range","request count":0,"request size":130,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-02T06:06:13.254528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"484.069459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T06:06:13.254547Z","caller":"traceutil/trace.go:171","msg":"trace[358461702] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:609; }","duration":"484.097709ms","start":"2026-04-02T06:06:12.770443Z","end":"2026-04-02T06:06:13.254541Z","steps":["trace[358461702] 'agreement among raft nodes before linearized reading' (duration: 484.070628ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:06:13.254564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:06:12.770438Z","time spent":"484.120879ms","remote":"127.0.0.1:36506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"warn","ts":"2026-04-02T06:06:13.254681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"484.370523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T06:06:13.254702Z","caller":"traceutil/trace.go:171","msg":"trace[1185043996] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:609; }","duration":"484.421134ms","start":"2026-04-02T06:06:12.770275Z","end":"2026-04-02T06:06:13.254697Z","steps":["trace[1185043996] 'agreement among raft nodes before linearized reading' (duration: 484.380584ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:06:13.25472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:06:12.77026Z","time spent":"484.454844ms","remote":"127.0.0.1:36506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 "} {"level":"warn","ts":"2026-04-02T06:06:13.25475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"649.68517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T06:06:13.254787Z","caller":"traceutil/trace.go:171","msg":"trace[1747506881] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:609; }","duration":"649.777742ms","start":"2026-04-02T06:06:12.605001Z","end":"2026-04-02T06:06:13.254779Z","steps":["trace[1747506881] 'agreement among raft nodes before linearized reading' (duration: 649.693271ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:06:13.254834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"616.490391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T06:06:13.254852Z","caller":"traceutil/trace.go:171","msg":"trace[1671049865] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:609; }","duration":"616.534381ms","start":"2026-04-02T06:06:12.638312Z","end":"2026-04-02T06:06:13.254846Z","steps":["trace[1671049865] 'agreement among raft nodes before linearized reading' (duration: 616.508961ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:06:13.254864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:06:12.60499Z","time spent":"649.843203ms","remote":"127.0.0.1:36494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":115,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:500 "} {"level":"warn","ts":"2026-04-02T06:06:13.254899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"616.777625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T06:06:13.254918Z","caller":"traceutil/trace.go:171","msg":"trace[2060995275] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:609; }","duration":"616.835606ms","start":"2026-04-02T06:06:12.638079Z","end":"2026-04-02T06:06:13.254914Z","steps":["trace[2060995275] 'agreement among raft nodes before linearized reading' (duration: 616.807856ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:06:13.254934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:06:12.638061Z","time spent":"616.868527ms","remote":"127.0.0.1:36504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":111,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:10000 "} {"level":"warn","ts":"2026-04-02T06:06:13.25503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"631.203477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T06:06:13.25505Z","caller":"traceutil/trace.go:171","msg":"trace[2132912226] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:609; }","duration":"631.237877ms","start":"2026-04-02T06:06:12.623807Z","end":"2026-04-02T06:06:13.255045Z","steps":["trace[2132912226] 'agreement among raft nodes before linearized reading' (duration: 631.208267ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:06:13.255067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:06:12.623799Z","time spent":"631.262558ms","remote":"127.0.0.1:36498","response type":"/etcdserverpb.KV/Range","request count":0,"request size":131,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:500 "} {"level":"warn","ts":"2026-04-02T06:06:13.25518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"651.453718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T06:06:13.255221Z","caller":"traceutil/trace.go:171","msg":"trace[134446216] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:609; }","duration":"651.59001ms","start":"2026-04-02T06:06:12.603623Z","end":"2026-04-02T06:06:13.255213Z","steps":["trace[134446216] 'agreement among raft nodes before linearized reading' (duration: 651.488208ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:06:13.255273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:06:12.603601Z","time spent":"651.662781ms","remote":"127.0.0.1:36494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":115,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:10000 "} {"level":"warn","ts":"2026-04-02T06:06:13.254428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.688012ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T06:06:13.255424Z","caller":"traceutil/trace.go:171","msg":"trace[740705152] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:609; }","duration":"368.716708ms","start":"2026-04-02T06:06:12.8867Z","end":"2026-04-02T06:06:13.255417Z","steps":["trace[740705152] 'agreement among raft nodes before linearized reading' (duration: 367.705093ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:06:13.254807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"616.40836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-02T06:06:13.255452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:06:12.886691Z","time spent":"368.748929ms","remote":"127.0.0.1:36514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"info","ts":"2026-04-02T06:06:13.255489Z","caller":"traceutil/trace.go:171","msg":"trace[505689237] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:609; }","duration":"617.09885ms","start":"2026-04-02T06:06:12.638382Z","end":"2026-04-02T06:06:13.25548Z","steps":["trace[505689237] 'agreement among raft nodes before linearized reading' (duration: 616.40545ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:06:13.255542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:06:12.638375Z","time spent":"617.136281ms","remote":"127.0.0.1:36504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":111,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:500 "} {"level":"warn","ts":"2026-04-02T06:06:13.253365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.397181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T06:06:13.255605Z","caller":"traceutil/trace.go:171","msg":"trace[621484031] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:609; }","duration":"174.637295ms","start":"2026-04-02T06:06:13.080961Z","end":"2026-04-02T06:06:13.255599Z","steps":["trace[621484031] 'agreement among raft nodes before linearized reading' (duration: 172.387791ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:06:13.254874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:06:12.638296Z","time spent":"616.573423ms","remote":"127.0.0.1:36504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true "} {"level":"warn","ts":"2026-04-02T06:06:13.255399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"651.778753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T06:06:13.255973Z","caller":"traceutil/trace.go:171","msg":"trace[817326812] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:609; }","duration":"652.329681ms","start":"2026-04-02T06:06:12.603611Z","end":"2026-04-02T06:06:13.255941Z","steps":["trace[817326812] 'agreement among raft nodes before linearized reading' (duration: 651.766823ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:06:13.256012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:06:12.60359Z","time spent":"652.409962ms","remote":"127.0.0.1:36494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":114,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-02T06:06:13.254501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:06:12.886613Z","time spent":"367.882365ms","remote":"127.0.0.1:36514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 "} {"level":"warn","ts":"2026-04-02T06:06:13.256798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.337372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T06:06:13.256828Z","caller":"traceutil/trace.go:171","msg":"trace[1134303767] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:610; }","duration":"340.422944ms","start":"2026-04-02T06:06:12.916398Z","end":"2026-04-02T06:06:13.256821Z","steps":["trace[1134303767] 'agreement among raft nodes before linearized reading' (duration: 340.379953ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:06:13.256848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:06:12.916359Z","time spent":"340.483314ms","remote":"127.0.0.1:36520","response type":"/etcdserverpb.KV/Range","request count":0,"request size":89,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:500 "} {"level":"warn","ts":"2026-04-02T06:06:13.256909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.969334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T06:06:13.256932Z","caller":"traceutil/trace.go:171","msg":"trace[48336628] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:610; }","duration":"243.997194ms","start":"2026-04-02T06:06:13.012929Z","end":"2026-04-02T06:06:13.256926Z","steps":["trace[48336628] 'agreement among raft nodes before linearized reading' (duration: 243.961294ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:06:13.257024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.717289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T06:06:13.257049Z","caller":"traceutil/trace.go:171","msg":"trace[1860134723] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:610; }","duration":"269.761079ms","start":"2026-04-02T06:06:12.987283Z","end":"2026-04-02T06:06:13.257044Z","steps":["trace[1860134723] 'agreement among raft nodes before linearized reading' (duration: 269.721368ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:06:13.257136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.636178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T06:06:13.257165Z","caller":"traceutil/trace.go:171","msg":"trace[862721162] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:610; }","duration":"316.71371ms","start":"2026-04-02T06:06:12.940441Z","end":"2026-04-02T06:06:13.257155Z","steps":["trace[862721162] 'agreement among raft nodes before linearized reading' (duration: 316.661099ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:06:13.257192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:06:12.940419Z","time spent":"316.76535ms","remote":"127.0.0.1:36522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:500 "} {"level":"warn","ts":"2026-04-02T06:06:13.257225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.239338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T06:06:13.25725Z","caller":"traceutil/trace.go:171","msg":"trace[1764109519] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:610; }","duration":"317.305359ms","start":"2026-04-02T06:06:12.939939Z","end":"2026-04-02T06:06:13.257244Z","steps":["trace[1764109519] 'agreement among raft nodes before linearized reading' (duration: 317.264138ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:06:13.257269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:06:12.939923Z","time spent":"317.341089ms","remote":"127.0.0.1:36522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:10000 "} {"level":"warn","ts":"2026-04-02T06:06:13.257328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.39672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-02T06:06:13.257334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.422039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T06:06:13.257348Z","caller":"traceutil/trace.go:171","msg":"trace[114766050] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:610; }","duration":"317.452111ms","start":"2026-04-02T06:06:12.939891Z","end":"2026-04-02T06:06:13.257343Z","steps":["trace[114766050] 'agreement among raft nodes before linearized reading' (duration: 317.41417ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T06:06:13.257367Z","caller":"traceutil/trace.go:171","msg":"trace[1901837631] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:610; }","duration":"270.506901ms","start":"2026-04-02T06:06:12.986852Z","end":"2026-04-02T06:06:13.257359Z","steps":["trace[1901837631] 'agreement among raft nodes before linearized reading' (duration: 270.45455ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:06:13.257365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:06:12.939868Z","time spent":"317.492532ms","remote":"127.0.0.1:36522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true "} {"level":"warn","ts":"2026-04-02T06:06:13.257459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.49882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T06:06:13.257485Z","caller":"traceutil/trace.go:171","msg":"trace[414330285] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:610; }","duration":"270.552851ms","start":"2026-04-02T06:06:12.986926Z","end":"2026-04-02T06:06:13.257479Z","steps":["trace[414330285] 'agreement among raft nodes before linearized reading' (duration: 270.51414ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T06:06:16.173043Z","caller":"traceutil/trace.go:171","msg":"trace[1875818187] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"272.411558ms","start":"2026-04-02T06:06:15.900595Z","end":"2026-04-02T06:06:16.173006Z","steps":["trace[1875818187] 'process raft request' (duration: 270.368437ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T06:06:16.968173Z","caller":"traceutil/trace.go:171","msg":"trace[187247537] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"780.401474ms","start":"2026-04-02T06:06:16.187744Z","end":"2026-04-02T06:06:16.968145Z","steps":["trace[187247537] 'process raft request' (duration: 780.189181ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:06:16.968324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:06:16.187706Z","time spent":"780.540486ms","remote":"127.0.0.1:37532","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-04-02T06:06:17.014962Z","caller":"traceutil/trace.go:171","msg":"trace[196132464] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"781.001003ms","start":"2026-04-02T06:06:16.233941Z","end":"2026-04-02T06:06:17.014942Z","steps":["trace[196132464] 'process raft request' (duration: 780.873481ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:06:17.015075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:06:16.233914Z","time spent":"781.107414ms","remote":"127.0.0.1:37576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T06:06:17.557339Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4984250365781412681,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-02T06:06:18.058121Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4984250365781412681,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-02T06:06:18.558556Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4984250365781412681,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-02T06:06:19.057554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000617567s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-02T06:06:19.057729Z","caller":"traceutil/trace.go:171","msg":"trace[1296197506] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000880401s","start":"2026-04-02T06:06:17.056824Z","end":"2026-04-02T06:06:19.057705Z","steps":["trace[1296197506] 'agreement among raft nodes before linearized reading' (duration: 2.000649907s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:06:19.05781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:06:17.056804Z","time spent":"2.000992202s","remote":"127.0.0.1:37340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/04/02 06:06:19 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-02T06:06:19.059018Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4984250365781412681,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-02T06:06:19.123162Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.094588898s","expected-duration":"1s"} {"level":"info","ts":"2026-04-02T06:06:19.146008Z","caller":"traceutil/trace.go:171","msg":"trace[726183490] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"2.117502156s","start":"2026-04-02T06:06:17.028473Z","end":"2026-04-02T06:06:19.145975Z","steps":["trace[726183490] 'process raft request' (duration: 2.095163546s)","trace[726183490] 'compare' (duration: 21.279195ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T06:06:19.146207Z","caller":"traceutil/trace.go:171","msg":"trace[1561486464] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"783.681144ms","start":"2026-04-02T06:06:18.362513Z","end":"2026-04-02T06:06:19.146194Z","steps":["trace[1561486464] 'process raft request' (duration: 783.363579ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:06:19.146216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:06:17.028456Z","time spent":"2.11770414s","remote":"127.0.0.1:37532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2735,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T06:06:19.146322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:06:18.362483Z","time spent":"783.769565ms","remote":"127.0.0.1:37592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T06:06:19.146011Z","caller":"traceutil/trace.go:171","msg":"trace[997232568] linearizableReadLoop","detail":"{readStateIndex:658; appliedIndex:656; }","duration":"2.089092112s","start":"2026-04-02T06:06:17.056888Z","end":"2026-04-02T06:06:19.14598Z","steps":["trace[997232568] 'read index received' (duration: 2.06661959s)","trace[997232568] 'applied index is now lower than readState.Index' (duration: 22.471352ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T06:06:19.146656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.871678726s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-04-02T06:06:19.147597Z","caller":"traceutil/trace.go:171","msg":"trace[1284644276] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:631; }","duration":"1.872644061s","start":"2026-04-02T06:06:17.274924Z","end":"2026-04-02T06:06:19.147568Z","steps":["trace[1284644276] 'agreement among raft nodes before linearized reading' (duration: 1.871670357s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:06:19.147714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:06:17.274904Z","time spent":"1.872777093s","remote":"127.0.0.1:37592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-02T06:06:19.147856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"940.399371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.96\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-04-02T06:06:19.146738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.870037612s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-02T06:06:19.147966Z","caller":"traceutil/trace.go:171","msg":"trace[416892963] range","detail":"{range_begin:/registry/masterleases/199.19.213.96; range_end:; response_count:1; response_revision:631; }","duration":"940.477372ms","start":"2026-04-02T06:06:18.207432Z","end":"2026-04-02T06:06:19.147909Z","steps":["trace[416892963] 'agreement among raft nodes before linearized reading' (duration: 939.196403ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:06:19.148065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:06:18.207387Z","time spent":"940.626475ms","remote":"127.0.0.1:37384","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.96\" "} {"level":"info","ts":"2026-04-02T06:06:19.148072Z","caller":"traceutil/trace.go:171","msg":"trace[1497584922] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:631; }","duration":"1.871321321s","start":"2026-04-02T06:06:17.276672Z","end":"2026-04-02T06:06:19.147993Z","steps":["trace[1497584922] 'agreement among raft nodes before linearized reading' (duration: 1.869967301s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:06:19.146785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.86991121s","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-04-02T06:06:19.148201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:06:17.276664Z","time spent":"1.871514474s","remote":"127.0.0.1:37592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-02T06:06:19.148304Z","caller":"traceutil/trace.go:171","msg":"trace[1854310912] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:631; }","duration":"1.871421232s","start":"2026-04-02T06:06:17.276855Z","end":"2026-04-02T06:06:19.148276Z","steps":["trace[1854310912] 'agreement among raft nodes before linearized reading' (duration: 1.86990665s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:06:19.1485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:06:17.276849Z","time spent":"1.871634545s","remote":"127.0.0.1:37592","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-04-02T06:06:19.146828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.87055776s","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-04-02T06:06:19.148647Z","caller":"traceutil/trace.go:171","msg":"trace[737843657] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:631; }","duration":"1.872404138s","start":"2026-04-02T06:06:17.276226Z","end":"2026-04-02T06:06:19.14863Z","steps":["trace[737843657] 'agreement among raft nodes before linearized reading' (duration: 1.870584291s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:06:19.14874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:06:17.276185Z","time spent":"1.872540119s","remote":"127.0.0.1:37592","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-04-02T06:06:20.506935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.880776ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T06:06:20.507048Z","caller":"traceutil/trace.go:171","msg":"trace[591240127] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:645; }","duration":"121.005047ms","start":"2026-04-02T06:06:20.386026Z","end":"2026-04-02T06:06:20.507031Z","steps":["trace[591240127] 'range keys from in-memory index tree' (duration: 120.865835ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T06:06:24.416678Z","caller":"traceutil/trace.go:171","msg":"trace[449725872] transaction","detail":"{read_only:false; response_revision:711; number_of_response:1; }","duration":"100.786461ms","start":"2026-04-02T06:06:24.315867Z","end":"2026-04-02T06:06:24.416654Z","steps":["trace[449725872] 'process raft request' (duration: 100.551218ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:06:35.741104Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4984250365781412988,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-02T06:06:36.088357Z","caller":"traceutil/trace.go:171","msg":"trace[849391225] linearizableReadLoop","detail":"{readStateIndex:821; appliedIndex:820; }","duration":"847.593084ms","start":"2026-04-02T06:06:35.240747Z","end":"2026-04-02T06:06:36.08834Z","steps":["trace[849391225] 'read index received' (duration: 847.447042ms)","trace[849391225] 'applied index is now lower than readState.Index' (duration: 145.432µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T06:06:36.088389Z","caller":"traceutil/trace.go:171","msg":"trace[575391382] transaction","detail":"{read_only:false; response_revision:789; number_of_response:1; }","duration":"878.02127ms","start":"2026-04-02T06:06:35.21035Z","end":"2026-04-02T06:06:36.088371Z","steps":["trace[575391382] 'process raft request' (duration: 877.877958ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:06:36.088502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"828.759707ms","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-04-02T06:06:36.088493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:06:35.210329Z","time spent":"878.096661ms","remote":"127.0.0.1:37532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4027,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T06:06:36.088533Z","caller":"traceutil/trace.go:171","msg":"trace[1566966636] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:789; }","duration":"828.815668ms","start":"2026-04-02T06:06:35.259711Z","end":"2026-04-02T06:06:36.088527Z","steps":["trace[1566966636] 'agreement among raft nodes before linearized reading' (duration: 828.717646ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:06:36.088552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:06:35.259704Z","time spent":"828.843128ms","remote":"127.0.0.1:37592","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-04-02T06:06:36.08864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"847.871588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-04-02T06:06:36.08869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"824.186225ms","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-04-02T06:06:36.088736Z","caller":"traceutil/trace.go:171","msg":"trace[1458001457] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:789; }","duration":"848.01389ms","start":"2026-04-02T06:06:35.240703Z","end":"2026-04-02T06:06:36.088717Z","steps":["trace[1458001457] 'agreement among raft nodes before linearized reading' (duration: 847.731256ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T06:06:36.088763Z","caller":"traceutil/trace.go:171","msg":"trace[1424926452] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:789; }","duration":"824.279126ms","start":"2026-04-02T06:06:35.264466Z","end":"2026-04-02T06:06:36.088745Z","steps":["trace[1424926452] 'agreement among raft nodes before linearized reading' (duration: 824.108433ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:06:36.088768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"827.893935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-04-02T06:06:36.088781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:06:35.240673Z","time spent":"848.098311ms","remote":"127.0.0.1:37592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-02T06:06:36.088802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:06:35.264459Z","time spent":"824.332586ms","remote":"127.0.0.1:37592","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-04-02T06:06:36.088816Z","caller":"traceutil/trace.go:171","msg":"trace[663902759] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:789; }","duration":"827.988476ms","start":"2026-04-02T06:06:35.260813Z","end":"2026-04-02T06:06:36.088802Z","steps":["trace[663902759] 'agreement among raft nodes before linearized reading' (duration: 827.783214ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:06:36.088851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T06:06:35.260784Z","time spent":"828.059577ms","remote":"127.0.0.1:37592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-02T06:06:58.377501Z","caller":"traceutil/trace.go:171","msg":"trace[1249088246] transaction","detail":"{read_only:false; response_revision:994; number_of_response:1; }","duration":"142.991936ms","start":"2026-04-02T06:06:58.234479Z","end":"2026-04-02T06:06:58.377471Z","steps":["trace[1249088246] 'process raft request' (duration: 78.556271ms)","trace[1249088246] 'compare' (duration: 63.814777ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T06:06:58.377775Z","caller":"traceutil/trace.go:171","msg":"trace[927928243] transaction","detail":"{read_only:false; response_revision:995; number_of_response:1; }","duration":"143.236379ms","start":"2026-04-02T06:06:58.234528Z","end":"2026-04-02T06:06:58.377764Z","steps":["trace[927928243] 'process raft request' (duration: 142.780103ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T06:06:58.37807Z","caller":"traceutil/trace.go:171","msg":"trace[1749673569] transaction","detail":"{read_only:false; response_revision:997; number_of_response:1; }","duration":"142.164005ms","start":"2026-04-02T06:06:58.235898Z","end":"2026-04-02T06:06:58.378062Z","steps":["trace[1749673569] 'process raft request' (duration: 141.801321ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T06:06:58.378133Z","caller":"traceutil/trace.go:171","msg":"trace[111543935] transaction","detail":"{read_only:false; response_revision:996; number_of_response:1; }","duration":"143.489112ms","start":"2026-04-02T06:06:58.23464Z","end":"2026-04-02T06:06:58.378129Z","steps":["trace[111543935] 'process raft request' (duration: 142.994876ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T06:08:27.214253Z","caller":"traceutil/trace.go:171","msg":"trace[520016384] linearizableReadLoop","detail":"{readStateIndex:1379; appliedIndex:1378; }","duration":"105.817769ms","start":"2026-04-02T06:08:27.108404Z","end":"2026-04-02T06:08:27.214222Z","steps":["trace[520016384] 'read index received' (duration: 105.611743ms)","trace[520016384] 'applied index is now lower than readState.Index' (duration: 204.306µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T06:08:27.214293Z","caller":"traceutil/trace.go:171","msg":"trace[1020033011] transaction","detail":"{read_only:false; response_revision:1320; number_of_response:1; }","duration":"144.426183ms","start":"2026-04-02T06:08:27.069833Z","end":"2026-04-02T06:08:27.214259Z","steps":["trace[1020033011] 'process raft request' (duration: 144.240757ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T06:08:27.214471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.032186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-02T06:08:27.215304Z","caller":"traceutil/trace.go:171","msg":"trace[1196514598] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:1320; }","duration":"106.939753ms","start":"2026-04-02T06:08:27.108341Z","end":"2026-04-02T06:08:27.215281Z","steps":["trace[1196514598] 'agreement among raft nodes before linearized reading' (duration: 106.031996ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T06:08:29.344016Z","caller":"traceutil/trace.go:171","msg":"trace[963105686] transaction","detail":"{read_only:false; response_revision:1329; number_of_response:1; }","duration":"100.856814ms","start":"2026-04-02T06:08:29.243141Z","end":"2026-04-02T06:08:29.343997Z","steps":["trace[963105686] 'process raft request' (duration: 100.655028ms)"],"step_count":1}